builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1472798227.16 results: success (0) buildid: 20160901220445 builduid: fc3aa56c3e8f40418da4f3c9634e1f8b revision: 54394c32a1068afdbdbd78faa4c41d2591d467bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.155913) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.156304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.156607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.178335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.178588) ========= 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=0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 23:37:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.7M=0.001s 2016-09-01 23:37:07 (21.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101419 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.293623) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.293974) ========= 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=0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017859 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:07.343460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 23:37:07.343860) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 54394c32a1068afdbdbd78faa4c41d2591d467bc --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 54394c32a1068afdbdbd78faa4c41d2591d467bc --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=0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 23:37:07,937 truncating revision to first 12 chars 2016-09-01 23:37:07,937 Setting DEBUG logging. 2016-09-01 23:37:07,937 attempt 1/10 2016-09-01 23:37:07,937 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/54394c32a106?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 23:37:09,247 unpacking tar archive at: autoland-54394c32a106/testing/mozharness/ program finished with exit code 0 elapsedTime=1.673014 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 23:37:09.030196) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:09.030548) ========= script_repo_revision: 54394c32a1068afdbdbd78faa4c41d2591d467bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:09.030966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:09.031226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 23:37:09.039708) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-01 23:37:09.040072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:37:09 INFO - MultiFileLogger online at 20160901 23:37:09 in /builds/slave/test 23:37:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 23:37:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:37:09 INFO - {'append_to_log': False, 23:37:09 INFO - 'base_work_dir': '/builds/slave/test', 23:37:09 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 23:37:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:37:09 INFO - 'branch': 'Autoland-Non-PGO', 23:37:09 INFO - 'buildbot_json_path': 'buildprops.json', 23:37:09 INFO - 'config_files': ('talos/linux_config.py',), 23:37:09 INFO - 'default_actions': ('clobber', 23:37:09 INFO - 'read-buildbot-config', 23:37:09 INFO - 'download-and-extract', 23:37:09 INFO - 'populate-webroot', 23:37:09 INFO - 'create-virtualenv', 23:37:09 INFO - 'install', 23:37:09 INFO - 'run-tests'), 23:37:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:37:09 INFO - 'download_minidump_stackwalk': True, 23:37:09 INFO - 'download_symbols': 'ondemand', 23:37:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:37:09 INFO - 'tooltool.py': '/tools/tooltool.py', 23:37:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:37:09 INFO - '/tools/misc-python/virtualenv.py')}, 23:37:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:37:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:37:09 INFO - 'installer_path': 'installer.exe', 23:37:09 INFO - 'log_level': 'info', 23:37:09 INFO - 'log_name': 'talos', 23:37:09 INFO - 'log_to_console': True, 23:37:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:37:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:37:09 INFO - 'opt_config_files': (), 23:37:09 INFO - 'pip_index': False, 23:37:09 INFO - 'sps_profile': False, 23:37:09 INFO - 'sps_profile_interval': 0, 23:37:09 INFO - 'suite': 'dromaeojs', 23:37:09 INFO - 'system_bits': '32', 23:37:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:37:09 INFO - 'title': 'talos-linux64-ix-017', 23:37:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:37:09 INFO - 'use_talos_json': True, 23:37:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:37:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:37:09 INFO - 'work_dir': 'build'} 23:37:09 INFO - [mozharness: 2016-09-02 06:37:09.727624Z] Running clobber step. 23:37:09 INFO - Running pre-action listener: _resource_record_pre_action 23:37:09 INFO - Running main action method: clobber 23:37:09 INFO - rmtree: /builds/slave/test/build 23:37:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:37:10 INFO - Running post-action listener: _resource_record_post_action 23:37:10 INFO - [mozharness: 2016-09-02 06:37:10.713394Z] Finished clobber step (success) 23:37:10 INFO - [mozharness: 2016-09-02 06:37:10.713496Z] Running read-buildbot-config step. 23:37:10 INFO - Running pre-action listener: _resource_record_pre_action 23:37:10 INFO - Running main action method: read_buildbot_config 23:37:10 INFO - Using buildbot properties: 23:37:10 INFO - { 23:37:10 INFO - "project": "", 23:37:10 INFO - "product": "firefox", 23:37:10 INFO - "script_repo_revision": "production", 23:37:10 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 23:37:10 INFO - "repository": "", 23:37:10 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 23:37:10 INFO - "buildid": "20160901220445", 23:37:10 INFO - "pgo_build": "False", 23:37:10 INFO - "basedir": "/builds/slave/test", 23:37:10 INFO - "buildnumber": 404, 23:37:10 INFO - "slavename": "talos-linux64-ix-017", 23:37:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:37:10 INFO - "platform": "ubuntu64_hw", 23:37:10 INFO - "scheduler": "tests-autoland-linux64-talos", 23:37:10 INFO - "branch": "autoland", 23:37:10 INFO - "repo_path": "integration/autoland", 23:37:10 INFO - "revision": "54394c32a1068afdbdbd78faa4c41d2591d467bc", 23:37:10 INFO - "stage_platform": "linux64", 23:37:10 INFO - "builduid": "fc3aa56c3e8f40418da4f3c9634e1f8b", 23:37:10 INFO - "slavebuilddir": "test" 23:37:10 INFO - } 23:37:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 23:37:10 INFO - Running post-action listener: _resource_record_post_action 23:37:10 INFO - [mozharness: 2016-09-02 06:37:10.715976Z] Finished read-buildbot-config step (success) 23:37:10 INFO - [mozharness: 2016-09-02 06:37:10.716067Z] Running download-and-extract step. 23:37:10 INFO - Running pre-action listener: _resource_record_pre_action 23:37:10 INFO - Running main action method: download_and_extract 23:37:10 INFO - mkdir: /builds/slave/test/build/tests 23:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:10 INFO - trying https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:37:10 INFO - Downloading https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:37:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:37:11 INFO - Downloaded 1361 bytes. 23:37:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:37:11 INFO - Using the following test package requirements: 23:37:11 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:37:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:37:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:37:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'jsshell-linux-x86_64.zip'], 23:37:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:37:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:37:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:37:11 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:37:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:37:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:37:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:37:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:11 INFO - trying https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:37:11 INFO - Downloading https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:37:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:37:12 INFO - Downloaded 20335050 bytes. 23:37:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 23:37:16 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:16 INFO - trying https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:37:16 INFO - Downloading https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:37:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:37:17 INFO - Downloaded 20335050 bytes. 23:37:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 23:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:21 INFO - trying https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:37:21 INFO - Downloading https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:37:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:37:22 INFO - Downloaded 14413998 bytes. 23:37:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 23:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:22 INFO - trying https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:37:22 INFO - Downloading https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:37:24 INFO - Downloaded 55873199 bytes. 23:37:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:37:24 INFO - mkdir: /builds/slave/test/properties 23:37:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:37:24 INFO - Writing to file /builds/slave/test/properties/build_url 23:37:24 INFO - Contents: 23:37:24 INFO - build_url:https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:37:24 INFO - Running post-action listener: _resource_record_post_action 23:37:24 INFO - Running post-action listener: set_extra_try_arguments 23:37:24 INFO - [mozharness: 2016-09-02 06:37:24.671947Z] Finished download-and-extract step (success) 23:37:24 INFO - [mozharness: 2016-09-02 06:37:24.672176Z] Running populate-webroot step. 23:37:24 INFO - Running pre-action listener: _resource_record_pre_action 23:37:24 INFO - Running main action method: populate_webroot 23:37:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:37:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:37:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:37:24 INFO - '--tppagecycles', 23:37:24 INFO - '7'], 23:37:24 INFO - 'tests': ['tsvgm']}}, 23:37:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:37:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:37:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:37:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:37:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:37:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:37:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['damp', 'tps']}, 23:37:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'tests': ['damp', 'tps']}, 23:37:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['dromaeo_dom']}, 23:37:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:37:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['basic_compositor_video']}, 23:37:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:37:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['a11yr', 23:37:24 INFO - 'ts_paint', 23:37:24 INFO - 'tpaint', 23:37:24 INFO - 'sessionrestore', 23:37:24 INFO - 'sessionrestore_no_auto_restore', 23:37:24 INFO - 'tabpaint']}, 23:37:24 INFO - 'other-e10s': {'tests': ['a11yr', 23:37:24 INFO - 'ts_paint', 23:37:24 INFO - 'tpaint', 23:37:24 INFO - 'sessionrestore', 23:37:24 INFO - 'sessionrestore_no_auto_restore', 23:37:24 INFO - 'tabpaint']}, 23:37:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:37:24 INFO - 'ts_paint', 23:37:24 INFO - 'tpaint', 23:37:24 INFO - 'sessionrestore', 23:37:24 INFO - 'sessionrestore_no_auto_restore', 23:37:24 INFO - 'tabpaint']}, 23:37:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:37:24 INFO - 'ts_paint', 23:37:24 INFO - 'tpaint', 23:37:24 INFO - 'sessionrestore', 23:37:24 INFO - 'sessionrestore_no_auto_restore', 23:37:24 INFO - 'tabpaint']}, 23:37:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['a11yr', 23:37:24 INFO - 'ts_paint', 23:37:24 INFO - 'tpaint', 23:37:24 INFO - 'sessionrestore', 23:37:24 INFO - 'sessionrestore_no_auto_restore', 23:37:24 INFO - 'tabpaint']}, 23:37:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['a11yr', 23:37:24 INFO - 'ts_paint', 23:37:24 INFO - 'tpaint', 23:37:24 INFO - 'sessionrestore', 23:37:24 INFO - 'sessionrestore_no_auto_restore', 23:37:24 INFO - 'tabpaint']}, 23:37:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['tsvgx', 23:37:24 INFO - 'tsvgr_opacity', 23:37:24 INFO - 'tart', 23:37:24 INFO - 'tscrollx', 23:37:24 INFO - 'cart']}, 23:37:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:37:24 INFO - 'tsvgr_opacity', 23:37:24 INFO - 'tart', 23:37:24 INFO - 'tscrollx', 23:37:24 INFO - 'cart']}, 23:37:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'talos_options': ['--disable-e10s'], 23:37:24 INFO - 'tests': ['tp5o']}, 23:37:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'tests': ['tp5o']}, 23:37:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'talos_options': ['--disable-e10s', 23:37:24 INFO - '--xperf_path', 23:37:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:37:24 INFO - 'tests': ['tp5n']}, 23:37:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:37:24 INFO - 'talos_options': ['--xperf_path', 23:37:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:37:24 INFO - 'tests': ['tp5n']}}, 23:37:24 INFO - 'talos.zip': {'path': '', 23:37:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:37:24 INFO - Running post-action listener: _resource_record_post_action 23:37:24 INFO - [mozharness: 2016-09-02 06:37:24.688711Z] Finished populate-webroot step (success) 23:37:24 INFO - [mozharness: 2016-09-02 06:37:24.688801Z] Running create-virtualenv step. 23:37:24 INFO - Running pre-action listener: _resource_record_pre_action 23:37:24 INFO - Running main action method: create_virtualenv 23:37:24 INFO - Creating virtualenv /builds/slave/test/build/venv 23:37:24 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 23:37:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:37:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:37:24 INFO - Using real prefix '/usr' 23:37:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:37:25 INFO - Installing distribute.............................................................................................................................................................................................done. 23:37:27 INFO - Installing pip.................done. 23:37:27 INFO - Return code: 0 23:37:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:27 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:27 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 23:37:27 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 23:37:27 INFO - Using env: {'DISPLAY': ':0', 23:37:27 INFO - 'HOME': '/home/cltbld', 23:37:27 INFO - 'LANG': 'en_US.UTF-8', 23:37:27 INFO - 'LANGUAGE': 'en_US:en', 23:37:27 INFO - 'LOGNAME': 'cltbld', 23:37:27 INFO - 'MAIL': '/var/mail/cltbld', 23:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:27 INFO - 'MOZ_NO_REMOTE': '1', 23:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:27 INFO - 'NO_EM_RESTART': '1', 23:37:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:27 INFO - 'PWD': '/builds/slave/test', 23:37:27 INFO - 'SHELL': '/bin/bash', 23:37:27 INFO - 'SHLVL': '1', 23:37:27 INFO - 'TERM': 'linux', 23:37:27 INFO - 'TMOUT': '86400', 23:37:27 INFO - 'USER': 'cltbld', 23:37:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:27 INFO - '_': '/tools/buildbot/bin/python'} 23:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:27 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 23:37:27 INFO - Cleaning up... 23:37:27 INFO - Return code: 0 23:37:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:27 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:27 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 23:37:27 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 23:37:27 INFO - Using env: {'DISPLAY': ':0', 23:37:27 INFO - 'HOME': '/home/cltbld', 23:37:27 INFO - 'LANG': 'en_US.UTF-8', 23:37:27 INFO - 'LANGUAGE': 'en_US:en', 23:37:27 INFO - 'LOGNAME': 'cltbld', 23:37:27 INFO - 'MAIL': '/var/mail/cltbld', 23:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:27 INFO - 'MOZ_NO_REMOTE': '1', 23:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:27 INFO - 'NO_EM_RESTART': '1', 23:37:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:27 INFO - 'PWD': '/builds/slave/test', 23:37:27 INFO - 'SHELL': '/bin/bash', 23:37:27 INFO - 'SHLVL': '1', 23:37:27 INFO - 'TERM': 'linux', 23:37:27 INFO - 'TMOUT': '86400', 23:37:27 INFO - 'USER': 'cltbld', 23:37:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:27 INFO - '_': '/tools/buildbot/bin/python'} 23:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:27 INFO - Downloading/unpacking psutil>=3.1.1 23:37:27 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 23:37:27 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 23:37:27 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 23:37:27 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 23:37:27 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 23:37:27 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 23:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:37:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:37:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:37:29 INFO - Installing collected packages: psutil 23:37:29 INFO - Running setup.py install for psutil 23:37:29 INFO - building 'psutil._psutil_linux' extension 23:37:29 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 23:37:29 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 23:37:29 INFO - building 'psutil._psutil_posix' extension 23:37:29 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 23:37:30 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 23:37:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:37:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:37:30 INFO - Successfully installed psutil 23:37:30 INFO - Cleaning up... 23:37:30 INFO - Return code: 0 23:37:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:30 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:30 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 23:37:30 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 23:37:30 INFO - Using env: {'DISPLAY': ':0', 23:37:30 INFO - 'HOME': '/home/cltbld', 23:37:30 INFO - 'LANG': 'en_US.UTF-8', 23:37:30 INFO - 'LANGUAGE': 'en_US:en', 23:37:30 INFO - 'LOGNAME': 'cltbld', 23:37:30 INFO - 'MAIL': '/var/mail/cltbld', 23:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:30 INFO - 'MOZ_NO_REMOTE': '1', 23:37:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:30 INFO - 'NO_EM_RESTART': '1', 23:37:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:30 INFO - 'PWD': '/builds/slave/test', 23:37:30 INFO - 'SHELL': '/bin/bash', 23:37:30 INFO - 'SHLVL': '1', 23:37:30 INFO - 'TERM': 'linux', 23:37:30 INFO - 'TMOUT': '86400', 23:37:30 INFO - 'USER': 'cltbld', 23:37:30 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:30 INFO - '_': '/tools/buildbot/bin/python'} 23:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:37:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:37:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:37:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:37:32 INFO - Installing collected packages: mozsystemmonitor 23:37:32 INFO - Running setup.py install for mozsystemmonitor 23:37:32 INFO - Successfully installed mozsystemmonitor 23:37:32 INFO - Cleaning up... 23:37:32 INFO - Return code: 0 23:37:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37: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', '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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:37: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 jsonschema==2.5.1 23:37:32 INFO - Using env: {'DISPLAY': ':0', 23:37:32 INFO - 'HOME': '/home/cltbld', 23:37:32 INFO - 'LANG': 'en_US.UTF-8', 23:37:32 INFO - 'LANGUAGE': 'en_US:en', 23:37:32 INFO - 'LOGNAME': 'cltbld', 23:37:32 INFO - 'MAIL': '/var/mail/cltbld', 23:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:32 INFO - 'MOZ_NO_REMOTE': '1', 23:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:32 INFO - 'NO_EM_RESTART': '1', 23:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:32 INFO - 'PWD': '/builds/slave/test', 23:37:32 INFO - 'SHELL': '/bin/bash', 23:37:32 INFO - 'SHLVL': '1', 23:37:32 INFO - 'TERM': 'linux', 23:37:32 INFO - 'TMOUT': '86400', 23:37:32 INFO - 'USER': 'cltbld', 23:37:32 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:32 INFO - '_': '/tools/buildbot/bin/python'} 23:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:32 INFO - Downloading/unpacking jsonschema==2.5.1 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:37:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37:34 INFO - Downloading functools32-3.2.3-2.tar.gz 23:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:37:34 INFO - warning: no files found matching '*.txt' 23:37:34 INFO - no previously-included directories found matching 'build' 23:37:34 INFO - no previously-included directories found matching 'dist' 23:37:34 INFO - no previously-included directories found matching '.git*' 23:37:34 INFO - Installing collected packages: jsonschema, functools32 23:37:34 INFO - Running setup.py install for functools32 23:37:35 INFO - warning: no files found matching '*.txt' 23:37:35 INFO - no previously-included directories found matching 'build' 23:37:35 INFO - no previously-included directories found matching 'dist' 23:37:35 INFO - no previously-included directories found matching '.git*' 23:37:35 INFO - Successfully installed jsonschema functools32 23:37:35 INFO - Cleaning up... 23:37:35 INFO - Return code: 0 23:37:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:37:35 INFO - Using env: {'DISPLAY': ':0', 23:37:35 INFO - 'HOME': '/home/cltbld', 23:37:35 INFO - 'LANG': 'en_US.UTF-8', 23:37:35 INFO - 'LANGUAGE': 'en_US:en', 23:37:35 INFO - 'LOGNAME': 'cltbld', 23:37:35 INFO - 'MAIL': '/var/mail/cltbld', 23:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:35 INFO - 'MOZ_NO_REMOTE': '1', 23:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:35 INFO - 'NO_EM_RESTART': '1', 23:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:35 INFO - 'PWD': '/builds/slave/test', 23:37:35 INFO - 'SHELL': '/bin/bash', 23:37:35 INFO - 'SHLVL': '1', 23:37:35 INFO - 'TERM': 'linux', 23:37:35 INFO - 'TMOUT': '86400', 23:37:35 INFO - 'USER': 'cltbld', 23:37:35 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:35 INFO - '_': '/tools/buildbot/bin/python'} 23:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:37:35 INFO - Cleaning up... 23:37:35 INFO - Return code: 0 23:37:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:35 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:35 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 23:37:35 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 23:37:35 INFO - Using env: {'DISPLAY': ':0', 23:37:35 INFO - 'HOME': '/home/cltbld', 23:37:35 INFO - 'LANG': 'en_US.UTF-8', 23:37:35 INFO - 'LANGUAGE': 'en_US:en', 23:37:35 INFO - 'LOGNAME': 'cltbld', 23:37:35 INFO - 'MAIL': '/var/mail/cltbld', 23:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:35 INFO - 'MOZ_NO_REMOTE': '1', 23:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:35 INFO - 'NO_EM_RESTART': '1', 23:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:35 INFO - 'PWD': '/builds/slave/test', 23:37:35 INFO - 'SHELL': '/bin/bash', 23:37:35 INFO - 'SHLVL': '1', 23:37:35 INFO - 'TERM': 'linux', 23:37:35 INFO - 'TMOUT': '86400', 23:37:35 INFO - 'USER': 'cltbld', 23:37:35 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:35 INFO - '_': '/tools/buildbot/bin/python'} 23:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:35 INFO - Downloading/unpacking blobuploader==1.2.4 23:37:35 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 23:37:35 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 23:37:35 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 23:37:35 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 23:37:35 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 23:37:35 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 23:37:37 INFO - Downloading blobuploader-1.2.4.tar.gz 23:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:37:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:37:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37: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 23:37:38 INFO - Downloading docopt-0.6.1.tar.gz 23:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:37:38 INFO - Installing collected packages: blobuploader, requests, docopt 23:37:38 INFO - Running setup.py install for blobuploader 23:37:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:37:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:37:38 INFO - Running setup.py install for requests 23:37:38 INFO - Running setup.py install for docopt 23:37:38 INFO - Successfully installed blobuploader requests docopt 23:37:38 INFO - Cleaning up... 23:37:38 INFO - Return code: 0 23:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:38 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:38 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 23:37:38 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 23:37:38 INFO - Using env: {'DISPLAY': ':0', 23:37:38 INFO - 'HOME': '/home/cltbld', 23:37:38 INFO - 'LANG': 'en_US.UTF-8', 23:37:38 INFO - 'LANGUAGE': 'en_US:en', 23:37:38 INFO - 'LOGNAME': 'cltbld', 23:37:38 INFO - 'MAIL': '/var/mail/cltbld', 23:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:38 INFO - 'MOZ_NO_REMOTE': '1', 23:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:38 INFO - 'NO_EM_RESTART': '1', 23:37:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:38 INFO - 'PWD': '/builds/slave/test', 23:37:38 INFO - 'SHELL': '/bin/bash', 23:37:38 INFO - 'SHLVL': '1', 23:37:38 INFO - 'TERM': 'linux', 23:37:38 INFO - 'TMOUT': '86400', 23:37:38 INFO - 'USER': 'cltbld', 23:37:38 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:38 INFO - '_': '/tools/buildbot/bin/python'} 23:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:37:38 INFO - Running setup.py (path:/tmp/pip-3kDH99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:37:39 INFO - Running setup.py (path:/tmp/pip-eALVB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:37:39 INFO - Running setup.py (path:/tmp/pip-XkZ87B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:37:39 INFO - Running setup.py (path:/tmp/pip-hl5pFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:37:39 INFO - Running setup.py (path:/tmp/pip-RcDgWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:37:39 INFO - Running setup.py (path:/tmp/pip-vblRwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:37:39 INFO - Running setup.py (path:/tmp/pip-PN3nEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:37:39 INFO - Running setup.py (path:/tmp/pip-53uVrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:37:39 INFO - Running setup.py (path:/tmp/pip-W59JrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:37:39 INFO - Running setup.py (path:/tmp/pip-_rAW0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:37:39 INFO - Running setup.py (path:/tmp/pip-xVrBmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:37:39 INFO - Running setup.py (path:/tmp/pip-T2DnQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:37:39 INFO - Running setup.py (path:/tmp/pip-gW8JXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:37:39 INFO - Running setup.py (path:/tmp/pip-r602Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:37:39 INFO - Running setup.py (path:/tmp/pip-vp2dmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:37:40 INFO - Running setup.py (path:/tmp/pip-4WDvVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:37:40 INFO - Running setup.py (path:/tmp/pip-ptJHw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:37:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:37:40 INFO - Running setup.py install for manifestparser 23:37:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:37:40 INFO - Running setup.py install for mozcrash 23:37:40 INFO - Running setup.py install for mozdebug 23:37:40 INFO - Running setup.py install for mozdevice 23:37:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:37:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:37:40 INFO - Running setup.py install for mozfile 23:37:40 INFO - Running setup.py install for mozhttpd 23:37:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:37:40 INFO - Running setup.py install for mozinfo 23:37:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:37:40 INFO - Running setup.py install for mozInstall 23:37:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Running setup.py install for mozleak 23:37:41 INFO - Running setup.py install for mozlog 23:37:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Running setup.py install for moznetwork 23:37:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Running setup.py install for mozprocess 23:37:41 INFO - Running setup.py install for mozprofile 23:37:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Running setup.py install for mozrunner 23:37:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Running setup.py install for mozscreenshot 23:37:41 INFO - Running setup.py install for moztest 23:37:41 INFO - Running setup.py install for mozversion 23:37:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:37:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:37:41 INFO - Cleaning up... 23:37:42 INFO - Return code: 0 23:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:42 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:42 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 23:37:42 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 23:37:42 INFO - Using env: {'DISPLAY': ':0', 23:37:42 INFO - 'HOME': '/home/cltbld', 23:37:42 INFO - 'LANG': 'en_US.UTF-8', 23:37:42 INFO - 'LANGUAGE': 'en_US:en', 23:37:42 INFO - 'LOGNAME': 'cltbld', 23:37:42 INFO - 'MAIL': '/var/mail/cltbld', 23:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:42 INFO - 'MOZ_NO_REMOTE': '1', 23:37:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:42 INFO - 'NO_EM_RESTART': '1', 23:37:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:42 INFO - 'PWD': '/builds/slave/test', 23:37:42 INFO - 'SHELL': '/bin/bash', 23:37:42 INFO - 'SHLVL': '1', 23:37:42 INFO - 'TERM': 'linux', 23:37:42 INFO - 'TMOUT': '86400', 23:37:42 INFO - 'USER': 'cltbld', 23:37:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:42 INFO - '_': '/tools/buildbot/bin/python'} 23:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:37:42 INFO - Running setup.py (path:/tmp/pip-VGtAj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:37:42 INFO - Running setup.py (path:/tmp/pip-SkV_WY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:37:42 INFO - Running setup.py (path:/tmp/pip-82wnMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:37:42 INFO - Running setup.py (path:/tmp/pip-YpySZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:37:42 INFO - Running setup.py (path:/tmp/pip-cdNhiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:37:42 INFO - Running setup.py (path:/tmp/pip-8DPz0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:37:42 INFO - Running setup.py (path:/tmp/pip-xaEm8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:37:42 INFO - Running setup.py (path:/tmp/pip-QKsi8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:37:42 INFO - Running setup.py (path:/tmp/pip-_FgFop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:37:42 INFO - Running setup.py (path:/tmp/pip-GEUY58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:37:42 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)) 23:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:37:42 INFO - Running setup.py (path:/tmp/pip-A31kCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:37:43 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)) 23:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:37:43 INFO - Running setup.py (path:/tmp/pip-A8ImF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:37:43 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)) 23:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:37:43 INFO - Running setup.py (path:/tmp/pip-lOXK2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:37:43 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)) 23:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:37:43 INFO - Running setup.py (path:/tmp/pip-fbPgPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:37:43 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)) 23:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:37:43 INFO - Running setup.py (path:/tmp/pip-8q8_mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:37:43 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)) 23:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:37:43 INFO - Running setup.py (path:/tmp/pip-oqv2jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:37:43 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)) 23:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:37:43 INFO - Running setup.py (path:/tmp/pip-KaWTiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:37:43 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)) 23:37:43 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)) 23:37:43 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)) 23:37:43 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)) 23:37:43 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)) 23:37:43 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)) 23:37:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:37:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:37:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:37:45 INFO - Downloading blessings-1.6.tar.gz 23:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:37:45 INFO - Installing collected packages: blessings 23:37:45 INFO - Running setup.py install for blessings 23:37:45 INFO - Successfully installed blessings 23:37:45 INFO - Cleaning up... 23:37:45 INFO - Return code: 0 23:37:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:37:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:37:45 INFO - Reading from file tmpfile_stdout 23:37:45 INFO - Current package versions: 23:37:45 INFO - argparse == 1.2.1 23:37:45 INFO - blessings == 1.6 23:37:45 INFO - blobuploader == 1.2.4 23:37:45 INFO - docopt == 0.6.1 23:37:45 INFO - functools32 == 3.2.3-2 23:37:45 INFO - jsonschema == 2.5.1 23:37:45 INFO - manifestparser == 1.1 23:37:45 INFO - mozInstall == 1.12 23:37:45 INFO - mozcrash == 0.17 23:37:45 INFO - mozdebug == 0.1 23:37:45 INFO - mozdevice == 0.48 23:37:45 INFO - mozfile == 1.2 23:37:45 INFO - mozhttpd == 0.7 23:37:45 INFO - mozinfo == 0.9 23:37:45 INFO - mozleak == 0.1 23:37:45 INFO - mozlog == 3.3 23:37:45 INFO - moznetwork == 0.27 23:37:45 INFO - mozprocess == 0.23 23:37:45 INFO - mozprofile == 0.28 23:37:45 INFO - mozrunner == 6.12 23:37:45 INFO - mozscreenshot == 0.1 23:37:45 INFO - mozsystemmonitor == 0.3 23:37:45 INFO - moztest == 0.7 23:37:45 INFO - mozversion == 1.4 23:37:45 INFO - psutil == 3.1.1 23:37:45 INFO - requests == 1.2.3 23:37:45 INFO - wsgiref == 0.1.2 23:37:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:45 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:45 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 23:37:45 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 23:37:45 INFO - Using env: {'DISPLAY': ':0', 23:37:45 INFO - 'HOME': '/home/cltbld', 23:37:45 INFO - 'LANG': 'en_US.UTF-8', 23:37:45 INFO - 'LANGUAGE': 'en_US:en', 23:37:45 INFO - 'LOGNAME': 'cltbld', 23:37:45 INFO - 'MAIL': '/var/mail/cltbld', 23:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:45 INFO - 'MOZ_NO_REMOTE': '1', 23:37:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:45 INFO - 'NO_EM_RESTART': '1', 23:37:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:45 INFO - 'PWD': '/builds/slave/test', 23:37:45 INFO - 'SHELL': '/bin/bash', 23:37:45 INFO - 'SHLVL': '1', 23:37:45 INFO - 'TERM': 'linux', 23:37:45 INFO - 'TMOUT': '86400', 23:37:45 INFO - 'USER': 'cltbld', 23:37:45 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:45 INFO - '_': '/tools/buildbot/bin/python'} 23:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 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)) 23:37:46 INFO - Cleaning up... 23:37:46 INFO - Return code: 0 23:37:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:37:46 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 0x7fda29bcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, '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': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', '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 23:37:46 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 23:37:46 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 23:37:46 INFO - Using env: {'DISPLAY': ':0', 23:37:46 INFO - 'HOME': '/home/cltbld', 23:37:46 INFO - 'LANG': 'en_US.UTF-8', 23:37:46 INFO - 'LANGUAGE': 'en_US:en', 23:37:46 INFO - 'LOGNAME': 'cltbld', 23:37:46 INFO - 'MAIL': '/var/mail/cltbld', 23:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:46 INFO - 'MOZ_NO_REMOTE': '1', 23:37:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:46 INFO - 'NO_EM_RESTART': '1', 23:37:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:46 INFO - 'PWD': '/builds/slave/test', 23:37:46 INFO - 'SHELL': '/bin/bash', 23:37:46 INFO - 'SHLVL': '1', 23:37:46 INFO - 'TERM': 'linux', 23:37:46 INFO - 'TMOUT': '86400', 23:37:46 INFO - 'USER': 'cltbld', 23:37:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:46 INFO - '_': '/tools/buildbot/bin/python'} 23:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:37:46 INFO - Cleaning up... 23:37:46 INFO - Return code: 0 23:37:46 INFO - Running post-action listener: _resource_record_post_action 23:37:46 INFO - Running post-action listener: _start_resource_monitoring 23:37:46 INFO - Starting resource monitoring. 23:37:46 INFO - [mozharness: 2016-09-02 06:37:46.293920Z] Finished create-virtualenv step (success) 23:37:46 INFO - [mozharness: 2016-09-02 06:37:46.294385Z] Running install step. 23:37:46 INFO - Running pre-action listener: _resource_record_pre_action 23:37:46 INFO - Running main action method: install 23:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:37:46 INFO - Reading from file tmpfile_stdout 23:37:46 INFO - Detecting whether we're running mozinstall >=1.0... 23:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:37:46 INFO - Reading from file tmpfile_stdout 23:37:46 INFO - Output received: 23:37:46 INFO - Usage: mozinstall [options] installer 23:37:46 INFO - Options: 23:37:46 INFO - -h, --help show this help message and exit 23:37:46 INFO - -d DEST, --destination=DEST 23:37:46 INFO - Directory to install application into. [default: 23:37:46 INFO - "/builds/slave/test"] 23:37:46 INFO - --app=APP Application being installed. [default: firefox] 23:37:46 INFO - mkdir: /builds/slave/test/build/application 23:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:38:02 INFO - Reading from file tmpfile_stdout 23:38:02 INFO - Output received: 23:38:02 INFO - /builds/slave/test/build/application/firefox/firefox 23:38:02 INFO - Running post-action listener: _resource_record_post_action 23:38:02 INFO - [mozharness: 2016-09-02 06:38:02.719253Z] Finished install step (success) 23:38:02 INFO - [mozharness: 2016-09-02 06:38:02.719541Z] Running run-tests step. 23:38:02 INFO - Running pre-action listener: _resource_record_pre_action 23:38:02 INFO - Running main action method: run_tests 23:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:38:02 INFO - Python 2.7.3 23:38:02 INFO - Return code: 0 23:38:02 INFO - grabbing minidump binary from tooltool 23:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:38:02 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 0x1e27090>, '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 0x1e2a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ae40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:38:02 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 23:38:02 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 23:38:02 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 23:38:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:38:02 INFO - Return code: 0 23:38:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:38:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:38:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:38:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:38:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:38:02 INFO - Using env: {'DISPLAY': ':0', 23:38:02 INFO - 'HOME': '/home/cltbld', 23:38:02 INFO - 'LANG': 'en_US.UTF-8', 23:38:02 INFO - 'LANGUAGE': 'en_US:en', 23:38:02 INFO - 'LOGNAME': 'cltbld', 23:38:02 INFO - 'MAIL': '/var/mail/cltbld', 23:38:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:38:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:02 INFO - 'MOZ_NO_REMOTE': '1', 23:38:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:38:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:02 INFO - 'NO_EM_RESTART': '1', 23:38:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:02 INFO - 'PWD': '/builds/slave/test', 23:38:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:38:02 INFO - 'SHELL': '/bin/bash', 23:38:02 INFO - 'SHLVL': '1', 23:38:02 INFO - 'TERM': 'linux', 23:38:02 INFO - 'TMOUT': '86400', 23:38:02 INFO - 'USER': 'cltbld', 23:38:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476', 23:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:02 INFO - '_': '/tools/buildbot/bin/python'} 23:38:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:38:02 INFO - mozversion application_buildid: 20160901220445 23:38:02 INFO - mozversion application_changeset: 54394c32a1068afdbdbd78faa4c41d2591d467bc 23:38:02 INFO - mozversion application_display_name: Nightly 23:38:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:38:02 INFO - mozversion application_name: Firefox 23:38:02 INFO - mozversion application_remotingname: firefox 23:38:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:38:02 INFO - mozversion application_vendor: Mozilla 23:38:02 INFO - mozversion application_version: 51.0a1 23:38:02 INFO - mozversion platform_buildid: 20160901220445 23:38:02 INFO - mozversion platform_changeset: 54394c32a1068afdbdbd78faa4c41d2591d467bc 23:38:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:38:02 INFO - mozversion platform_version: 51.0a1 23:38:02 INFO - using testdate: 1472798282 23:38:02 INFO - actual date: 1472798282 23:38:02 INFO - starting webserver on 'localhost:48074' 23:38:02 INFO - SUITE-START | Running 2 tests 23:38:02 INFO - TEST-START | dromaeo_css 23:38:02 INFO - operating with platform_type : linux_ 23:38:02 INFO - Initialising browser for dromaeo_css test... 23:38:03 INFO - TEST-INFO | started process 24978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7EIX5o/profile http://localhost:48074/getInfo.html) 23:38:07 INFO - PROCESS | 24978 | __metrics Screen width/height:1600/1200 23:38:07 INFO - PROCESS | 24978 | colorDepth:24 23:38:07 INFO - PROCESS | 24978 | Browser inner width/height: 1024/697 23:38:07 INFO - PROCESS | 24978 | __metrics 23:38:07 INFO - PROCESS | 24978 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:38:07 INFO - PROCESS | 24978 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:38:08 INFO - PROCESS | 24978 | console.error: PushService: 23:38:08 INFO - PROCESS | 24978 | stateChangeProcessEnqueue: Error transitioning state 23:38:08 INFO - PROCESS | 24978 | UnknownError 23:38:08 INFO - TEST-INFO | 24978: exit 0 23:38:08 INFO - Browser initialized. 23:38:08 INFO - Running cycle 1/1 for dromaeo_css test... 23:38:08 INFO - TEST-INFO | started process 25060 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7EIX5o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:38:21 INFO - PROCESS | 25060 | RSS: Main: 180772864 23:38:21 INFO - PROCESS | 25060 | 23:39:12 INFO - PROCESS | 25060 | 1472798352344 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] 23:41:25 INFO - PROCESS | 25060 | Cycle 1(1): loaded http://localhost:48074/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48074/tests/dromaeo/cssquery-ext.html) 23:41:26 INFO - PROCESS | 25060 | RSS: Main: 297299968 23:41:26 INFO - PROCESS | 25060 | 23:44:29 INFO - PROCESS | 25060 | Cycle 1(1): loaded http://localhost:48074/tests/dromaeo/cssquery-ext.html (next: http://localhost:48074/tests/dromaeo/cssquery-jquery.html) 23:44:30 INFO - PROCESS | 25060 | RSS: Main: 273874944 23:44:30 INFO - PROCESS | 25060 | 23:47:32 INFO - PROCESS | 25060 | Cycle 1(1): loaded http://localhost:48074/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48074/tests/dromaeo/cssquery-mootools.html) 23:47:32 INFO - PROCESS | 25060 | RSS: Main: 242241536 23:47:32 INFO - PROCESS | 25060 | 23:50:35 INFO - PROCESS | 25060 | Cycle 1(1): loaded http://localhost:48074/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48074/tests/dromaeo/cssquery-prototype.html) 23:50:35 INFO - PROCESS | 25060 | RSS: Main: 242429952 23:50:35 INFO - PROCESS | 25060 | 23:53:38 INFO - PROCESS | 25060 | Cycle 1(1): loaded http://localhost:48074/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48074/tests/dromaeo/cssquery-yui.html) 23:53:39 INFO - PROCESS | 25060 | RSS: Main: 220180480 23:53:39 INFO - PROCESS | 25060 | 23:56:42 INFO - PROCESS | 25060 | Cycle 1(1): loaded http://localhost:48074/tests/dromaeo/cssquery-yui.html (next: http://localhost:48074/tests/dromaeo/cssquery-dojo.html) 23:56:43 INFO - PROCESS | 25060 | RSS: Main: 299839488 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | __start_tp_report 23:56:43 INFO - PROCESS | 25060 | _x_x_mozilla_page_load 23:56:43 INFO - PROCESS | 25060 | _x_x_mozilla_page_load_details 23:56:43 INFO - PROCESS | 25060 | |i|pagename|runs| 23:56:43 INFO - PROCESS | 25060 | |0;dojo.html;2573.806964477664;2592.300078978677;2611.099170786078;2617.5550156473396;2636.472705458909;4545.227311357069;4573.565511276432;4623.953760462395;4649.186392381333;4678.368420263268;4606.33208184813;4608.861734147972;4620.976895115528;4675.064987002596;4702.553257440538;4317.913641727164;4336.609705126546;4400.251957167273;4426.601605855472;4440.42274504314;4368.672349573779;4388.100439409929;4389.253826849427;4389.648828093761;4410.4266445362055;4308.2029824482415;4512.12013657337;4517.41273634427;4522.050369422409;4522.23122069249;4203.47456567929;4327.394164816928;4336.154449882274;4389.626881715058;4389.978050109761;4206.558311377292;4262.424572682679;4296;4342.565743425632;4375.6937014409;4631.036136872404;4645.535446455361;4658.231391820379;4703.647226457995;4803.663743537919;5007.048660754482;5037.596992240612;5050.267711181858;5059.064073146443;5104.591632669378;4850.369451971256;4901.1177987962155;5044.495550444926;5070.518300761459;5106.387233531963;4732.763361831895;4733.810647574059;4779.235322348408;4791.257355109963;4792.065547218259;5153.896922061538;5165.096108181053;5187.896242075138;5197.272381866542;5253.711045892513;4726.550977657117;4929.876753081202;4966.428860681068;4972.825951091767;4976.6765160265195;5054.696718196847;5056.317397151338;5060.898782024339;5096.796128154906;5110.821121260738;4996.475370086161;5011.198208286657;5015.172496538104;5017.197248440232;5070.467600901926;4776.8328108516735;4871.293662419001;4897.142999975062;4996.775145118406;5001.349824522788;4146.3365861462025;4165.500139983222;4167.291560434734;4200.201961627281;4203.936940945889;3654.744167908274;3666.2850744104644;3670.137517683342;3699.315626609086;3709.5177626908867;4257.723247988871;4337.0892112656065;4340.891477713082;4346.413234213404;4385.758783266951;5034.194528875362;5043.5965122790085;5055.823046193365;5100.132977393852;5122.308488354024;4385.583369579885;4387.780610969438;4391.494978077498;4393.802278897458;4441.244988351988;4425.513193548707;4476.529964353761;4523.36672865799;4526.592606665416;4570.565796249351;4751.382320298339;4771.618270538279;4793.472718000948;4796.3285140079715;4818.975905120452;4993.251012348177;5014.84955451337;5015.523525265094;5016.322796422436;5070.3155074064525;4732.858014259577;4746.288056791509;4750.738709371018;4751.239801631722;4763.833265835679;4781.880452988703;4835.371401717754;4847.418309802846;4890.217565189552;4949.034938187019;4951.851444456672;4963.205887058053;4964.379452568429;4964.900701986012;5027.798888044437;4813.109574728686;4818.494058123846;4821;4830.396200474941;4880.633952453509;4717.622590192707;4832.975835120802;4875.24433712775;4884.462709101925;4932.334134891744;4735.810567577396;4768.237082066853;4771.355866958055;4771.880702982453;4821.2045012572535;4774.665773395829;4818.397700287464;4895.657303988829;4900.240462728283;4906.313116163811;4814.542618451242;4861.927071094007;4869.6347773917805;4870.95129048705;4922.335484709662 23:56:43 INFO - PROCESS | 25060 | |1;ext.html;576.1300436341129;576.4091805898953;579.6580017789506;585.5110982329753;597.2564157623759;23624.64563031554;36895.26209475812;38124;38207.04482387938;42170.7891460543;39746.403803942965;39951.80024099875;39978.80010599943;40019.79990100053;40049.199016019666;7671.61641917906;7799.103103143126;7800.609969501531;7801.960990195041;7805.9609701951695;7585;7652.4260680448915;7665.731699390522;7667.693292268303;7678.347340476066;7700.11448683403;7739.496932699357;7747.573883436408;7747.806304842367;7778.338841198477;7694.653740581659;7722.691092356299;7726.7681969541;7728.381729461629;7745.806354841146;7591.506552074153;7697.153313135551;7714.537127772295;7722;7730.458867879251;37421.438678419574;37545.812270938746;37646.623533764585;37764.62235377639;37934;2613.2421597736634;2709.580015097663;2759.351552385188;2765.502209602271;3073.9702199763105;28099.57850632242;28116;28119.57820632692;28476.145715628565;29002.41995160106;3071.969280307213;3096.4426403247408;3132.4518209313505;3140.1992491914643;3257.9348413031844;247857;248358;248430;248607;248801;51107.63369846187;51462.74268628708;51483.48516514862;51509;51564.22653660198;32884.83557582197;33833.492497612555;33837.96972181628;35206.471902921476;54344.728276358364;14470.348834302482;14612.926935365254;14669.33987970544;14688.706225875423;14702.63243418923;11214.215004949738;11459.77080458386;11464.484098215433;11464.541418343173;11475.71310717222;17725.29098836058;17737.024463654623;17777.20002599886;17826.643467130583;17849.196786144654;6803.931960680429;6816.795496135124;6864.313568643196;7065.858682826418;7218.855622887618;758.93928485721;874.226309715906;934.6915517879083;939.5818860606964;947.8672985781996;26326.473470530484;27507.58738618922;27566.862165689443;27741.306467338076;27800.304992375353;912.717057712098;922.0963455813206;922.3036607361399;972.1348000279756;1002.7042022603349;13795.724085518234;14588.41646334156;14840.925795370955;14901.329440175217;15133.772993405108;381301;385793;386325;386994;387286;49252.26121608179;49575;49701.50298497041;49821.501784982414;49857.75071124694;1139.971500712489;1153.2907262033789;1178.4814681540267;1186.1756079524648;1194.3192380343294;25935.740642593333;26896.865515672296;27187.59218611762;27187.86406067957;27768.722312777423;41378.793106034274;41585;41605.75182744522;41637;41725.95685107897;324252;325765;326217;327378;328710;24662.87668561646;24869.751302486744;25606.61590076113;25675;25686.743132568434;40951.59048409597;41128;41930;42220.788896055325;42237.57762422336;34830.82584587061;34994.3900067324;36029.27941441209;36198.27603447969;37591.2481750358;35263.82368088143;35371.46942795911;35677;35707.64292357147;35829;34852.30295394129;35293;35763.46354804628;36296.81851590725;36327.63672363242;30751.692483074883;32660.183495412806;32840.67159328376;32862.507062393604;33507.49738753872;24531.509369812862;24726.898213652043;24855.751442485343;24962;25525.61711574291 23:56:43 INFO - PROCESS | 25060 | |2;jquery.html;338.2220891948518;365.2348330248131;369.6820734168615;372.2201986837574;384.95380554333485;12420.130590858633;14936.701265974674;17203.82796172041;17308.394206202785;17447.651046979052;15564.221788910538;17031.40390086347;17141.314347426087;17223.483295501155;17238.39665611704;4909.680870743408;5181.222816577506;5181.481851814826;5184.637075404723;5185.377754669426;3642.672159505644;4971.080350135218;5069.062223488666;5763;6011.609245399035;6102.72537735803;6106.969465152691;6113.602615829956;6115.052166914135;6120.60216085953;5850.063989761639;5899.793507227269;5932.7330270137945;5942.405759424067;6030.10051833671;5156;5163.457836927106;5169.0954083035695;5170.638055336128;5170.922436163461;21285.467863303387;22054.779452205592;22236;23035.654465183037;23219.76780232193;1852.990735046316;1927.5961567064244;1948.4252145617077;1972.358983330409;2054.116729806183;14038.157710537298;14228.430862765477;14440.205788681618;14698.191599462025;14834.777478337834;1721.1910402110989;1749.4139463279816;1752.115181833179;1800.225902861782;1876.8029356917464;82098;83942.58028709878;84166;89665;89809.10190898138;16412.589685257724;16493.752593711102;16897.662046758996;16925.830741692673;17230.310787568607;28497.715022849505;28533.857330713214;28611;28893.422131557672;28994.565081523793;8378.371622128365;8809.911900881038;8960.193582577469;9215.953920230357;9235.12266334697;7323.450741194431;7341.3392794648735;7360.595167265745;7594.848103037908;7617.6572054257695;8103.716369927024;8221.465604735673;8284.792880177924;8319.168083191633;8335.416520843482;2968.4063187362613;3242.4325742994884;3242.6757324267383;3280.5079238113995;3351.949720754191;832.820943497151;869.9260562852102;876.509154873273;933.9813203736023;954.6801821389773;16086.83913160877;16241.756373654405;16768.161591920354;17075.914620426818;17307.356673764083;830.4892491117919;851.4550687559966;852.2585350744891;868.3400615532241;942.6747772018642;8849.64601415949;8907.777305567413;9331.300152488591;9398.483083430478;9585.089416505421;384942;385226;385910;391712;393053;67605.66197169082;82463.5876820612;88506.55746721225;88871.55564222267;89584.55207723919;878.0911756332293;880.8106257154615;930.0559931669361;930.6835675870236;932.8787257656461;15406.614834629225;15556.611084722972;16156.757648635044;16285.311015247637;16517.669646607243;38633.227335453696;38785.4182187273;38930.22139557137;39302.21395572015;39618.22672412903;49726;52806;53451;54115.72942135264;55479.72260138673;6833.145856767904;6872.484563657846;6975.6163411012885;7020.5787652741;7122.038524799086;8292.668293268202;10721.624743133953;10753.516091776046;10883.619073332395;11094.167937404565;9020.188183063556;9576.521173941135;9730.075642813921;9982.60069597208;10080.69757907264;9026.729198124067;9171.19734799123;9213.677521286723;9255;9369.953150234205;9320.487373194268;9382.249420046517;9509.572069257038;9607.471589062668;9826.557804898654;6807.9319206807295;6859.588424694534;6920.7231710731;7009.579425234502;7755.457118001911;7547.28300811422;9131.269498439977;9355;9733.513324333897;9758.51207439611 23:56:43 INFO - PROCESS | 25060 | |3;mootools.html;1329.946802127915;1363.2502123831894;1405.8383285922116;1641.5403686967638;1644.9177541122933;6863.725450981967;7644.923550764491;7700.730474433396;8172.877406838902;8238.958805205973;7841.529508229496;8091.150429204937;8093.959530202355;8099.635516401761;8105.91894081059;2178.2376168341075;2208.138825857917;2216.8115710164657;2221.278084622496;2223.6664500325;1897.4118023412718;2088.905999230031;2175.9782402175933;2177.3032629558547;2202.6695995600626;2253.357793028987;2271.239134889814;2274;2274.658801179828;2278.043221846828;2141.8929053547417;2160.405888380692;2161.7081693971427;2162.1243396424393;2163.3618082665494;2131.6482780341225;2145.0990583954776;2154.2244791874914;2156.3746513511064;2179.2917301876955;7926.841463170705;7983.880241796378;7991.160928102524;7991.960040199761;7994.360451163893;4351.999040220735;4488.798004089824;4495.325701144821;4496.100779844044;4497.168023915586;2763.392053748172;2902;2948.2334593005758;2955.9113226603235;2961.6446026476747;907.1065000974036;936.1557731269388;961.6538046303325;965.7826988927505;988.2094324540338;10532.789344213073;10539.104176145036;10639.414832184228;10651.733706657318;10690.732731681692;4059.6955228357992;4152.563980782034;4249.171411574714;4251.893702657459;4280.293751531025;8929.955350223207;8937.821243575092;8941.239994600532;8947.642094316285;8953.059928707522;4471;4484.349769283435;4493.640508759291;4503.414556107685;4516.480604730433;4029.879103626896;4072.837086516506;4091.365838295069;4096.672266218755;4096.692748043909;3651.087228192952;3785.261873934556;3843.2121415110396;3856.9228615427533;3988.3818008208195;1108.2851560743436;1166.1079274355125;1176.8411264479187;1235.172434468908;1238.4798384678456;808.6118663041773;828.5815663090222;830.1076342931373;830.339879795564;834.8580741273998;5213.635045546851;5266.21006848968;5332.973335133377;5342.6260161788305;5472.589555783332;844.8859403980507;852.0925214646505;860.2129051917425;862.5557837713583;868.452874688942;3522.383582872987;3591.802450865175;3600.6759391654878;3618.5476815398074;3683.834227459717;9598.136167744797;9892.505374731234;9954.203663706887;9957.850632240523;9978.301518893753;9868.851967220497;9877.209823214125;9901.455419951972;9904.306698531178;9916.95871933451;6977.686004129826;7039.507234493639;7047.119110111236;7053.16617679407;7055.823604409931;5210.635255532076;5235.319408476874;5237.895242095213;5285.841424757264;5335.67985920846;8872.689455869013;9070.319726020707;9204.355695101278;9243.075692430702;9312.161905428544;9132.269418446376;9202;9417.905820941976;9459.290553208675;9460.337776355589;6749.527533072835;6876.518643694893;6885.414739747178;6889.276625954403;6893.896591551031;7503.324700776956;7589.772306830804;7629.809254768676;7641.694332226871;7666.929928919049;6835.726570937107;6942.166939967236;6961.37347638715;7046.577205367695;7102.076730025064;6641.33586641332;6827.419669328163;6866.6566671667215;6966.825829354307;7011.228764835966;5855.970720146372;6069.2716873975405;6118.296395914527;6226.533010024357;6229.595076320024;4410.470743510671;4432.955670443254;4451.221036318567;4465.106978604227;4696.38946936896;3730.757500762442;3765.284595926765;4336.804843781974;4385.802638881321;4404.295312749945 23:56:43 INFO - PROCESS | 25060 | |4;prototype.html;297.6131029661441;302.7124231979619;307.38216185467206;311.6369429614499;311.81758671177363;4777.187878060729;4781.617470602352;4789.664723469358;4832.927506087407;4887.385248891844;4428.18078655449;4573.542645735425;4689.179393606114;4698.55363740445;4755.976220118903;3783.7729736215847;3803.4484999675033;3810.4474851146565;3817.44647026181;3831.544013274952;3809.5999920008417;3823.847046118152;3827.291950989062;3854.1713531590703;3856.9614303857024;3603.873864414746;3646.252518233769;3723.646253605903;3734.533183352081;3800.4869342638804;3734.25314937011;3741.551013878352;3746.6253374662588;3748.11919198988;3767.472553842464;3326.7338612910908;3339.482380231068;3349.112485191426;3350.4974253861874;3362.2098806780386;4450.376947227391;4582.1064892346;4614.792334344963;4636.860894173146;4659.976700116512;4271.188474189925;4271.209826182167;4273.636740877029;4286.87139385819;4294.6042536175555;4933.802647894116;4945.629077819142;4971.6271279654175;4985.626078044161;4993.201087825967;3707.7775333480076;3714.4056950888;3722.4788529605876;3726.5900750917376;3726.739128261023;4965.205567109245;5179.404368497596;5192.55863251624;5197.350331208561;5199.818006369796;3755.830987605564;3766.7928263945746;3767.453719210699;3767.773933563995;3769.095417099876;3816.503854498953;3817.885463436046;3821.7133714971487;3854.9421758673643;3865.690744740414;4213.536510983789;4281.550437204049;4314.158739045919;4344.913101737948;4347.5652434756275;4975.825846095442;4976.328195693535;4977.303177555145;4978.601711863114;5028.949710502849;1229.6864299603544;1299.3373379576485;1309.3584143769485;1318.53851152096;1322.259534660593;897.0132853860702;925.5279807298192;942.604106275366;943.8678584998106;945.333539854401;4420.646348292129;4423.535528769433;4432.423784907941;4435.489918659331;4470.284754439274;5069.569086627678;5070.340855688737;5088.643794934319;5091.905240373338;5096.362954630671;4307.806148723315;4419.8011089500405;4429.2913133899065;4433.312836510346;4450.1767173073085;2687.8118531702594;2698.231004163804;2700.3924117073893;2705.3101459127797;2718.7824974002033;4778.952210477851;4790.592799612072;4813.879653008633;4847.733374664357;4853.005133947532;4982.850514484571;5118.283440318359;5191.506806853343;5197.428282888879;5225.16397376418;4813.78337974792;4827.372441582572;4828.2998965150555;4841.225403935424;4846.39420072491;5109.540141387293;5130.97434512825;5139.665921715076;5141.87145321355;5163.793448262028;4829.551134659518;5080.11098057831;5098.133317335987;5131;5134.512221338967;4952.678075925053;4954.083494553519;4955.547004232928;4958.479359667183;4966.528179822911;3726.31063253299;3734.79458629778;3735.252949410183;3741.812909354576;3752.718546108998;5117.590592752496;5166.3025491558155;5182.989317083284;5190.55880250183;5300.761465733974;5000.174971129868;5013.573846223112;5027.321311622884;5034.421041580119;5035.622328325317;5035.949640503694;5105.463926287686;5116.539511443988;5118.181091025418;5123.412676598294;4673.228917228619;4853.058776489292;4896.681715688469;4918.803247870045;4922.523243027149;4915.058551872062;5057.69653820772;5074.238864170256;5077.873053173553;5097.311862898461;4946.551457028794;4970.602351811919;4973;5016.749162541785;5041.117804384145 23:56:43 INFO - PROCESS | 25060 | |5;yui.html;571.5656101362964;587.5886879184571;593.4036293525006;605.7637521366668;679.6533767778433;11408.372539510323;11684.065274778019;11717.882821171786;11719.882801171985;11778.411079446025;11456;11486.942565287183;11588.478518466669;11613.245139065955;11618.302901825897;604.3533419241413;749.4229443328634;763.9503432276912;764.5947647746704;764.8049747314427;614.7971169514072;618.5299172628812;620.534599050712;719.3346154806809;771.2364758888688;809.3161278719502;812.3257696112242;812.808989887376;813.0121901889204;813.1949370123565;800.7917941335295;801.7634797734682;802.5345299726174;802.6027116577274;804.5735760047185;466.1120565323065;562.9324481062258;581.5812614917253;592.602956019468;592.6829146406652;11425.885741142565;11471.139664525193;11497.482613282338;11514.136439766968;11517.884821151765;12365.69085772854;12827.935860320733;12860.87139128606;13230.669233269246;13646.317684115755;11308.773824523545;11505.137114716346;11540.480678369493;11709.53161873524;12233.510659573607;3814.5422549294262;3826.0626146593986;3830.2339532093692;3939.4878665773267;4011.5787842276723;53046;55079.72460137714;55258;58676.41323586752;61460;8748.781280467912;9120;10001.849972250422;10116.645917392856;10263.7434064149;16474.17629118539;16602.916985414995;16620.085895275875;16647.41734039303;16651.25069371881;5971.223740913653;5990.011648078105;5993.580449368589;6008.519318454513;6082.9695851520455;3539.2567560812004;3579.2125732338845;3586.605473397924;3627.328944145289;3665.065408320861;2641.9867900660374;2752.160591019758;2760.1857452051695;2766.8478233696937;2769.3492029373083;504.67196322390004;514.2389263889464;527.8733104054999;531.5163201486629;535.750875842735;450.3672340361797;554.525880372284;564.841844283593;570.535559248871;573.4007961680054;7020.5787652741;7174.426045916314;7447;7590.7722768317035;7688.115866675404;468.414481897628;470.89404883900846;506.97590866450304;511.98165563797323;560.1094260126418;3440.2775417162675;3461.5153878457036;3544.8404821783083;3554.7867127972404;3595.1910820065277;98955.50522247487;103287.4835625832;105114;109403;111279;90330.54834725785;90520;90587;90785;91333.54333228292;48547.757261214174;76352.61823690846;82165;83112.58443707984;85037.14962850293;6708.966455167693;6978.197507286524;6991.580505169706;7061.399781018671;7107.637457855147;41957.370639439825;47242.29136562886;47545.04909901852;48518;49344;63294.36705632885;64147;70115;71630;72392.63803680948;9645.662401815904;9923.206143508358;9944.801103977736;10358.171346292429;11553.191276610556;12554.623361299176;12658.367081646065;12670;13344;14473.565793026226;11275.492602832728;11359.602413915472;11722.648320550397;11876.524939002344;11945.581904633296;11319.943400283275;11478.426078695864;11652.88347116518;11887.82168267494;12375.75248495043;11067;11499.367534785668;11515.942420287845;12072.215306005082;12519.687007824878;9639.614415423304;10056.64801731936;10208.183345332503;10598.417087059977;10614.257002009785;9707.442173144324;9777.706668799947;9900.50497475109;9946.850797238192;10078.697639070839 23:56:43 INFO - PROCESS | 25060 | __end_tp_report 23:56:43 INFO - PROCESS | 25060 | __start_cc_report 23:56:43 INFO - PROCESS | 25060 | _x_x_mozilla_cycle_collect,1058 23:56:43 INFO - PROCESS | 25060 | __end_cc_report 23:56:43 INFO - PROCESS | 25060 | __startTimestamp1472799403106__endTimestamp 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | ------- Summary: start ------- 23:56:43 INFO - PROCESS | 25060 | Number of tests: 6 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | [#0] dojo.html Cycles:165 Average:4639.17 Median:4771.62 stddev:485.04 (10.2%) stddev-sans-first:458.67 23:56:43 INFO - PROCESS | 25060 | Values: 2573.8 2592.3 2611.1 2617.6 2636.5 4545.2 4573.6 4624.0 4649.2 4678.4 4606.3 4608.9 4621.0 4675.1 4702.6 4317.9 4336.6 4400.3 4426.6 4440.4 4368.7 4388.1 4389.3 4389.6 4410.4 4308.2 4512.1 4517.4 4522.1 4522.2 4203.5 4327.4 4336.2 4389.6 4390.0 4206.6 4262.4 4296.0 4342.6 4375.7 4631.0 4645.5 4658.2 4703.6 4803.7 5007.0 5037.6 5050.3 5059.1 5104.6 4850.4 4901.1 5044.5 5070.5 5106.4 4732.8 4733.8 4779.2 4791.3 4792.1 5153.9 5165.1 5187.9 5197.3 5253.7 4726.6 4929.9 4966.4 4972.8 4976.7 5054.7 5056.3 5060.9 5096.8 5110.8 4996.5 5011.2 5015.2 5017.2 5070.5 4776.8 4871.3 4897.1 4996.8 5001.3 4146.3 4165.5 4167.3 4200.2 4203.9 3654.7 3666.3 3670.1 3699.3 3709.5 4257.7 4337.1 4340.9 4346.4 4385.8 5034.2 5043.6 5055.8 5100.1 5122.3 4385.6 4387.8 4391.5 4393.8 4441.2 4425.5 4476.5 4523.4 4526.6 4570.6 4751.4 4771.6 4793.5 4796.3 4819.0 4993.3 5014.8 5015.5 5016.3 5070.3 4732.9 4746.3 4750.7 4751.2 4763.8 4781.9 4835.4 4847.4 4890.2 4949.0 4951.9 4963.2 4964.4 4964.9 5027.8 4813.1 4818.5 4821.0 4830.4 4880.6 4717.6 4833.0 4875.2 4884.5 4932.3 4735.8 4768.2 4771.4 4771.9 4821.2 4774.7 4818.4 4895.7 4900.2 4906.3 4814.5 4861.9 4869.6 4871.0 4922.3 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | [#1] ext.html Cycles:180 Average:46734.11 Median:35929.14 stddev:85615.40 (238.3%) stddev-sans-first:85785.43 23:56:43 INFO - PROCESS | 25060 | Values: 576.1 576.4 579.7 585.5 597.3 23624.6 36895.3 38124.0 38207.0 42170.8 39746.4 39951.8 39978.8 40019.8 40049.2 7671.6 7799.1 7800.6 7802.0 7806.0 7585.0 7652.4 7665.7 7667.7 7678.3 7700.1 7739.5 7747.6 7747.8 7778.3 7694.7 7722.7 7726.8 7728.4 7745.8 7591.5 7697.2 7714.5 7722.0 7730.5 37421.4 37545.8 37646.6 37764.6 37934.0 2613.2 2709.6 2759.4 2765.5 3074.0 28099.6 28116.0 28119.6 28476.1 29002.4 3072.0 3096.4 3132.5 3140.2 3257.9 247857.0 248358.0 248430.0 248607.0 248801.0 51107.6 51462.7 51483.5 51509.0 51564.2 32884.8 33833.5 33838.0 35206.5 54344.7 14470.3 14612.9 14669.3 14688.7 14702.6 11214.2 11459.8 11464.5 11464.5 11475.7 17725.3 17737.0 17777.2 17826.6 17849.2 6803.9 6816.8 6864.3 7065.9 7218.9 758.9 874.2 934.7 939.6 947.9 26326.5 27507.6 27566.9 27741.3 27800.3 912.7 922.1 922.3 972.1 1002.7 13795.7 14588.4 14840.9 14901.3 15133.8 381301.0 385793.0 386325.0 386994.0 387286.0 49252.3 49575.0 49701.5 49821.5 49857.8 1140.0 1153.3 1178.5 1186.2 1194.3 25935.7 26896.9 27187.6 27187.9 27768.7 41378.8 41585.0 41605.8 41637.0 41726.0 324252.0 325765.0 326217.0 327378.0 328710.0 24662.9 24869.8 25606.6 25675.0 25686.7 40951.6 41128.0 41930.0 42220.8 42237.6 34830.8 34994.4 36029.3 36198.3 37591.2 35263.8 35371.5 35677.0 35707.6 35829.0 34852.3 35293.0 35763.5 36296.8 36327.6 30751.7 32660.2 32840.7 32862.5 33507.5 24531.5 24726.9 24855.8 24962.0 25525.6 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | [#2] jquery.html Cycles:180 Average:25848.12 Median:53783.36 stddev:64581.89 (120.1%) stddev-sans-first:64734.66 23:56:43 INFO - PROCESS | 25060 | Values: 338.2 365.2 369.7 372.2 385.0 12420.1 14936.7 17203.8 17308.4 17447.7 15564.2 17031.4 17141.3 17223.5 17238.4 4909.7 5181.2 5181.5 5184.6 5185.4 3642.7 4971.1 5069.1 5763.0 6011.6 6102.7 6107.0 6113.6 6115.1 6120.6 5850.1 5899.8 5932.7 5942.4 6030.1 5156.0 5163.5 5169.1 5170.6 5170.9 21285.5 22054.8 22236.0 23035.7 23219.8 1853.0 1927.6 1948.4 1972.4 2054.1 14038.2 14228.4 14440.2 14698.2 14834.8 1721.2 1749.4 1752.1 1800.2 1876.8 82098.0 83942.6 84166.0 89665.0 89809.1 16412.6 16493.8 16897.7 16925.8 17230.3 28497.7 28533.9 28611.0 28893.4 28994.6 8378.4 8809.9 8960.2 9216.0 9235.1 7323.5 7341.3 7360.6 7594.8 7617.7 8103.7 8221.5 8284.8 8319.2 8335.4 2968.4 3242.4 3242.7 3280.5 3351.9 832.8 869.9 876.5 934.0 954.7 16086.8 16241.8 16768.2 17075.9 17307.4 830.5 851.5 852.3 868.3 942.7 8849.6 8907.8 9331.3 9398.5 9585.1 384942.0 385226.0 385910.0 391712.0 393053.0 67605.7 82463.6 88506.6 88871.6 89584.6 878.1 880.8 930.1 930.7 932.9 15406.6 15556.6 16156.8 16285.3 16517.7 38633.2 38785.4 38930.2 39302.2 39618.2 49726.0 52806.0 53451.0 54115.7 55479.7 6833.1 6872.5 6975.6 7020.6 7122.0 8292.7 10721.6 10753.5 10883.6 11094.2 9020.2 9576.5 9730.1 9982.6 10080.7 9026.7 9171.2 9213.7 9255.0 9370.0 9320.5 9382.2 9509.6 9607.5 9826.6 6807.9 6859.6 6920.7 7009.6 7755.5 7547.3 9131.3 9355.0 9733.5 9758.5 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | [#3] mootools.html Cycles:180 Average:5165.46 Median:5309.41 stddev:2930.54 (55.2%) stddev-sans-first:2924.58 23:56:43 INFO - PROCESS | 25060 | Values: 1329.9 1363.3 1405.8 1641.5 1644.9 6863.7 7644.9 7700.7 8172.9 8239.0 7841.5 8091.2 8094.0 8099.6 8105.9 2178.2 2208.1 2216.8 2221.3 2223.7 1897.4 2088.9 2176.0 2177.3 2202.7 2253.4 2271.2 2274.0 2274.7 2278.0 2141.9 2160.4 2161.7 2162.1 2163.4 2131.6 2145.1 2154.2 2156.4 2179.3 7926.8 7983.9 7991.2 7992.0 7994.4 4352.0 4488.8 4495.3 4496.1 4497.2 2763.4 2902.0 2948.2 2955.9 2961.6 907.1 936.2 961.7 965.8 988.2 10532.8 10539.1 10639.4 10651.7 10690.7 4059.7 4152.6 4249.2 4251.9 4280.3 8930.0 8937.8 8941.2 8947.6 8953.1 4471.0 4484.3 4493.6 4503.4 4516.5 4029.9 4072.8 4091.4 4096.7 4096.7 3651.1 3785.3 3843.2 3856.9 3988.4 1108.3 1166.1 1176.8 1235.2 1238.5 808.6 828.6 830.1 830.3 834.9 5213.6 5266.2 5333.0 5342.6 5472.6 844.9 852.1 860.2 862.6 868.5 3522.4 3591.8 3600.7 3618.5 3683.8 9598.1 9892.5 9954.2 9957.9 9978.3 9868.9 9877.2 9901.5 9904.3 9917.0 6977.7 7039.5 7047.1 7053.2 7055.8 5210.6 5235.3 5237.9 5285.8 5335.7 8872.7 9070.3 9204.4 9243.1 9312.2 9132.3 9202.0 9417.9 9459.3 9460.3 6749.5 6876.5 6885.4 6889.3 6893.9 7503.3 7589.8 7629.8 7641.7 7666.9 6835.7 6942.2 6961.4 7046.6 7102.1 6641.3 6827.4 6866.7 6966.8 7011.2 5856.0 6069.3 6118.3 6226.5 6229.6 4410.5 4433.0 4451.2 4465.1 4696.4 3730.8 3765.3 4336.8 4385.8 4404.3 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | [#4] prototype.html Cycles:180 Average:4180.92 Median:4766.58 stddev:1189.19 (24.9%) stddev-sans-first:1156.25 23:56:43 INFO - PROCESS | 25060 | Values: 297.6 302.7 307.4 311.6 311.8 4777.2 4781.6 4789.7 4832.9 4887.4 4428.2 4573.5 4689.2 4698.6 4756.0 3783.8 3803.4 3810.4 3817.4 3831.5 3809.6 3823.8 3827.3 3854.2 3857.0 3603.9 3646.3 3723.6 3734.5 3800.5 3734.3 3741.6 3746.6 3748.1 3767.5 3326.7 3339.5 3349.1 3350.5 3362.2 4450.4 4582.1 4614.8 4636.9 4660.0 4271.2 4271.2 4273.6 4286.9 4294.6 4933.8 4945.6 4971.6 4985.6 4993.2 3707.8 3714.4 3722.5 3726.6 3726.7 4965.2 5179.4 5192.6 5197.4 5199.8 3755.8 3766.8 3767.5 3767.8 3769.1 3816.5 3817.9 3821.7 3854.9 3865.7 4213.5 4281.6 4314.2 4344.9 4347.6 4975.8 4976.3 4977.3 4978.6 5028.9 1229.7 1299.3 1309.4 1318.5 1322.3 897.0 925.5 942.6 943.9 945.3 4420.6 4423.5 4432.4 4435.5 4470.3 5069.6 5070.3 5088.6 5091.9 5096.4 4307.8 4419.8 4429.3 4433.3 4450.2 2687.8 2698.2 2700.4 2705.3 2718.8 4779.0 4790.6 4813.9 4847.7 4853.0 4982.9 5118.3 5191.5 5197.4 5225.2 4813.8 4827.4 4828.3 4841.2 4846.4 5109.5 5131.0 5139.7 5141.9 5163.8 4829.6 5080.1 5098.1 5131.0 5134.5 4952.7 4954.1 4955.5 4958.5 4966.5 3726.3 3734.8 3735.3 3741.8 3752.7 5117.6 5166.3 5183.0 5190.6 5300.8 5000.2 5013.6 5027.3 5034.4 5035.6 5035.9 5105.5 5116.5 5118.2 5123.4 4673.2 4853.1 4896.7 4918.8 4922.5 4915.1 5057.7 5074.2 5077.9 5097.3 4946.6 4970.6 4973.0 5016.7 5041.1 23:56:43 INFO - PROCESS | 25060 | 23:56:43 INFO - PROCESS | 25060 | [#5] yui.html Cycles:180 Average:17983.22 Median:48031.52 stddev:26870.21 (55.9%) stddev-sans-first:26913.78 23:56:43 INFO - PROCESS | 25060 | Values: 571.6 587.6 593.4 605.8 679.7 11408.4 11684.1 11717.9 11719.9 11778.4 11456.0 11486.9 11588.5 11613.2 11618.3 604.4 749.4 764.0 764.6 764.8 614.8 618.5 620.5 719.3 771.2 809.3 812.3 812.8 813.0 813.2 800.8 801.8 802.5 802.6 804.6 466.1 562.9 581.6 592.6 592.7 11425.9 11471.1 11497.5 11514.1 11517.9 12365.7 12827.9 12860.9 13230.7 13646.3 11308.8 11505.1 11540.5 11709.5 12233.5 3814.5 3826.1 3830.2 3939.5 4011.6 53046.0 55079.7 55258.0 58676.4 61460.0 8748.8 9120.0 10001.8 10116.6 10263.7 16474.2 16602.9 16620.1 16647.4 16651.3 5971.2 5990.0 5993.6 6008.5 6083.0 3539.3 3579.2 3586.6 3627.3 3665.1 2642.0 2752.2 2760.2 2766.8 2769.3 504.7 514.2 527.9 531.5 535.8 450.4 554.5 564.8 570.5 573.4 7020.6 7174.4 7447.0 7590.8 7688.1 468.4 470.9 507.0 512.0 560.1 3440.3 3461.5 3544.8 3554.8 3595.2 98955.5 103287.5 105114.0 109403.0 111279.0 90330.5 90520.0 90587.0 90785.0 91333.5 48547.8 76352.6 82165.0 83112.6 85037.1 6709.0 6978.2 6991.6 7061.4 7107.6 41957.4 47242.3 47545.0 48518.0 49344.0 63294.4 64147.0 70115.0 71630.0 72392.6 9645.7 9923.2 9944.8 10358.2 11553.2 12554.6 12658.4 12670.0 13344.0 14473.6 11275.5 11359.6 11722.6 11876.5 11945.6 11319.9 11478.4 11652.9 11887.8 12375.8 11067.0 11499.4 11515.9 12072.2 12519.7 9639.6 10056.6 10208.2 10598.4 10614.3 9707.4 9777.7 9900.5 9946.9 10078.7 23:56:43 INFO - PROCESS | 25060 | -------- Summary: end -------- 23:56:43 INFO - PROCESS | 25060 | 23:56:44 INFO - TEST-INFO | 25060: exit 0 23:56:44 INFO - TEST-OK | dromaeo_css | took 1121107ms 23:56:44 INFO - TEST-START | kraken 23:56:44 INFO - operating with platform_type : linux_ 23:56:44 INFO - Initialising browser for kraken test... 23:56:44 INFO - TEST-INFO | started process 25364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFKvxzm/profile http://localhost:48074/getInfo.html) 23:56:48 INFO - PROCESS | 25364 | __metrics Screen width/height:1600/1200 23:56:48 INFO - PROCESS | 25364 | colorDepth:24 23:56:48 INFO - PROCESS | 25364 | Browser inner width/height: 1024/697 23:56:48 INFO - PROCESS | 25364 | __metrics 23:56:49 INFO - PROCESS | 25364 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:56:49 INFO - PROCESS | 25364 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:56:49 INFO - PROCESS | 25364 | console.error: PushService: 23:56:49 INFO - PROCESS | 25364 | stateChangeProcessEnqueue: Error transitioning state 23:56:49 INFO - PROCESS | 25364 | UnknownError 23:56:50 INFO - TEST-INFO | 25364: exit 0 23:56:50 INFO - Browser initialized. 23:56:50 INFO - Running cycle 1/1 for kraken test... 23:56:50 INFO - TEST-INFO | started process 25446 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFKvxzm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:57:02 INFO - PROCESS | 25446 | RSS: Main: 180957184 23:57:02 INFO - PROCESS | 25446 | 23:57:05 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48074/tests/kraken/driver.html?testName=audio-beat-detection) 23:57:06 INFO - PROCESS | 25446 | RSS: Main: 235589632 23:57:06 INFO - PROCESS | 25446 | 23:57:08 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48074/tests/kraken/driver.html?testName=audio-dft) 23:57:09 INFO - PROCESS | 25446 | RSS: Main: 224915456 23:57:09 INFO - PROCESS | 25446 | 23:57:12 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48074/tests/kraken/driver.html?testName=audio-fft) 23:57:13 INFO - PROCESS | 25446 | RSS: Main: 303611904 23:57:13 INFO - PROCESS | 25446 | 23:57:15 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48074/tests/kraken/driver.html?testName=audio-oscillator) 23:57:15 INFO - PROCESS | 25446 | RSS: Main: 214007808 23:57:15 INFO - PROCESS | 25446 | 23:57:18 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48074/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:57:18 INFO - PROCESS | 25446 | RSS: Main: 215162880 23:57:18 INFO - PROCESS | 25446 | 23:57:21 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48074/tests/kraken/driver.html?testName=imaging-darkroom) 23:57:22 INFO - PROCESS | 25446 | RSS: Main: 253243392 23:57:22 INFO - PROCESS | 25446 | 23:57:26 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48074/tests/kraken/driver.html?testName=imaging-desaturate) 23:57:26 INFO - PROCESS | 25446 | RSS: Main: 247275520 23:57:26 INFO - PROCESS | 25446 | 23:57:29 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48074/tests/kraken/driver.html?testName=json-parse-financial) 23:57:30 INFO - PROCESS | 25446 | RSS: Main: 250597376 23:57:30 INFO - PROCESS | 25446 | 23:57:31 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48074/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:57:32 INFO - PROCESS | 25446 | RSS: Main: 214540288 23:57:32 INFO - PROCESS | 25446 | 23:57:34 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:57:35 INFO - PROCESS | 25446 | RSS: Main: 222294016 23:57:35 INFO - PROCESS | 25446 | 23:57:37 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:57:38 INFO - PROCESS | 25446 | RSS: Main: 223092736 23:57:38 INFO - PROCESS | 25446 | 23:57:40 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:57:41 INFO - PROCESS | 25446 | RSS: Main: 224231424 23:57:41 INFO - PROCESS | 25446 | 23:57:44 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:57:45 INFO - PROCESS | 25446 | RSS: Main: 221896704 23:57:45 INFO - PROCESS | 25446 | 23:57:46 INFO - PROCESS | 25446 | Cycle 1(1): loaded http://localhost:48074/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48074/tests/kraken/driver.html?testName=ai-astar) 23:57:47 INFO - PROCESS | 25446 | RSS: Main: 273592320 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | __start_tp_report 23:57:47 INFO - PROCESS | 25446 | _x_x_mozilla_page_load 23:57:47 INFO - PROCESS | 25446 | _x_x_mozilla_page_load_details 23:57:47 INFO - PROCESS | 25446 | |i|pagename|runs| 23:57:47 INFO - PROCESS | 25446 | |0;ai-astar;113;115;122;115;115;116;115;116;116;130 23:57:47 INFO - PROCESS | 25446 | |1;audio-beat-detection;160;128;153;184;125;147;146;124;181;124 23:57:47 INFO - PROCESS | 25446 | |2;audio-dft;162;163;162;161;163;162;162;162;161;163 23:57:47 INFO - PROCESS | 25446 | |3;audio-fft;77;79;77;165;76;78;76;76;157;76 23:57:47 INFO - PROCESS | 25446 | |4;audio-oscillator;86;85;141;85;85;85;147;83;84;142 23:57:47 INFO - PROCESS | 25446 | |5;imaging-gaussian-blur;117;117;117;117;116;117;118;118;118;117 23:57:47 INFO - PROCESS | 25446 | |6;imaging-darkroom;146;146;147;146;146;147;146;146;145;147 23:57:47 INFO - PROCESS | 25446 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;96;95 23:57:47 INFO - PROCESS | 25446 | |8;json-parse-financial;64;64;66;66;64;66;65;65;65;65 23:57:47 INFO - PROCESS | 25446 | |9;json-stringify-tinderbox;66;66;66;67;66;65;66;65;66;66 23:57:47 INFO - PROCESS | 25446 | |10;stanford-crypto-aes;84;83;82;82;91;85;82;85;82;91 23:57:47 INFO - PROCESS | 25446 | |11;stanford-crypto-ccm;133;134;132;131;131;131;131;131;186;132 23:57:47 INFO - PROCESS | 25446 | |12;stanford-crypto-pbkdf2;177;177;184;181;179;179;175;174;173;183 23:57:47 INFO - PROCESS | 25446 | |13;stanford-crypto-sha256-iterative;72;70;69;69;68;70;69;69;68;68 23:57:47 INFO - PROCESS | 25446 | __end_tp_report 23:57:47 INFO - PROCESS | 25446 | __start_cc_report 23:57:47 INFO - PROCESS | 25446 | _x_x_mozilla_cycle_collect,1961 23:57:47 INFO - PROCESS | 25446 | __end_cc_report 23:57:47 INFO - PROCESS | 25446 | __startTimestamp1472799467074__endTimestamp 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | ------- Summary: start ------- 23:57:47 INFO - PROCESS | 25446 | Number of tests: 14 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#0] ai-astar Cycles:10 Average:117.30 Median:116.00 stddev:5.03 (4.3%) stddev-sans-first:5.09 23:57:47 INFO - PROCESS | 25446 | Values: 113.0 115.0 122.0 115.0 115.0 116.0 115.0 116.0 116.0 130.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:150.00 stddev:22.69 (15.1%) stddev-sans-first:23.59 23:57:47 INFO - PROCESS | 25446 | Values: 160.0 128.0 153.0 184.0 125.0 147.0 146.0 124.0 181.0 124.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 23:57:47 INFO - PROCESS | 25446 | Values: 162.0 163.0 162.0 161.0 163.0 162.0 162.0 162.0 161.0 163.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.50 stddev:35.53 (46.4%) stddev-sans-first:37.17 23:57:47 INFO - PROCESS | 25446 | Values: 77.0 79.0 77.0 165.0 76.0 78.0 76.0 76.0 157.0 76.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#4] audio-oscillator Cycles:10 Average:102.30 Median:85.00 stddev:28.37 (33.4%) stddev-sans-first:29.47 23:57:47 INFO - PROCESS | 25446 | Values: 86.0 85.0 141.0 85.0 85.0 85.0 147.0 83.0 84.0 142.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 23:57:47 INFO - PROCESS | 25446 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 118.0 118.0 118.0 117.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 23:57:47 INFO - PROCESS | 25446 | Values: 146.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 145.0 147.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:57:47 INFO - PROCESS | 25446 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 23:57:47 INFO - PROCESS | 25446 | Values: 64.0 64.0 66.0 66.0 64.0 66.0 65.0 65.0 65.0 65.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 23:57:47 INFO - PROCESS | 25446 | Values: 66.0 66.0 66.0 67.0 66.0 65.0 66.0 65.0 66.0 66.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.50 stddev:3.53 (4.2%) stddev-sans-first:3.73 23:57:47 INFO - PROCESS | 25446 | Values: 84.0 83.0 82.0 82.0 91.0 85.0 82.0 85.0 82.0 91.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:132.00 stddev:17.18 (13.0%) stddev-sans-first:18.15 23:57:47 INFO - PROCESS | 25446 | Values: 133.0 134.0 132.0 131.0 131.0 131.0 131.0 131.0 186.0 132.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:179.00 stddev:3.71 (2.1%) stddev-sans-first:3.91 23:57:47 INFO - PROCESS | 25446 | Values: 177.0 177.0 184.0 181.0 179.0 179.0 175.0 174.0 173.0 183.0 23:57:47 INFO - PROCESS | 25446 | 23:57:47 INFO - PROCESS | 25446 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:1.23 (1.8%) stddev-sans-first:0.78 23:57:47 INFO - PROCESS | 25446 | Values: 72.0 70.0 69.0 69.0 68.0 70.0 69.0 69.0 68.0 68.0 23:57:47 INFO - PROCESS | 25446 | -------- Summary: end -------- 23:57:47 INFO - PROCESS | 25446 | 23:57:48 INFO - TEST-INFO | 25446: exit 0 23:57:48 INFO - TEST-OK | kraken | took 64304ms 23:57:48 INFO - SUITE-END | took 1185s 23:57:48 INFO - Completed test suite (00:19:45) 23:57:48 INFO - javascript benchmark 23:57:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.806964477664, 2592.300078978677, 2611.099170786078, 2617.5550156473396, 2636.472705458909, 4545.227311357069, 4573.565511276432, 4623.953760462395, 4649.186392381333, 4678.368420263268, 4606.33208184813, 4608.861734147972, 4620.976895115528, 4675.064987002596, 4702.553257440538, 4317.913641727164, 4336.609705126546, 4400.251957167273, 4426.601605855472, 4440.42274504314, 4368.672349573779, 4388.100439409929, 4389.253826849427, 4389.648828093761, 4410.4266445362055, 4308.2029824482415, 4512.12013657337, 4517.41273634427, 4522.050369422409, 4522.23122069249, 4203.47456567929, 4327.394164816928, 4336.154449882274, 4389.626881715058, 4389.978050109761, 4206.558311377292, 4262.424572682679, 4296.0, 4342.565743425632, 4375.6937014409, 4631.036136872404, 4645.535446455361, 4658.231391820379, 4703.647226457995, 4803.663743537919, 5007.048660754482, 5037.596992240612, 5050.267711181858, 5059.064073146443, 5104.591632669378, 4850.369451971256, 4901.1177987962155, 5044.495550444926, 5070.518300761459, 5106.387233531963, 4732.763361831895, 4733.810647574059, 4779.235322348408, 4791.257355109963, 4792.065547218259, 5153.896922061538, 5165.096108181053, 5187.896242075138, 5197.272381866542, 5253.711045892513, 4726.550977657117, 4929.876753081202, 4966.428860681068, 4972.825951091767, 4976.6765160265195, 5054.696718196847, 5056.317397151338, 5060.898782024339, 5096.796128154906, 5110.821121260738, 4996.475370086161, 5011.198208286657, 5015.172496538104, 5017.197248440232, 5070.467600901926, 4776.8328108516735, 4871.293662419001, 4897.142999975062, 4996.775145118406, 5001.349824522788, 4146.3365861462025, 4165.500139983222, 4167.291560434734, 4200.201961627281, 4203.936940945889, 3654.744167908274, 3666.2850744104644, 3670.137517683342, 3699.315626609086, 3709.5177626908867, 4257.723247988871, 4337.0892112656065, 4340.891477713082, 4346.413234213404, 4385.758783266951, 5034.194528875362, 5043.5965122790085, 5055.823046193365, 5100.132977393852, 5122.308488354024, 4385.583369579885, 4387.780610969438, 4391.494978077498, 4393.802278897458, 4441.244988351988, 4425.513193548707, 4476.529964353761, 4523.36672865799, 4526.592606665416, 4570.565796249351, 4751.382320298339, 4771.618270538279, 4793.472718000948, 4796.3285140079715, 4818.975905120452, 4993.251012348177, 5014.84955451337, 5015.523525265094, 5016.322796422436, 5070.3155074064525, 4732.858014259577, 4746.288056791509, 4750.738709371018, 4751.239801631722, 4763.833265835679, 4781.880452988703, 4835.371401717754, 4847.418309802846, 4890.217565189552, 4949.034938187019, 4951.851444456672, 4963.205887058053, 4964.379452568429, 4964.900701986012, 5027.798888044437, 4813.109574728686, 4818.494058123846, 4821.0, 4830.396200474941, 4880.633952453509, 4717.622590192707, 4832.975835120802, 4875.24433712775, 4884.462709101925, 4932.334134891744, 4735.810567577396, 4768.237082066853, 4771.355866958055, 4771.880702982453, 4821.2045012572535, 4774.665773395829, 4818.397700287464, 4895.657303988829, 4900.240462728283, 4906.313116163811, 4814.542618451242, 4861.927071094007, 4869.6347773917805, 4870.95129048705, 4922.335484709662], "value": 4608.072056801558, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.1300436341129, 576.4091805898953, 579.6580017789506, 585.5110982329753, 597.2564157623759, 23624.64563031554, 36895.26209475812, 38124.0, 38207.04482387938, 42170.7891460543, 39746.403803942965, 39951.80024099875, 39978.80010599943, 40019.79990100053, 40049.199016019666, 7671.61641917906, 7799.103103143126, 7800.609969501531, 7801.960990195041, 7805.9609701951695, 7585.0, 7652.4260680448915, 7665.731699390522, 7667.693292268303, 7678.347340476066, 7700.11448683403, 7739.496932699357, 7747.573883436408, 7747.806304842367, 7778.338841198477, 7694.653740581659, 7722.691092356299, 7726.7681969541, 7728.381729461629, 7745.806354841146, 7591.506552074153, 7697.153313135551, 7714.537127772295, 7722.0, 7730.458867879251, 37421.438678419574, 37545.812270938746, 37646.623533764585, 37764.62235377639, 37934.0, 2613.2421597736634, 2709.580015097663, 2759.351552385188, 2765.502209602271, 3073.9702199763105, 28099.57850632242, 28116.0, 28119.57820632692, 28476.145715628565, 29002.41995160106, 3071.969280307213, 3096.4426403247408, 3132.4518209313505, 3140.1992491914643, 3257.9348413031844, 247857.0, 248358.0, 248430.0, 248607.0, 248801.0, 51107.63369846187, 51462.74268628708, 51483.48516514862, 51509.0, 51564.22653660198, 32884.83557582197, 33833.492497612555, 33837.96972181628, 35206.471902921476, 54344.728276358364, 14470.348834302482, 14612.926935365254, 14669.33987970544, 14688.706225875423, 14702.63243418923, 11214.215004949738, 11459.77080458386, 11464.484098215433, 11464.541418343173, 11475.71310717222, 17725.29098836058, 17737.024463654623, 17777.20002599886, 17826.643467130583, 17849.196786144654, 6803.931960680429, 6816.795496135124, 6864.313568643196, 7065.858682826418, 7218.855622887618, 758.93928485721, 874.226309715906, 934.6915517879083, 939.5818860606964, 947.8672985781996, 26326.473470530484, 27507.58738618922, 27566.862165689443, 27741.306467338076, 27800.304992375353, 912.717057712098, 922.0963455813206, 922.3036607361399, 972.1348000279756, 1002.7042022603349, 13795.724085518234, 14588.41646334156, 14840.925795370955, 14901.329440175217, 15133.772993405108, 381301.0, 385793.0, 386325.0, 386994.0, 387286.0, 49252.26121608179, 49575.0, 49701.50298497041, 49821.501784982414, 49857.75071124694, 1139.971500712489, 1153.2907262033789, 1178.4814681540267, 1186.1756079524648, 1194.3192380343294, 25935.740642593333, 26896.865515672296, 27187.59218611762, 27187.86406067957, 27768.722312777423, 41378.793106034274, 41585.0, 41605.75182744522, 41637.0, 41725.95685107897, 324252.0, 325765.0, 326217.0, 327378.0, 328710.0, 24662.87668561646, 24869.751302486744, 25606.61590076113, 25675.0, 25686.743132568434, 40951.59048409597, 41128.0, 41930.0, 42220.788896055325, 42237.57762422336, 34830.82584587061, 34994.3900067324, 36029.27941441209, 36198.27603447969, 37591.2481750358, 35263.82368088143, 35371.46942795911, 35677.0, 35707.64292357147, 35829.0, 34852.30295394129, 35293.0, 35763.46354804628, 36296.81851590725, 36327.63672363242, 30751.692483074883, 32660.183495412806, 32840.67159328376, 32862.507062393604, 33507.49738753872, 24531.509369812862, 24726.898213652043, 24855.751442485343, 24962.0, 25525.61711574291], "value": 17077.16164136522, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.2220891948518, 365.2348330248131, 369.6820734168615, 372.2201986837574, 384.95380554333485, 12420.130590858633, 14936.701265974674, 17203.82796172041, 17308.394206202785, 17447.651046979052, 15564.221788910538, 17031.40390086347, 17141.314347426087, 17223.483295501155, 17238.39665611704, 4909.680870743408, 5181.222816577506, 5181.481851814826, 5184.637075404723, 5185.377754669426, 3642.672159505644, 4971.080350135218, 5069.062223488666, 5763.0, 6011.609245399035, 6102.72537735803, 6106.969465152691, 6113.602615829956, 6115.052166914135, 6120.60216085953, 5850.063989761639, 5899.793507227269, 5932.7330270137945, 5942.405759424067, 6030.10051833671, 5156.0, 5163.457836927106, 5169.0954083035695, 5170.638055336128, 5170.922436163461, 21285.467863303387, 22054.779452205592, 22236.0, 23035.654465183037, 23219.76780232193, 1852.990735046316, 1927.5961567064244, 1948.4252145617077, 1972.358983330409, 2054.116729806183, 14038.157710537298, 14228.430862765477, 14440.205788681618, 14698.191599462025, 14834.777478337834, 1721.1910402110989, 1749.4139463279816, 1752.115181833179, 1800.225902861782, 1876.8029356917464, 82098.0, 83942.58028709878, 84166.0, 89665.0, 89809.10190898138, 16412.589685257724, 16493.752593711102, 16897.662046758996, 16925.830741692673, 17230.310787568607, 28497.715022849505, 28533.857330713214, 28611.0, 28893.422131557672, 28994.565081523793, 8378.371622128365, 8809.911900881038, 8960.193582577469, 9215.953920230357, 9235.12266334697, 7323.450741194431, 7341.3392794648735, 7360.595167265745, 7594.848103037908, 7617.6572054257695, 8103.716369927024, 8221.465604735673, 8284.792880177924, 8319.168083191633, 8335.416520843482, 2968.4063187362613, 3242.4325742994884, 3242.6757324267383, 3280.5079238113995, 3351.949720754191, 832.820943497151, 869.9260562852102, 876.509154873273, 933.9813203736023, 954.6801821389773, 16086.83913160877, 16241.756373654405, 16768.161591920354, 17075.914620426818, 17307.356673764083, 830.4892491117919, 851.4550687559966, 852.2585350744891, 868.3400615532241, 942.6747772018642, 8849.64601415949, 8907.777305567413, 9331.300152488591, 9398.483083430478, 9585.089416505421, 384942.0, 385226.0, 385910.0, 391712.0, 393053.0, 67605.66197169082, 82463.5876820612, 88506.55746721225, 88871.55564222267, 89584.55207723919, 878.0911756332293, 880.8106257154615, 930.0559931669361, 930.6835675870236, 932.8787257656461, 15406.614834629225, 15556.611084722972, 16156.757648635044, 16285.311015247637, 16517.669646607243, 38633.227335453696, 38785.4182187273, 38930.22139557137, 39302.21395572015, 39618.22672412903, 49726.0, 52806.0, 53451.0, 54115.72942135264, 55479.72260138673, 6833.145856767904, 6872.484563657846, 6975.6163411012885, 7020.5787652741, 7122.038524799086, 8292.668293268202, 10721.624743133953, 10753.516091776046, 10883.619073332395, 11094.167937404565, 9020.188183063556, 9576.521173941135, 9730.075642813921, 9982.60069597208, 10080.69757907264, 9026.729198124067, 9171.19734799123, 9213.677521286723, 9255.0, 9369.953150234205, 9320.487373194268, 9382.249420046517, 9509.572069257038, 9607.471589062668, 9826.557804898654, 6807.9319206807295, 6859.588424694534, 6920.7231710731, 7009.579425234502, 7755.457118001911, 7547.28300811422, 9131.269498439977, 9355.0, 9733.513324333897, 9758.51207439611], "value": 8946.172725937113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.946802127915, 1363.2502123831894, 1405.8383285922116, 1641.5403686967638, 1644.9177541122933, 6863.725450981967, 7644.923550764491, 7700.730474433396, 8172.877406838902, 8238.958805205973, 7841.529508229496, 8091.150429204937, 8093.959530202355, 8099.635516401761, 8105.91894081059, 2178.2376168341075, 2208.138825857917, 2216.8115710164657, 2221.278084622496, 2223.6664500325, 1897.4118023412718, 2088.905999230031, 2175.9782402175933, 2177.3032629558547, 2202.6695995600626, 2253.357793028987, 2271.239134889814, 2274.0, 2274.658801179828, 2278.043221846828, 2141.8929053547417, 2160.405888380692, 2161.7081693971427, 2162.1243396424393, 2163.3618082665494, 2131.6482780341225, 2145.0990583954776, 2154.2244791874914, 2156.3746513511064, 2179.2917301876955, 7926.841463170705, 7983.880241796378, 7991.160928102524, 7991.960040199761, 7994.360451163893, 4351.999040220735, 4488.798004089824, 4495.325701144821, 4496.100779844044, 4497.168023915586, 2763.392053748172, 2902.0, 2948.2334593005758, 2955.9113226603235, 2961.6446026476747, 907.1065000974036, 936.1557731269388, 961.6538046303325, 965.7826988927505, 988.2094324540338, 10532.789344213073, 10539.104176145036, 10639.414832184228, 10651.733706657318, 10690.732731681692, 4059.6955228357992, 4152.563980782034, 4249.171411574714, 4251.893702657459, 4280.293751531025, 8929.955350223207, 8937.821243575092, 8941.239994600532, 8947.642094316285, 8953.059928707522, 4471.0, 4484.349769283435, 4493.640508759291, 4503.414556107685, 4516.480604730433, 4029.879103626896, 4072.837086516506, 4091.365838295069, 4096.672266218755, 4096.692748043909, 3651.087228192952, 3785.261873934556, 3843.2121415110396, 3856.9228615427533, 3988.3818008208195, 1108.2851560743436, 1166.1079274355125, 1176.8411264479187, 1235.172434468908, 1238.4798384678456, 808.6118663041773, 828.5815663090222, 830.1076342931373, 830.339879795564, 834.8580741273998, 5213.635045546851, 5266.21006848968, 5332.973335133377, 5342.6260161788305, 5472.589555783332, 844.8859403980507, 852.0925214646505, 860.2129051917425, 862.5557837713583, 868.452874688942, 3522.383582872987, 3591.802450865175, 3600.6759391654878, 3618.5476815398074, 3683.834227459717, 9598.136167744797, 9892.505374731234, 9954.203663706887, 9957.850632240523, 9978.301518893753, 9868.851967220497, 9877.209823214125, 9901.455419951972, 9904.306698531178, 9916.95871933451, 6977.686004129826, 7039.507234493639, 7047.119110111236, 7053.16617679407, 7055.823604409931, 5210.635255532076, 5235.319408476874, 5237.895242095213, 5285.841424757264, 5335.67985920846, 8872.689455869013, 9070.319726020707, 9204.355695101278, 9243.075692430702, 9312.161905428544, 9132.269418446376, 9202.0, 9417.905820941976, 9459.290553208675, 9460.337776355589, 6749.527533072835, 6876.518643694893, 6885.414739747178, 6889.276625954403, 6893.896591551031, 7503.324700776956, 7589.772306830804, 7629.809254768676, 7641.694332226871, 7666.929928919049, 6835.726570937107, 6942.166939967236, 6961.37347638715, 7046.577205367695, 7102.076730025064, 6641.33586641332, 6827.419669328163, 6866.6566671667215, 6966.825829354307, 7011.228764835966, 5855.970720146372, 6069.2716873975405, 6118.296395914527, 6226.533010024357, 6229.595076320024, 4410.470743510671, 4432.955670443254, 4451.221036318567, 4465.106978604227, 4696.38946936896, 3730.757500762442, 3765.284595926765, 4336.804843781974, 4385.802638881321, 4404.295312749945], "value": 4148.123477061578, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.6131029661441, 302.7124231979619, 307.38216185467206, 311.6369429614499, 311.81758671177363, 4777.187878060729, 4781.617470602352, 4789.664723469358, 4832.927506087407, 4887.385248891844, 4428.18078655449, 4573.542645735425, 4689.179393606114, 4698.55363740445, 4755.976220118903, 3783.7729736215847, 3803.4484999675033, 3810.4474851146565, 3817.44647026181, 3831.544013274952, 3809.5999920008417, 3823.847046118152, 3827.291950989062, 3854.1713531590703, 3856.9614303857024, 3603.873864414746, 3646.252518233769, 3723.646253605903, 3734.533183352081, 3800.4869342638804, 3734.25314937011, 3741.551013878352, 3746.6253374662588, 3748.11919198988, 3767.472553842464, 3326.7338612910908, 3339.482380231068, 3349.112485191426, 3350.4974253861874, 3362.2098806780386, 4450.376947227391, 4582.1064892346, 4614.792334344963, 4636.860894173146, 4659.976700116512, 4271.188474189925, 4271.209826182167, 4273.636740877029, 4286.87139385819, 4294.6042536175555, 4933.802647894116, 4945.629077819142, 4971.6271279654175, 4985.626078044161, 4993.201087825967, 3707.7775333480076, 3714.4056950888, 3722.4788529605876, 3726.5900750917376, 3726.739128261023, 4965.205567109245, 5179.404368497596, 5192.55863251624, 5197.350331208561, 5199.818006369796, 3755.830987605564, 3766.7928263945746, 3767.453719210699, 3767.773933563995, 3769.095417099876, 3816.503854498953, 3817.885463436046, 3821.7133714971487, 3854.9421758673643, 3865.690744740414, 4213.536510983789, 4281.550437204049, 4314.158739045919, 4344.913101737948, 4347.5652434756275, 4975.825846095442, 4976.328195693535, 4977.303177555145, 4978.601711863114, 5028.949710502849, 1229.6864299603544, 1299.3373379576485, 1309.3584143769485, 1318.53851152096, 1322.259534660593, 897.0132853860702, 925.5279807298192, 942.604106275366, 943.8678584998106, 945.333539854401, 4420.646348292129, 4423.535528769433, 4432.423784907941, 4435.489918659331, 4470.284754439274, 5069.569086627678, 5070.340855688737, 5088.643794934319, 5091.905240373338, 5096.362954630671, 4307.806148723315, 4419.8011089500405, 4429.2913133899065, 4433.312836510346, 4450.1767173073085, 2687.8118531702594, 2698.231004163804, 2700.3924117073893, 2705.3101459127797, 2718.7824974002033, 4778.952210477851, 4790.592799612072, 4813.879653008633, 4847.733374664357, 4853.005133947532, 4982.850514484571, 5118.283440318359, 5191.506806853343, 5197.428282888879, 5225.16397376418, 4813.78337974792, 4827.372441582572, 4828.2998965150555, 4841.225403935424, 4846.39420072491, 5109.540141387293, 5130.97434512825, 5139.665921715076, 5141.87145321355, 5163.793448262028, 4829.551134659518, 5080.11098057831, 5098.133317335987, 5131.0, 5134.512221338967, 4952.678075925053, 4954.083494553519, 4955.547004232928, 4958.479359667183, 4966.528179822911, 3726.31063253299, 3734.79458629778, 3735.252949410183, 3741.812909354576, 3752.718546108998, 5117.590592752496, 5166.3025491558155, 5182.989317083284, 5190.55880250183, 5300.761465733974, 5000.174971129868, 5013.573846223112, 5027.321311622884, 5034.421041580119, 5035.622328325317, 5035.949640503694, 5105.463926287686, 5116.539511443988, 5118.181091025418, 5123.412676598294, 4673.228917228619, 4853.058776489292, 4896.681715688469, 4918.803247870045, 4922.523243027149, 4915.058551872062, 5057.69653820772, 5074.238864170256, 5077.873053173553, 5097.311862898461, 4946.551457028794, 4970.602351811919, 4973.0, 5016.749162541785, 5041.117804384145], "value": 3809.278954534975, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.5656101362964, 587.5886879184571, 593.4036293525006, 605.7637521366668, 679.6533767778433, 11408.372539510323, 11684.065274778019, 11717.882821171786, 11719.882801171985, 11778.411079446025, 11456.0, 11486.942565287183, 11588.478518466669, 11613.245139065955, 11618.302901825897, 604.3533419241413, 749.4229443328634, 763.9503432276912, 764.5947647746704, 764.8049747314427, 614.7971169514072, 618.5299172628812, 620.534599050712, 719.3346154806809, 771.2364758888688, 809.3161278719502, 812.3257696112242, 812.808989887376, 813.0121901889204, 813.1949370123565, 800.7917941335295, 801.7634797734682, 802.5345299726174, 802.6027116577274, 804.5735760047185, 466.1120565323065, 562.9324481062258, 581.5812614917253, 592.602956019468, 592.6829146406652, 11425.885741142565, 11471.139664525193, 11497.482613282338, 11514.136439766968, 11517.884821151765, 12365.69085772854, 12827.935860320733, 12860.87139128606, 13230.669233269246, 13646.317684115755, 11308.773824523545, 11505.137114716346, 11540.480678369493, 11709.53161873524, 12233.510659573607, 3814.5422549294262, 3826.0626146593986, 3830.2339532093692, 3939.4878665773267, 4011.5787842276723, 53046.0, 55079.72460137714, 55258.0, 58676.41323586752, 61460.0, 8748.781280467912, 9120.0, 10001.849972250422, 10116.645917392856, 10263.7434064149, 16474.17629118539, 16602.916985414995, 16620.085895275875, 16647.41734039303, 16651.25069371881, 5971.223740913653, 5990.011648078105, 5993.580449368589, 6008.519318454513, 6082.9695851520455, 3539.2567560812004, 3579.2125732338845, 3586.605473397924, 3627.328944145289, 3665.065408320861, 2641.9867900660374, 2752.160591019758, 2760.1857452051695, 2766.8478233696937, 2769.3492029373083, 504.67196322390004, 514.2389263889464, 527.8733104054999, 531.5163201486629, 535.750875842735, 450.3672340361797, 554.525880372284, 564.841844283593, 570.535559248871, 573.4007961680054, 7020.5787652741, 7174.426045916314, 7447.0, 7590.7722768317035, 7688.115866675404, 468.414481897628, 470.89404883900846, 506.97590866450304, 511.98165563797323, 560.1094260126418, 3440.2775417162675, 3461.5153878457036, 3544.8404821783083, 3554.7867127972404, 3595.1910820065277, 98955.50522247487, 103287.4835625832, 105114.0, 109403.0, 111279.0, 90330.54834725785, 90520.0, 90587.0, 90785.0, 91333.54333228292, 48547.757261214174, 76352.61823690846, 82165.0, 83112.58443707984, 85037.14962850293, 6708.966455167693, 6978.197507286524, 6991.580505169706, 7061.399781018671, 7107.637457855147, 41957.370639439825, 47242.29136562886, 47545.04909901852, 48518.0, 49344.0, 63294.36705632885, 64147.0, 70115.0, 71630.0, 72392.63803680948, 9645.662401815904, 9923.206143508358, 9944.801103977736, 10358.171346292429, 11553.191276610556, 12554.623361299176, 12658.367081646065, 12670.0, 13344.0, 14473.565793026226, 11275.492602832728, 11359.602413915472, 11722.648320550397, 11876.524939002344, 11945.581904633296, 11319.943400283275, 11478.426078695864, 11652.88347116518, 11887.82168267494, 12375.75248495043, 11067.0, 11499.367534785668, 11515.942420287845, 12072.215306005082, 12519.687007824878, 9639.614415423304, 10056.64801731936, 10208.183345332503, 10598.417087059977, 10614.257002009785, 9707.442173144324, 9777.706668799947, 9900.50497475109, 9946.850797238192, 10078.697639070839], "value": 6327.513386481652, "unit": "score"}], "name": "dromaeo_css", "value": 6425.766860903606, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 122.0, 115.0, 115.0, 116.0, 115.0, 116.0, 116.0, 130.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 128.0, 153.0, 184.0, 125.0, 147.0, 146.0, 124.0, 181.0, 124.0], "value": 147.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 161.0, 163.0, 162.0, 162.0, 162.0, 161.0, 163.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 77.0, 165.0, 76.0, 78.0, 76.0, 76.0, 157.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 141.0, 85.0, 85.0, 85.0, 147.0, 83.0, 84.0, 142.0], "value": 102.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 118.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 66.0, 64.0, 66.0, 65.0, 65.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 65.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 82.0, 91.0, 85.0, 82.0, 85.0, 82.0, 91.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 132.0, 131.0, 131.0, 131.0, 131.0, 131.0, 186.0, 132.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 177.0, 184.0, 181.0, 179.0, 179.0, 175.0, 174.0, 173.0, 183.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 69.0, 69.0, 68.0, 70.0, 69.0, 69.0, 68.0, 68.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1581.4000000000003, "alertThreshold": 2.0}]} 23:57:48 INFO - Return code: 0 23:57:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:57:48 INFO - # TBPL SUCCESS # 23:57:48 INFO - Running post-action listener: _resource_record_post_action 23:57:48 INFO - [mozharness: 2016-09-02 06:57:48.870306Z] Finished run-tests step (success) 23:57:48 INFO - Running post-run listener: _resource_record_post_run 23:57:48 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408932352; Read time: 16; Write time: 268676 23:57:48 INFO - TinderboxPrint: CPU usage
13.0% 23:57:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 23:57:48 INFO - TinderboxPrint: I/O write bytes / time
408,932,352 / 268,676 23:57:48 INFO - TinderboxPrint: CPU idle
8,341.5 (87.0%) 23:57:48 INFO - TinderboxPrint: CPU user
1,201.3 (12.5%) 23:57:48 INFO - TinderboxPrint: Swap in / out
0 / 0 23:57:48 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 113598464; Read time: 0; Write time: 98996 23:57:48 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 294342656; Read time: 0; Write time: 169592 23:57:49 INFO - Running post-run listener: _upload_blobber_files 23:57:49 INFO - Blob upload gear active. 23:57:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:57:49 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 23:57:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:57:49 INFO - (blobuploader) - INFO - Open directory for files ... 23:57:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:57:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:57:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:57:50 INFO - (blobuploader) - INFO - Done attempting. 23:57:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:57:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:57:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:57:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:57:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:57:51 INFO - (blobuploader) - INFO - Done attempting. 23:57:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:57:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:57:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:57:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:57:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:57:55 INFO - (blobuploader) - INFO - Done attempting. 23:57:55 INFO - (blobuploader) - INFO - Iteration through files over. 23:57:55 INFO - Return code: 0 23:57:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:57:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:57:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7ed6738d7a40b79271dbb2ac3c045eb220c1891ea564a7591b188efd118b91e7897fc0c7b1d3eaff2444ef65a967df81f9fd3874e2668016d6bb1137fe1f3813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/01b7b35b9f4eabf1d6ae1127e8e9631f42f2a2cdcbbe334f95f8d7542626d778283fdf00287907de6b1053215891512aed4c0867d0dfd6068d01e4ba79150d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f252c217dbdadb77d75399e7ae9908251e20afa7f84ee5bcd16490acca399906bb7a5b259b3a93bb45d9f2b9ade16bfb5269c67fb794fb2d14f73a776446eb6"} 23:57:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:57:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:57:55 INFO - Contents: 23:57:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7ed6738d7a40b79271dbb2ac3c045eb220c1891ea564a7591b188efd118b91e7897fc0c7b1d3eaff2444ef65a967df81f9fd3874e2668016d6bb1137fe1f3813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/01b7b35b9f4eabf1d6ae1127e8e9631f42f2a2cdcbbe334f95f8d7542626d778283fdf00287907de6b1053215891512aed4c0867d0dfd6068d01e4ba79150d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f252c217dbdadb77d75399e7ae9908251e20afa7f84ee5bcd16490acca399906bb7a5b259b3a93bb45d9f2b9ade16bfb5269c67fb794fb2d14f73a776446eb6"} 23:57:55 INFO - Running post-run listener: copy_logs_to_upload_dir 23:57:55 INFO - Copying logs to upload dir... 23:57:55 INFO - mkdir: /builds/slave/test/build/upload/logs 23:57:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.709353 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-01 23:57:54.765141) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:57:54.769113) ========= 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=0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7ed6738d7a40b79271dbb2ac3c045eb220c1891ea564a7591b188efd118b91e7897fc0c7b1d3eaff2444ef65a967df81f9fd3874e2668016d6bb1137fe1f3813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/01b7b35b9f4eabf1d6ae1127e8e9631f42f2a2cdcbbe334f95f8d7542626d778283fdf00287907de6b1053215891512aed4c0867d0dfd6068d01e4ba79150d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f252c217dbdadb77d75399e7ae9908251e20afa7f84ee5bcd16490acca399906bb7a5b259b3a93bb45d9f2b9ade16bfb5269c67fb794fb2d14f73a776446eb6"} build_url:https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/dYBcgnSSRPuCVtrMWvwNYQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7ed6738d7a40b79271dbb2ac3c045eb220c1891ea564a7591b188efd118b91e7897fc0c7b1d3eaff2444ef65a967df81f9fd3874e2668016d6bb1137fe1f3813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/01b7b35b9f4eabf1d6ae1127e8e9631f42f2a2cdcbbe334f95f8d7542626d778283fdf00287907de6b1053215891512aed4c0867d0dfd6068d01e4ba79150d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f252c217dbdadb77d75399e7ae9908251e20afa7f84ee5bcd16490acca399906bb7a5b259b3a93bb45d9f2b9ade16bfb5269c67fb794fb2d14f73a776446eb6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:57:54.794669) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:57:54.795008) ========= 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=0333a256a5e4f052382dd180000001b1-1472796563.20043-1321669476 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:57:54.878900) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:57:54.879260) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:57:54.879678) ========= ========= Total master_lag: 0.17 =========