builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1468608893.68 results: success (0) buildid: 20160715064309 builduid: c7b7f5c4fe1d41f4b73d9e5baffa9038 revision: 5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.681815) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.682268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.682629) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.697153) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.697433) ========= 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=62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 11:54:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-07-15 11:54:53 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128100 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.839230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.839548) ========= 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=62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018344 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.889205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:53.889558) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854 --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=62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 11:54:54,091 truncating revision to first 12 chars 2016-07-15 11:54:54,091 Setting DEBUG logging. 2016-07-15 11:54:54,092 attempt 1/10 2016-07-15 11:54:54,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/5a9c26f8bb9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 11:54:54,406 unpacking tar archive at: mozilla-inbound-5a9c26f8bb9d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647940 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:54.550730) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:54.551025) ========= script_repo_revision: 5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:54.554430) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:54.554707) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 11:54:54.565126) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-15 11:54:54.565404) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:54:54 INFO - MultiFileLogger online at 20160715 11:54:54 in /builds/slave/test 11:54:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 11:54:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:54:54 INFO - {'append_to_log': False, 11:54:54 INFO - 'base_work_dir': '/builds/slave/test', 11:54:54 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 11:54:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:54:54 INFO - 'branch': 'Mozilla-Inbound', 11:54:54 INFO - 'buildbot_json_path': 'buildprops.json', 11:54:54 INFO - 'config_files': ('talos/linux_config.py',), 11:54:54 INFO - 'default_actions': ('clobber', 11:54:54 INFO - 'read-buildbot-config', 11:54:54 INFO - 'download-and-extract', 11:54:54 INFO - 'populate-webroot', 11:54:54 INFO - 'create-virtualenv', 11:54:54 INFO - 'install', 11:54:54 INFO - 'run-tests'), 11:54:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:54:54 INFO - 'download_minidump_stackwalk': True, 11:54:54 INFO - 'download_symbols': 'ondemand', 11:54:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:54:54 INFO - 'tooltool.py': '/tools/tooltool.py', 11:54:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:54:54 INFO - '/tools/misc-python/virtualenv.py')}, 11:54:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:54:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:54:54 INFO - 'installer_path': 'installer.exe', 11:54:54 INFO - 'log_level': 'info', 11:54:54 INFO - 'log_name': 'talos', 11:54:54 INFO - 'log_to_console': True, 11:54:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:54:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:54:54 INFO - 'opt_config_files': (), 11:54:54 INFO - 'pip_index': False, 11:54:54 INFO - 'sps_profile': False, 11:54:54 INFO - 'sps_profile_interval': 0, 11:54:54 INFO - 'suite': 'dromaeojs', 11:54:54 INFO - 'system_bits': '32', 11:54:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:54:54 INFO - 'title': 'talos-linux64-ix-018', 11:54:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:54:54 INFO - 'use_talos_json': True, 11:54:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:54:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:54:54 INFO - 'work_dir': 'build'} 11:54:54 INFO - ##### 11:54:54 INFO - ##### Running clobber step. 11:54:54 INFO - ##### 11:54:54 INFO - Running pre-action listener: _resource_record_pre_action 11:54:54 INFO - Running main action method: clobber 11:54:54 INFO - rmtree: /builds/slave/test/build 11:54:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:54:55 INFO - Running post-action listener: _resource_record_post_action 11:54:55 INFO - ##### 11:54:55 INFO - ##### Finished clobber step (success) 11:54:55 INFO - ##### 11:54:55 INFO - ##### 11:54:55 INFO - ##### Running read-buildbot-config step. 11:54:55 INFO - ##### 11:54:55 INFO - Running pre-action listener: _resource_record_pre_action 11:54:55 INFO - Running main action method: read_buildbot_config 11:54:55 INFO - Using buildbot properties: 11:54:55 INFO - { 11:54:55 INFO - "project": "", 11:54:55 INFO - "product": "firefox", 11:54:55 INFO - "script_repo_revision": "production", 11:54:55 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 11:54:55 INFO - "repository": "", 11:54:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 11:54:55 INFO - "buildid": "20160715064309", 11:54:55 INFO - "pgo_build": "True", 11:54:55 INFO - "basedir": "/builds/slave/test", 11:54:55 INFO - "buildnumber": 2064, 11:54:55 INFO - "slavename": "talos-linux64-ix-018", 11:54:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:54:55 INFO - "platform": "ubuntu64_hw", 11:54:55 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 11:54:55 INFO - "branch": "mozilla-inbound", 11:54:55 INFO - "repo_path": "integration/mozilla-inbound", 11:54:55 INFO - "revision": "5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854", 11:54:55 INFO - "stage_platform": "linux64-pgo", 11:54:55 INFO - "builduid": "c7b7f5c4fe1d41f4b73d9e5baffa9038", 11:54:55 INFO - "slavebuilddir": "test" 11:54:55 INFO - } 11:54:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:54:55 INFO - Running post-action listener: _resource_record_post_action 11:54:55 INFO - ##### 11:54:55 INFO - ##### Finished read-buildbot-config step (success) 11:54:55 INFO - ##### 11:54:55 INFO - ##### 11:54:55 INFO - ##### Running download-and-extract step. 11:54:55 INFO - ##### 11:54:55 INFO - Running pre-action listener: _resource_record_pre_action 11:54:55 INFO - Running main action method: download_and_extract 11:54:55 INFO - mkdir: /builds/slave/test/build/tests 11:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:55 INFO - https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:54:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:54:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:54:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:54:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:54:56 INFO - Downloaded 1361 bytes. 11:54:56 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:54:56 INFO - Using the following test package requirements: 11:54:56 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:54:56 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:54:56 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:54:56 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'jsshell-linux-x86_64.zip'], 11:54:56 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:54:56 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:54:56 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:54:56 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:54:56 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:54:56 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:54:56 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:54:56 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:56 INFO - https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:54:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:54:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:54:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:54:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:54:57 INFO - Downloaded 24327512 bytes. 11:54:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:54:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:54:58 INFO - Return code: 0 11:54:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:58 INFO - https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:54:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:54:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:54:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:54:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:54:59 INFO - Downloaded 24327512 bytes. 11:54:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:54:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:55:01 INFO - Return code: 0 11:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:01 INFO - https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:55:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:55:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:55:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:55:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:55:02 INFO - Downloaded 14403317 bytes. 11:55:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:55:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:55:02 INFO - Return code: 0 11:55: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:02 INFO - https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:55:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:55:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:55:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:55:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:55:03 INFO - Downloaded 60817707 bytes. 11:55:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:55:03 INFO - mkdir: /builds/slave/test/properties 11:55:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:55:03 INFO - Writing to file /builds/slave/test/properties/build_url 11:55:03 INFO - Contents: 11:55:03 INFO - build_url:https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:55:04 INFO - Running post-action listener: _resource_record_post_action 11:55:04 INFO - Running post-action listener: set_extra_try_arguments 11:55:04 INFO - ##### 11:55:04 INFO - ##### Finished download-and-extract step (success) 11:55:04 INFO - ##### 11:55:04 INFO - ##### 11:55:04 INFO - ##### Running populate-webroot step. 11:55:04 INFO - ##### 11:55:04 INFO - Running pre-action listener: _resource_record_pre_action 11:55:04 INFO - Running main action method: populate_webroot 11:55:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:55:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:55:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:55:04 INFO - '--tppagecycles', 11:55:04 INFO - '7'], 11:55:04 INFO - 'tests': ['tsvgm']}}, 11:55:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:55:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:55:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:55:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:55:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:55:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:55:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['damp', 'tps']}, 11:55:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'tests': ['damp', 'tps']}, 11:55:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['dromaeo_dom']}, 11:55:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:55:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['basic_compositor_video']}, 11:55:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:55:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['a11yr', 11:55:04 INFO - 'ts_paint', 11:55:04 INFO - 'tpaint', 11:55:04 INFO - 'sessionrestore', 11:55:04 INFO - 'sessionrestore_no_auto_restore', 11:55:04 INFO - 'tabpaint']}, 11:55:04 INFO - 'other-e10s': {'tests': ['a11yr', 11:55:04 INFO - 'ts_paint', 11:55:04 INFO - 'tpaint', 11:55:04 INFO - 'sessionrestore', 11:55:04 INFO - 'sessionrestore_no_auto_restore', 11:55:04 INFO - 'tabpaint']}, 11:55:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:55:04 INFO - 'ts_paint', 11:55:04 INFO - 'tpaint', 11:55:04 INFO - 'sessionrestore', 11:55:04 INFO - 'sessionrestore_no_auto_restore', 11:55:04 INFO - 'tabpaint']}, 11:55:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:55:04 INFO - 'ts_paint', 11:55:04 INFO - 'tpaint', 11:55:04 INFO - 'sessionrestore', 11:55:04 INFO - 'sessionrestore_no_auto_restore', 11:55:04 INFO - 'tabpaint']}, 11:55:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['a11yr', 11:55:04 INFO - 'ts_paint', 11:55:04 INFO - 'tpaint', 11:55:04 INFO - 'sessionrestore', 11:55:04 INFO - 'sessionrestore_no_auto_restore', 11:55:04 INFO - 'tabpaint']}, 11:55:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['a11yr', 11:55:04 INFO - 'ts_paint', 11:55:04 INFO - 'tpaint', 11:55:04 INFO - 'sessionrestore', 11:55:04 INFO - 'sessionrestore_no_auto_restore', 11:55:04 INFO - 'tabpaint']}, 11:55:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['tsvgx', 11:55:04 INFO - 'tsvgr_opacity', 11:55:04 INFO - 'tart', 11:55:04 INFO - 'tscrollx', 11:55:04 INFO - 'cart']}, 11:55:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:55:04 INFO - 'tsvgr_opacity', 11:55:04 INFO - 'tart', 11:55:04 INFO - 'tscrollx', 11:55:04 INFO - 'cart']}, 11:55:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'talos_options': ['--disable-e10s'], 11:55:04 INFO - 'tests': ['tp5o']}, 11:55:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'tests': ['tp5o']}, 11:55:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'talos_options': ['--disable-e10s', 11:55:04 INFO - '--xperf_path', 11:55:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:55:04 INFO - 'tests': ['tp5n']}, 11:55:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:55:04 INFO - 'talos_options': ['--xperf_path', 11:55:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:55:04 INFO - 'tests': ['tp5n']}}, 11:55:04 INFO - 'talos.zip': {'path': '', 11:55:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:55:04 INFO - Running post-action listener: _resource_record_post_action 11:55:04 INFO - ##### 11:55:04 INFO - ##### Finished populate-webroot step (success) 11:55:04 INFO - ##### 11:55:04 INFO - ##### 11:55:04 INFO - ##### Running create-virtualenv step. 11:55:04 INFO - ##### 11:55:04 INFO - Running pre-action listener: _resource_record_pre_action 11:55:04 INFO - Running main action method: create_virtualenv 11:55:04 INFO - Creating virtualenv /builds/slave/test/build/venv 11:55:04 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 11:55:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:55:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:55:04 INFO - Using real prefix '/usr' 11:55:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:55:05 INFO - Installing distribute.............................................................................................................................................................................................done. 11:55:07 INFO - Installing pip.................done. 11:55:07 INFO - Return code: 0 11:55:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:55:07 INFO - Using env: {'DISPLAY': ':0', 11:55:07 INFO - 'HOME': '/home/cltbld', 11:55:07 INFO - 'LANG': 'en_US.UTF-8', 11:55:07 INFO - 'LANGUAGE': 'en_US:en', 11:55:07 INFO - 'LOGNAME': 'cltbld', 11:55:07 INFO - 'MAIL': '/var/mail/cltbld', 11:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:07 INFO - 'MOZ_NO_REMOTE': '1', 11:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:07 INFO - 'NO_EM_RESTART': '1', 11:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:07 INFO - 'PWD': '/builds/slave/test', 11:55:07 INFO - 'SHELL': '/bin/bash', 11:55:07 INFO - 'SHLVL': '1', 11:55:07 INFO - 'TERM': 'linux', 11:55:07 INFO - 'TMOUT': '86400', 11:55:07 INFO - 'USER': 'cltbld', 11:55:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:07 INFO - '_': '/tools/buildbot/bin/python'} 11:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:07 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 11:55:07 INFO - Cleaning up... 11:55:07 INFO - Return code: 0 11:55:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:55:07 INFO - Using env: {'DISPLAY': ':0', 11:55:07 INFO - 'HOME': '/home/cltbld', 11:55:07 INFO - 'LANG': 'en_US.UTF-8', 11:55:07 INFO - 'LANGUAGE': 'en_US:en', 11:55:07 INFO - 'LOGNAME': 'cltbld', 11:55:07 INFO - 'MAIL': '/var/mail/cltbld', 11:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:07 INFO - 'MOZ_NO_REMOTE': '1', 11:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:07 INFO - 'NO_EM_RESTART': '1', 11:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:07 INFO - 'PWD': '/builds/slave/test', 11:55:07 INFO - 'SHELL': '/bin/bash', 11:55:07 INFO - 'SHLVL': '1', 11:55:07 INFO - 'TERM': 'linux', 11:55:07 INFO - 'TMOUT': '86400', 11:55:07 INFO - 'USER': 'cltbld', 11:55:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:07 INFO - '_': '/tools/buildbot/bin/python'} 11:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:07 INFO - Downloading/unpacking psutil>=3.1.1 11:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:55:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:55:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:55:09 INFO - Installing collected packages: psutil 11:55:09 INFO - Running setup.py install for psutil 11:55:09 INFO - building 'psutil._psutil_linux' extension 11:55:09 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 11:55:09 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 11:55:09 INFO - building 'psutil._psutil_posix' extension 11:55:09 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 11:55:10 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 11:55:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:55:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:55:10 INFO - Successfully installed psutil 11:55:10 INFO - Cleaning up... 11:55:10 INFO - Return code: 0 11:55:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:55: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:10 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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:10 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 11:55:10 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 11:55:10 INFO - Using env: {'DISPLAY': ':0', 11:55:10 INFO - 'HOME': '/home/cltbld', 11:55:10 INFO - 'LANG': 'en_US.UTF-8', 11:55:10 INFO - 'LANGUAGE': 'en_US:en', 11:55:10 INFO - 'LOGNAME': 'cltbld', 11:55:10 INFO - 'MAIL': '/var/mail/cltbld', 11:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:10 INFO - 'MOZ_NO_REMOTE': '1', 11:55:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:10 INFO - 'NO_EM_RESTART': '1', 11:55:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:10 INFO - 'PWD': '/builds/slave/test', 11:55:10 INFO - 'SHELL': '/bin/bash', 11:55:10 INFO - 'SHLVL': '1', 11:55:10 INFO - 'TERM': 'linux', 11:55:10 INFO - 'TMOUT': '86400', 11:55:10 INFO - 'USER': 'cltbld', 11:55:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:10 INFO - '_': '/tools/buildbot/bin/python'} 11:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:55:10 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 11:55:10 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 11:55:10 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 11:55:10 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 11:55:10 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 11:55:10 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 11:55:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:55:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:55:12 INFO - Installing collected packages: mozsystemmonitor 11:55:12 INFO - Running setup.py install for mozsystemmonitor 11:55:12 INFO - Successfully installed mozsystemmonitor 11:55:12 INFO - Cleaning up... 11:55:12 INFO - Return code: 0 11:55:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:55:12 INFO - Using env: {'DISPLAY': ':0', 11:55:12 INFO - 'HOME': '/home/cltbld', 11:55:12 INFO - 'LANG': 'en_US.UTF-8', 11:55:12 INFO - 'LANGUAGE': 'en_US:en', 11:55:12 INFO - 'LOGNAME': 'cltbld', 11:55:12 INFO - 'MAIL': '/var/mail/cltbld', 11:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:12 INFO - 'MOZ_NO_REMOTE': '1', 11:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:12 INFO - 'NO_EM_RESTART': '1', 11:55:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:12 INFO - 'PWD': '/builds/slave/test', 11:55:12 INFO - 'SHELL': '/bin/bash', 11:55:12 INFO - 'SHLVL': '1', 11:55:12 INFO - 'TERM': 'linux', 11:55:12 INFO - 'TMOUT': '86400', 11:55:12 INFO - 'USER': 'cltbld', 11:55:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:12 INFO - '_': '/tools/buildbot/bin/python'} 11:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:12 INFO - Downloading/unpacking jsonschema==2.5.1 11:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:55:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:14 INFO - Downloading functools32-3.2.3-2.tar.gz 11:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:55:14 INFO - warning: no files found matching '*.txt' 11:55:14 INFO - no previously-included directories found matching 'build' 11:55:14 INFO - no previously-included directories found matching 'dist' 11:55:14 INFO - no previously-included directories found matching '.git*' 11:55:14 INFO - Installing collected packages: jsonschema, functools32 11:55:14 INFO - Running setup.py install for functools32 11:55:15 INFO - warning: no files found matching '*.txt' 11:55:15 INFO - no previously-included directories found matching 'build' 11:55:15 INFO - no previously-included directories found matching 'dist' 11:55:15 INFO - no previously-included directories found matching '.git*' 11:55:15 INFO - Successfully installed jsonschema functools32 11:55:15 INFO - Cleaning up... 11:55:15 INFO - Return code: 0 11:55:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:15 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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:15 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 11:55:15 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 11:55:15 INFO - Using env: {'DISPLAY': ':0', 11:55:15 INFO - 'HOME': '/home/cltbld', 11:55:15 INFO - 'LANG': 'en_US.UTF-8', 11:55:15 INFO - 'LANGUAGE': 'en_US:en', 11:55:15 INFO - 'LOGNAME': 'cltbld', 11:55:15 INFO - 'MAIL': '/var/mail/cltbld', 11:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:15 INFO - 'MOZ_NO_REMOTE': '1', 11:55:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:15 INFO - 'NO_EM_RESTART': '1', 11:55:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:15 INFO - 'PWD': '/builds/slave/test', 11:55:15 INFO - 'SHELL': '/bin/bash', 11:55:15 INFO - 'SHLVL': '1', 11:55:15 INFO - 'TERM': 'linux', 11:55:15 INFO - 'TMOUT': '86400', 11:55:15 INFO - 'USER': 'cltbld', 11:55:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:15 INFO - '_': '/tools/buildbot/bin/python'} 11:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:15 INFO - Downloading/unpacking blobuploader==1.2.4 11:55:15 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 11:55:15 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 11:55:15 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 11:55:15 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 11:55:15 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 11:55:15 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 11:55:17 INFO - Downloading blobuploader-1.2.4.tar.gz 11:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:55:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:55:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 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 11:55:17 INFO - Downloading docopt-0.6.1.tar.gz 11:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:55:17 INFO - Installing collected packages: blobuploader, requests, docopt 11:55:17 INFO - Running setup.py install for blobuploader 11:55:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:55:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:55:18 INFO - Running setup.py install for requests 11:55:18 INFO - Running setup.py install for docopt 11:55:18 INFO - Successfully installed blobuploader requests docopt 11:55:18 INFO - Cleaning up... 11:55:18 INFO - Return code: 0 11:55:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:18 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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:18 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 11:55:18 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 11:55:18 INFO - Using env: {'DISPLAY': ':0', 11:55:18 INFO - 'HOME': '/home/cltbld', 11:55:18 INFO - 'LANG': 'en_US.UTF-8', 11:55:18 INFO - 'LANGUAGE': 'en_US:en', 11:55:18 INFO - 'LOGNAME': 'cltbld', 11:55:18 INFO - 'MAIL': '/var/mail/cltbld', 11:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:18 INFO - 'MOZ_NO_REMOTE': '1', 11:55:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:18 INFO - 'NO_EM_RESTART': '1', 11:55:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:18 INFO - 'PWD': '/builds/slave/test', 11:55:18 INFO - 'SHELL': '/bin/bash', 11:55:18 INFO - 'SHLVL': '1', 11:55:18 INFO - 'TERM': 'linux', 11:55:18 INFO - 'TMOUT': '86400', 11:55:18 INFO - 'USER': 'cltbld', 11:55:18 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:18 INFO - '_': '/tools/buildbot/bin/python'} 11:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:55:18 INFO - Running setup.py (path:/tmp/pip-XpRGJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:55:18 INFO - Running setup.py (path:/tmp/pip-zMlhdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:55:18 INFO - Running setup.py (path:/tmp/pip-XBSVw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:55:18 INFO - Running setup.py (path:/tmp/pip-m8bAmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:55:19 INFO - Running setup.py (path:/tmp/pip-W8nLBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:55:19 INFO - Running setup.py (path:/tmp/pip-99h_oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:55:19 INFO - Running setup.py (path:/tmp/pip-n5ojhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:55:19 INFO - Running setup.py (path:/tmp/pip-qtCzC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:55:19 INFO - Running setup.py (path:/tmp/pip-S0LqbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:55:19 INFO - Running setup.py (path:/tmp/pip-baWRMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:55:19 INFO - Running setup.py (path:/tmp/pip-mNjHA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:55:19 INFO - Running setup.py (path:/tmp/pip-0pqJ9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:55:19 INFO - Running setup.py (path:/tmp/pip-RCdkTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:55:19 INFO - Running setup.py (path:/tmp/pip-rj7pZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:55:19 INFO - Running setup.py (path:/tmp/pip-Z_G3kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:55:19 INFO - Running setup.py (path:/tmp/pip-5vKobr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:55:19 INFO - Running setup.py (path:/tmp/pip-aaxe9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:55:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:55:20 INFO - Running setup.py install for manifestparser 11:55:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Running setup.py install for mozcrash 11:55:20 INFO - Running setup.py install for mozdebug 11:55:20 INFO - Running setup.py install for mozdevice 11:55:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Running setup.py install for mozfile 11:55:20 INFO - Running setup.py install for mozhttpd 11:55:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Running setup.py install for mozinfo 11:55:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Running setup.py install for mozInstall 11:55:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:55:20 INFO - Running setup.py install for mozleak 11:55:20 INFO - Running setup.py install for mozlog 11:55:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Running setup.py install for moznetwork 11:55:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Running setup.py install for mozprocess 11:55:21 INFO - Running setup.py install for mozprofile 11:55:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Running setup.py install for mozrunner 11:55:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Running setup.py install for mozscreenshot 11:55:21 INFO - Running setup.py install for moztest 11:55:21 INFO - Running setup.py install for mozversion 11:55:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:55:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:55:21 INFO - Cleaning up... 11:55:21 INFO - Return code: 0 11:55:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:21 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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:21 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 11:55:21 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 11:55:21 INFO - Using env: {'DISPLAY': ':0', 11:55:21 INFO - 'HOME': '/home/cltbld', 11:55:21 INFO - 'LANG': 'en_US.UTF-8', 11:55:21 INFO - 'LANGUAGE': 'en_US:en', 11:55:21 INFO - 'LOGNAME': 'cltbld', 11:55:21 INFO - 'MAIL': '/var/mail/cltbld', 11:55:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:21 INFO - 'MOZ_NO_REMOTE': '1', 11:55:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:21 INFO - 'NO_EM_RESTART': '1', 11:55:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:21 INFO - 'PWD': '/builds/slave/test', 11:55:21 INFO - 'SHELL': '/bin/bash', 11:55:21 INFO - 'SHLVL': '1', 11:55:21 INFO - 'TERM': 'linux', 11:55:21 INFO - 'TMOUT': '86400', 11:55:21 INFO - 'USER': 'cltbld', 11:55:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:21 INFO - '_': '/tools/buildbot/bin/python'} 11:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:55:22 INFO - Running setup.py (path:/tmp/pip-3rjn2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:55:22 INFO - Running setup.py (path:/tmp/pip-aJLEKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:55:22 INFO - Running setup.py (path:/tmp/pip-qjJ9YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:55:22 INFO - Running setup.py (path:/tmp/pip-3AH5QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:55:22 INFO - Running setup.py (path:/tmp/pip-RTCBJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:55:22 INFO - Running setup.py (path:/tmp/pip-ND5oHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:55:22 INFO - Running setup.py (path:/tmp/pip-jqimIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:55:22 INFO - Running setup.py (path:/tmp/pip-slDSL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:55:22 INFO - Running setup.py (path:/tmp/pip-7Hyqld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:55:22 INFO - Running setup.py (path:/tmp/pip-1KLM54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:55:22 INFO - Running setup.py (path:/tmp/pip-k5cXXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:55:22 INFO - Running setup.py (path:/tmp/pip-vUx9Oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:55:22 INFO - Running setup.py (path:/tmp/pip-_jkEK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:55:22 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)) 11:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:55:22 INFO - Running setup.py (path:/tmp/pip-Dcu30f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:55:23 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)) 11:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:55:23 INFO - Running setup.py (path:/tmp/pip-CUu0lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:55:23 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)) 11:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:55:23 INFO - Running setup.py (path:/tmp/pip-bJJq5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:55:23 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)) 11:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:55:23 INFO - Running setup.py (path:/tmp/pip-ex3QJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:55:23 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)) 11:55:23 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)) 11:55:23 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)) 11:55:23 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)) 11:55:23 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)) 11:55:23 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)) 11:55:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:55:25 INFO - Downloading blessings-1.6.tar.gz 11:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:55:25 INFO - Installing collected packages: blessings 11:55:25 INFO - Running setup.py install for blessings 11:55:25 INFO - Successfully installed blessings 11:55:25 INFO - Cleaning up... 11:55:25 INFO - Return code: 0 11:55:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:55:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:55:25 INFO - Reading from file tmpfile_stdout 11:55:25 INFO - Current package versions: 11:55:25 INFO - argparse == 1.2.1 11:55:25 INFO - blessings == 1.6 11:55:25 INFO - blobuploader == 1.2.4 11:55:25 INFO - docopt == 0.6.1 11:55:25 INFO - functools32 == 3.2.3-2 11:55:25 INFO - jsonschema == 2.5.1 11:55:25 INFO - manifestparser == 1.1 11:55:25 INFO - mozInstall == 1.12 11:55:25 INFO - mozcrash == 0.17 11:55:25 INFO - mozdebug == 0.1 11:55:25 INFO - mozdevice == 0.48 11:55:25 INFO - mozfile == 1.2 11:55:25 INFO - mozhttpd == 0.7 11:55:25 INFO - mozinfo == 0.9 11:55:25 INFO - mozleak == 0.1 11:55:25 INFO - mozlog == 3.2 11:55:25 INFO - moznetwork == 0.27 11:55:25 INFO - mozprocess == 0.23 11:55:25 INFO - mozprofile == 0.28 11:55:25 INFO - mozrunner == 6.12 11:55:25 INFO - mozscreenshot == 0.1 11:55:25 INFO - mozsystemmonitor == 0.3 11:55:25 INFO - moztest == 0.7 11:55:25 INFO - mozversion == 1.4 11:55:25 INFO - psutil == 3.1.1 11:55:25 INFO - requests == 1.2.3 11:55:25 INFO - wsgiref == 0.1.2 11:55:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:25 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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:25 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 11:55:25 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 11:55:25 INFO - Using env: {'DISPLAY': ':0', 11:55:25 INFO - 'HOME': '/home/cltbld', 11:55:25 INFO - 'LANG': 'en_US.UTF-8', 11:55:25 INFO - 'LANGUAGE': 'en_US:en', 11:55:25 INFO - 'LOGNAME': 'cltbld', 11:55:25 INFO - 'MAIL': '/var/mail/cltbld', 11:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:25 INFO - 'MOZ_NO_REMOTE': '1', 11:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:25 INFO - 'NO_EM_RESTART': '1', 11:55:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:25 INFO - 'PWD': '/builds/slave/test', 11:55:25 INFO - 'SHELL': '/bin/bash', 11:55:25 INFO - 'SHLVL': '1', 11:55:25 INFO - 'TERM': 'linux', 11:55:25 INFO - 'TMOUT': '86400', 11:55:25 INFO - 'USER': 'cltbld', 11:55:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:25 INFO - '_': '/tools/buildbot/bin/python'} 11:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 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)) 11:55:25 INFO - Cleaning up... 11:55:25 INFO - Return code: 0 11:55:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:25 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 0x185d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, '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': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', '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 11:55:25 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 11:55:25 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 11:55:25 INFO - Using env: {'DISPLAY': ':0', 11:55:25 INFO - 'HOME': '/home/cltbld', 11:55:25 INFO - 'LANG': 'en_US.UTF-8', 11:55:25 INFO - 'LANGUAGE': 'en_US:en', 11:55:25 INFO - 'LOGNAME': 'cltbld', 11:55:25 INFO - 'MAIL': '/var/mail/cltbld', 11:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:25 INFO - 'MOZ_NO_REMOTE': '1', 11:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:25 INFO - 'NO_EM_RESTART': '1', 11:55:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:25 INFO - 'PWD': '/builds/slave/test', 11:55:25 INFO - 'SHELL': '/bin/bash', 11:55:25 INFO - 'SHLVL': '1', 11:55:25 INFO - 'TERM': 'linux', 11:55:25 INFO - 'TMOUT': '86400', 11:55:25 INFO - 'USER': 'cltbld', 11:55:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:25 INFO - '_': '/tools/buildbot/bin/python'} 11:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:55:26 INFO - Cleaning up... 11:55:26 INFO - Return code: 0 11:55:26 INFO - Running post-action listener: _resource_record_post_action 11:55:26 INFO - Running post-action listener: _start_resource_monitoring 11:55:26 INFO - Starting resource monitoring. 11:55:26 INFO - ##### 11:55:26 INFO - ##### Finished create-virtualenv step (success) 11:55:26 INFO - ##### 11:55:26 INFO - ##### 11:55:26 INFO - ##### Running install step. 11:55:26 INFO - ##### 11:55:26 INFO - Running pre-action listener: _resource_record_pre_action 11:55:26 INFO - Running main action method: install 11:55:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:55:26 INFO - Reading from file tmpfile_stdout 11:55:26 INFO - Detecting whether we're running mozinstall >=1.0... 11:55:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:55:26 INFO - Reading from file tmpfile_stdout 11:55:26 INFO - Output received: 11:55:26 INFO - Usage: mozinstall [options] installer 11:55:26 INFO - Options: 11:55:26 INFO - -h, --help show this help message and exit 11:55:26 INFO - -d DEST, --destination=DEST 11:55:26 INFO - Directory to install application into. [default: 11:55:26 INFO - "/builds/slave/test"] 11:55:26 INFO - --app=APP Application being installed. [default: firefox] 11:55:26 INFO - mkdir: /builds/slave/test/build/application 11:55:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:55:43 INFO - Reading from file tmpfile_stdout 11:55:43 INFO - Output received: 11:55:43 INFO - /builds/slave/test/build/application/firefox/firefox 11:55:43 INFO - Running post-action listener: _resource_record_post_action 11:55:43 INFO - ##### 11:55:43 INFO - ##### Finished install step (success) 11:55:43 INFO - ##### 11:55:43 INFO - ##### 11:55:43 INFO - ##### Running run-tests step. 11:55:43 INFO - ##### 11:55:43 INFO - Running pre-action listener: _resource_record_pre_action 11:55:43 INFO - Running main action method: run_tests 11:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:55:43 INFO - Python 2.7.3 11:55:43 INFO - Return code: 0 11:55:43 INFO - grabbing minidump binary from tooltool 11:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:43 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 0x1b1c3b0>, '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 0x1b1d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:55:43 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 11:55:43 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 11:55:43 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 11:55:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:55:43 INFO - Return code: 0 11:55:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:55:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:55:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:55:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:55:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 11:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 11:55:43 INFO - Using env: {'DISPLAY': ':0', 11:55:43 INFO - 'HOME': '/home/cltbld', 11:55:43 INFO - 'LANG': 'en_US.UTF-8', 11:55:43 INFO - 'LANGUAGE': 'en_US:en', 11:55:43 INFO - 'LOGNAME': 'cltbld', 11:55:43 INFO - 'MAIL': '/var/mail/cltbld', 11:55:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:55:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:43 INFO - 'MOZ_NO_REMOTE': '1', 11:55:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:55:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:43 INFO - 'NO_EM_RESTART': '1', 11:55:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:55:43 INFO - 'PWD': '/builds/slave/test', 11:55:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:55:43 INFO - 'SHELL': '/bin/bash', 11:55:43 INFO - 'SHLVL': '1', 11:55:43 INFO - 'TERM': 'linux', 11:55:43 INFO - 'TMOUT': '86400', 11:55:43 INFO - 'USER': 'cltbld', 11:55:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817', 11:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:43 INFO - '_': '/tools/buildbot/bin/python'} 11:55:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 11:55:43 INFO - mozversion application_buildid: 20160715064309 11:55:43 INFO - mozversion application_changeset: 5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854 11:55:43 INFO - mozversion application_display_name: Nightly 11:55:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:55:43 INFO - mozversion application_name: Firefox 11:55:43 INFO - mozversion application_remotingname: firefox 11:55:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:55:43 INFO - mozversion application_vendor: Mozilla 11:55:43 INFO - mozversion application_version: 50.0a1 11:55:43 INFO - mozversion platform_buildid: 20160715064309 11:55:43 INFO - mozversion platform_changeset: 5a9c26f8bb9d599e80c92f6a7f30ad91bd54a854 11:55:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:55:43 INFO - mozversion platform_version: 50.0a1 11:55:43 INFO - using testdate: 1468608943 11:55:43 INFO - actual date: 1468608943 11:55:43 INFO - starting webserver on 'localhost:38396' 11:55:43 INFO - SUITE-START | Running 2 tests 11:55:43 INFO - TEST-START | dromaeo_css 11:55:43 INFO - operating with platform_type : linux_ 11:55:43 INFO - Initialising browser for dromaeo_css test... 11:55:43 INFO - TEST-INFO | started process 23218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpslK5f1/profile http://localhost:38396/getInfo.html) 11:55:47 INFO - PROCESS | 23218 | __metrics Screen width/height:1600/1200 11:55:47 INFO - PROCESS | 23218 | colorDepth:24 11:55:47 INFO - PROCESS | 23218 | Browser inner width/height: 1024/697 11:55:47 INFO - PROCESS | 23218 | __metrics 11:55:48 INFO - PROCESS | 23218 | 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" 11:55:48 INFO - PROCESS | 23218 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:55:48 INFO - PROCESS | 23218 | console.error: PushService: 11:55:48 INFO - PROCESS | 23218 | stateChangeProcessEnqueue: Error transitioning state 11:55:48 INFO - PROCESS | 23218 | UnknownError 11:55:49 INFO - TEST-INFO | 23218: exit 0 11:55:49 INFO - Browser initialized. 11:55:49 INFO - Running cycle 1/1 for dromaeo_css test... 11:55:49 INFO - TEST-INFO | started process 23298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpslK5f1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:56:01 INFO - PROCESS | 23298 | RSS: Main: 157450240 11:56:01 INFO - PROCESS | 23298 | 11:56:52 INFO - PROCESS | 23298 | 1468609012626 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] 11:59:00 INFO - PROCESS | 23298 | Cycle 1(1): loaded http://localhost:38396/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38396/tests/dromaeo/cssquery-ext.html) 11:59:01 INFO - PROCESS | 23298 | RSS: Main: 291053568 11:59:01 INFO - PROCESS | 23298 | 12:02:03 INFO - PROCESS | 23298 | Cycle 1(1): loaded http://localhost:38396/tests/dromaeo/cssquery-ext.html (next: http://localhost:38396/tests/dromaeo/cssquery-jquery.html) 12:02:04 INFO - PROCESS | 23298 | RSS: Main: 239996928 12:02:04 INFO - PROCESS | 23298 | 12:05:06 INFO - PROCESS | 23298 | Cycle 1(1): loaded http://localhost:38396/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38396/tests/dromaeo/cssquery-mootools.html) 12:05:07 INFO - PROCESS | 23298 | RSS: Main: 242245632 12:05:07 INFO - PROCESS | 23298 | 12:08:09 INFO - PROCESS | 23298 | Cycle 1(1): loaded http://localhost:38396/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38396/tests/dromaeo/cssquery-prototype.html) 12:08:09 INFO - PROCESS | 23298 | RSS: Main: 241483776 12:08:09 INFO - PROCESS | 23298 | 12:11:12 INFO - PROCESS | 23298 | Cycle 1(1): loaded http://localhost:38396/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38396/tests/dromaeo/cssquery-yui.html) 12:11:13 INFO - PROCESS | 23298 | RSS: Main: 218861568 12:11:13 INFO - PROCESS | 23298 | 12:14:15 INFO - PROCESS | 23298 | Cycle 1(1): loaded http://localhost:38396/tests/dromaeo/cssquery-yui.html (next: http://localhost:38396/tests/dromaeo/cssquery-dojo.html) 12:14:16 INFO - PROCESS | 23298 | RSS: Main: 273481728 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | __start_tp_report 12:14:16 INFO - PROCESS | 23298 | _x_x_mozilla_page_load 12:14:16 INFO - PROCESS | 23298 | _x_x_mozilla_page_load_details 12:14:16 INFO - PROCESS | 23298 | |i|pagename|runs| 12:14:16 INFO - PROCESS | 23298 | |0;dojo.html;3025.2739342557784;3038.1189455058034;3059.036403532887;3069.5702601635776;3073.738732207762;5235.55497782688;5254.316938797951;5304.946950530493;5355.812546560871;5368.382635996857;5272.789088436458;5275.235090911816;5378.435264297251;5408.891822163554;5409.675419474835;4741.881452963669;4794.760261986904;4805.471398146201;4815.66290359675;5002.199648056312;4917.4344950330815;4936.062148191832;4937.530934561211;4993.550580447759;5004.224345226496;4752.5247475252545;4792.616590672756;4800.399950006232;4838.975805120987;4839.564439200489;4781.497942716034;4784.186688263004;4784.712917224978;4788.640851936118;4874.073925954093;4765.261384485376;4795.136875362434;4810.927836082462;4815.2054910940005;4888.535589119028;5207.895842083175;5237.607179461517;5249.002689488985;5354.678719276856;5361;5555.777768889239;5609.635373700697;5619.7471113799975;5627.155926611;5632.9436705632825;5610.410906854762;5616.52259557938;5621.747021384048;5670.5463562914865;5673.035583950697;5235.424103348629;5237.921431178535;5277.604179686539;5330.760115794798;5335.306410166692;5867.295924489089;5878.647281163144;5880.059190529495;5884.735186916513;5885.058390657474;5614.466625670555;5620.044592419298;5628.662280263197;5629.690367029771;5631.267935168402;5544.750486228129;5638.126090455904;5645.322561292671;5690.601657883908;5733.71331433435;5654.067078932012;5674.0637794764225;5678.261825962681;5679.914801277984;5720.885582288331;5577.888442231133;5688.260526131577;5689.6586204827845;5691;5697.003024470701;4302.65578753699;4320.978395108004;4330.155619654137;4338.739675619473;4421.646268298528;3887.028242939265;3902.687784977195;3902.746321489094;3929.7838618876235;3931.0565464288366;5373.489518495737;5415.620906536505;5427;5429.809956651498;5435.211894275308;5609.915851262234;5648.068068768689;5691.004074286983;5692.1461780733225;5701.71491425427;5347.919781203285;5348.251244825728;5352.4915133062295;5353.304070470814;5359.169328754049;4699.365585645963;4819.614430845524;4828.130936431476;4830.275458681226;4858.416989961227;5775.5668324875805;5786;5789.652620842763;5799;5824.126381042877;5642.153676948491;5644.096944488862;5646.576506761927;5652.915206271991;5653.095504719226;5733.770649174153;5814.244148260699;5819;5827.5629327801435;5851.61964472308;5637.379888212376;5654;5659.377468478382;5661.264035675501;5719.999000174869;5701.258836351248;5709.172170035405;5712.6286791358425;5714.314282286152;5714.971425142848;5425.186222066722;5573.136163894603;5581.190727344593;5584.804531841366;5629.690367029689;5577.024020796425;5590.105583106846;5593.133064375028;5597.160425935979;5627.5497960162265;5481.287432633732;5486.286782718221;5520.889582208413;5530.889382212253;5532.5020748132865;5534.501894829485;5578.30271216098;5616.241807355955;5638.23883775685;5672.801451949162;5330.413654498079;5375.81184658535;5378.462153784591;5402.918956215739;5404.243405923253 12:14:16 INFO - PROCESS | 23298 | |1;ext.html;584.9941500584996;609.6098496961944;639.9904001439979;640.6700549217154;643.1863692429081;43919.56080439195;44978.10043799122;45733.771331143296;45789.08421831561;45827;47092.293615595794;47380.52619473804;47624.28563571541;47634;47673.761631191795;8139.430239883211;9145.634174633007;9303.25573954082;10847.403392813392;11135.944320278428;10636.361818290889;11025.44872756363;11095.889041109607;11099.55601775928;11108.833367499494;11065.170112241574;11140.665780026613;11142.49858756358;11142.888571114267;11148.721281967935;11081.113510919107;11091.33451992883;11152.609658661908;11152.944235278852;11154.274972126786;11058.723531911686;11157.38634375109;11162.274452160584;11164.553417863275;11164.944175279154;42610.360844587354;42623.14753704939;42692.146157076684;42914.570854291684;42962.57037429617;3484.0593039879345;3515.1739341254784;3551.2187318789825;3582.695470884978;3602.585702644177;33631.663683363346;33745.83127084374;33917;33960.49059264113;34185.487217691756;4519.751413672247;4563.657725670555;4570.588647021784;4589.58693717567;4699.929501057487;386822;389442;391079;391164.99999999715;391655;64012.35987640157;64478.677606611665;65148.34851651423;65227.347726523076;65227.347726523076;68817.3118268821;69316;69510;69527;69572.30427695658;16625.41811036632;17036.744448833277;17098;18001.639967200845;18025.54936126581;13698.452061917611;13769.586912392644;13802.654933626538;13820.585382438543;13830.446782128602;19380.515487112934;20169.798302017087;20364.28724994618;20450.488737781674;20471.897640511703;7225.385842203366;7416.184219735825;7547.396208303323;7558.697652093854;7578;1007.1942446043137;1102.459794700591;1105.4528008635777;1118.0161457916981;1129.0290350298737;33900.49149262763;33956.660433395846;34061.31877362489;34184.48723269153;34316.65683343184;995.4674249276602;1128.3906690387264;1143.5654451308617;1172.6013155527162;1202.963911082669;17236.224369903386;17782.680529696834;17862.567956507846;17992.820071799375;18524.351647692536;547082;551550;553293;555716;556137;60553.697231514445;62103.37896620976;62188.378116219166;62191.37808621946;62195.37804621896;1278.6164150754737;1364.447398803486;1364.986350136486;1380.3650320852319;1389.7428975639539;33413.83293083519;35388.11529711778;35571.82214088913;35744.28511429809;35799;52852;53719.194212087634;53756.73121634523;53769.73115134399;53815.46184538105;424596;435419;435479;436520;437205;25296.596823302494;27989.720102799525;28447;28629.28426789347;30419.847900760353;48287.5171248283;49013;49714;50506.49493505018;53679.46320536901;43347.566524334354;43726.34410483909;44200;45051.774741126086;45359.31961020521;43280.134397312504;44077.77961110174;44190.33714494221;45329;45411.956461959955;43825.78087109544;44130.77934610307;45110.774446128875;45300.32049519194;45456.09087818291;38795.6120438792;39395;39759.204815902944;40045.79977100096;40428.36144746869;29499.672514737318;29910.401791964476;30170.09489715312;30285;30511.847440762653 12:14:16 INFO - PROCESS | 23298 | |2;jquery.html;384.4751913637885;392.17838628074173;396.68859944943216;399.6343345838557;409.6804492495854;13623.415070100004;18631.441056768286;19802.504937376572;20186.495337616587;20248.29130980416;14967.326470308835;18429.815701842977;19258.903705481454;19353.4193974181;19643.410697679057;5671.517920976722;5844.386339434362;5852.707364631751;5863.941360586404;5885.411458854123;5743.3108027036815;5817.41825817417;5820.068788993762;5820.767169313222;5823.854403639901;4243.405923170759;4275.358696195564;6300.621962682231;6345.238571371442;6451.870962580746;6196.938030619681;6391.265004524469;6425.935740642604;6439.839004024913;6446.387593178641;5669.773209071632;5680.204771332017;5697.800576979827;5700.087985922232;5711.428857114297;19587.11857966395;22624.77375226243;23112.65331020036;23485.695266198687;23776;2190.759016508183;2342.8125749939963;2365.089440565395;2377.679013333195;2388.3193289912465;10622.893771062269;11104.55581776728;11844.993175580086;13298.268595227259;15191.088534687955;1924.7112933060014;1974.9835418038165;1984.8412127029947;2003.7996200379991;2006.6989951507244;83326.58336708277;84028;85186;86243.61094595467;87254;9109.908900910907;9411.623535058658;9688.12806847387;9792.461414622265;11148.554057837595;12341.259524428562;12408.069394795246;12456;12460.314682692539;12464.750704985849;7543.1325397578885;7777.066751989797;7785.805354866174;7828.256315650005;7894.289513943772;7488.887666685004;7510.6620202091035;7607.429442791813;7645.350145237717;7693.576853273012;4096.180763847243;4120;4167.854125105607;4350.369196466495;4483.3947417098925;1948.2791367193945;1964.5383334916282;1980.0990549300034;1998.2506560039985;2044.4479990402806;928.3223247029571;1009.3741880034279;1013.7516308504464;1029.264076185516;1096.7735496941223;19412.708809367872;20002.799972000386;20020.69968950467;20263.696044559343;20599.897000514902;1042.1246153231323;1049.040128282628;1063.5639387850945;1086.7174534621056;1089.1123734156638;10594.15246780271;11066.336019838835;11195.4962026709;11200.21598488098;11200.775984480264;418899;425585;531098;558351;564974;97766.5111674437;98498;100788.49605751924;105310;106701.4664926686;463.4484962894145;479.60672248756384;481.5112660649444;486.79554587072806;487.8487668822676;11135.665930022113;11477;11624.302541847515;11659.475323610628;12010;42830.35754463748;43504;43631;45642;48665.5133448661;57315.71342143263;58694.7065264671;60802.695986519786;63504.682476587324;65942.67028664827;11929.343886086348;12717.618471445872;12806.88686366739;12880.291583962971;12962.80555791645;8584.656613735631;13183.934080329536;13283.335833208108;13421.530246441718;13482.460701571827;11437.771244574895;11693.064554835422;11748;11749.177557570887;11751.823722643996;11454.541818327174;11545.595904143314;11607.535698572301;11669.708257293636;11832.76334473323;11650.70873228176;11800.52797888075;12000.939995299968;12014.459349329476;12036.879631203927;12646.367681616066;12812.807807882702;12877.871221288042;12909.096363254483;12970;11631.243969141979;12043.879561204276;12047.638570842888;12236.327002014974;12253.877461225631 12:14:16 INFO - PROCESS | 23298 | |3;mootools.html;1422.857714228577;1490.7167638148753;1587.785648937393;1732.5322163015994;1788.7853457585093;8387.370947178963;8863.379563430562;8949.239314658262;8950.462972221663;8953.418027828187;7175.390091842187;7332.376747976415;7345.3021962913435;7548.81127971801;8745.562721863897;2251.4821591034033;2328.348062542491;2329.906803727849;2347.58917189492;2354.2466410748566;2285.405794493435;2327.173853282086;2336.3925379401394;2337.4623836517612;2346.730126035502;2014.0634604908714;2199.098369668436;2290.5189910118893;2307.4116100394376;2309.8383113182085;2293.644485104803;2317.5133222023396;2323.7095363079698;2334.8015418689433;2349.7650234976536;2279.8746068966366;2282.2582660635194;2318.698569186012;2319.1419174905222;2322.779335963081;8877.289816814638;8988.550572471415;8992.100789921022;8992.190702836711;8993.505357205351;5099.923501147486;5210.895782084375;5245.79016839326;5251.501107394792;5253.947460525423;3223.1458663454205;3275.7706960512774;3366.8316584170693;3367.9494807577908;3369.3598216339064;982.1798798003675;1007.4559737741685;1010.6412223660568;1018.241410149436;1041.1306559023149;12043;12077.516899324017;12109.757804843854;12119.636410907688;12148.088893333035;4345.326474396474;4589.862304130881;4606.355110284563;4673.088747694235;4677.812887484462;10185.083342499063;10217.13154381886;10277.280590358603;10311.793764124675;10326.48367581618;5228.189630607262;5230.372355317386;5234.476552344735;5236.790528378822;5242.554382877422;3969.583193764613;4611.377464042378;4815.590674792612;4818.855434336905;4820.975895120502;3933.3903244997073;3959.2675355059405;4031.1937612477623;4036.2936486114813;4044.2518134145275;1240.2682417373792;1252.6179515247936;1255.3095797311441;1260.710036691566;1269.320913311374;1017.4200705597739;1020.4438580973388;1022.3610243597751;1027.2295778166376;1029.0686928329874;6138.693065346715;6158.876822463526;6240.251169859646;6306.905396419057;6483;453.6234925012232;455.7015155073366;463.4994206257234;465.5949324088065;472.85341544121434;4179.832806687759;4333.804978775963;4380.408644832971;4386.385905973167;4411.250087485135;10608.15134789215;10967.725806854733;11054.170937179744;11134.053605443465;11136.164787640797;10966.122710183166;10999.175061870232;11000.339979601249;11000.944995274973;11016.614418495314;7435.107787065586;8260.463069900437;8366.916330836735;8371;8372.246497815104;6207.875842483035;6275.560710750204;6278.340774218824;6329.968350158219;6464.5151613630105;9974;10127.18982481385;10293.176545876462;10310.948445257725;10318.071373576415;10293.176545876462;10309.278350515691;10468.371897686164;10476.790464190826;10613.363198208132;6903.89644155328;7334.4132469403385;7915.44591878586;8052.516848989079;8076.313513351431;7993.520388776459;8300.667973281243;8306.04480484752;8313.875291870505;8315.875261870955;7132.821679458055;7217.819554511179;7240.167380751281;7268.27317268269;7475.177730449537;7317.560946343024;7322.926770732224;7358.521696089737;7390.408767298711;7422.777316680508;6757.45940324763;6827.351401616838;6828.863422731418;6830.624315662686;6837.213720422215;5089.567386772166;5167.974160129175;5213.635045546927;5365.570754339566;5388.515033646991;5537.390887002346;5549.278593782782;5591.664500130005;5606.495415412633;5657.0100232458335 12:14:16 INFO - PROCESS | 23298 | |4;prototype.html;315.2135422121806;317.30193574136905;319.88963807486437;320.11169006008333;322.08206611158187;5433.347998240207;5497.202905578693;5503.174523821429;5530.391656917735;5556.805511807088;5385.21914322424;5433;5461.5357694595905;5462.426445223244;5474.890502189964;4045.5145382553987;4059.451973983504;4205.915881682365;4214.325707886739;4237.427947227131;4173.6661067114555;4182.247195504808;4210.179015092058;4216.831326746927;4220.957790422087;3996.780177090259;4165.479315085614;4171.812268447927;4177.791110444481;4187.853425130121;4074.2258970795456;4164.354525048622;4166.937495937533;4202.936955945664;4221.387898754694;3676.8713095041544;3681.3557627415094;3691.741578089535;3697.8890633281044;3710.1466662667467;5185.870353241162;5319.122344813101;5319.441458646864;5323.627346085776;5327.334083239595;4745.477997420281;4761.500042495522;4767.189577771787;4777.928331075037;4791.832285869978;5544.889102217973;5571.470710282517;5573.749181286852;5587.69267690281;5675.6878371689545;3845.0002999220123;3991.2416640838437;4000.7599544027453;4037.2732908076537;4043;5857.736401861926;5862.062070068769;5868.383819698955;5871.3541510433815;5885.323187833368;4059.5534491205945;4072.714909956334;4105.8973525662095;4107.753534787922;4116.773577453209;4041.9191616167514;4043.332850079704;4104.56902025289;4161.273071675281;4165.958510372407;4705.929411058837;4707.95292047082;4718.127146477913;4730.361401210793;4736.194846876039;5632.676321064547;5699.886002279931;5728.283964504437;5745;5750;1354.3769865861616;1420.659041829974;1422.950196743109;1439.9064060836013;1440.3734375546671;883.5626364949392;893.5041052216083;903.0112027330063;929.2380248196549;953.8140062687849;5399.379071406838;5411.431799661058;5414.7021913795115;5424.783008679608;5426;5723.713814309352;5727.742251598688;5757.222774925332;5760.74076666551;5767.653940763567;5257.553107985787;5378.220158077057;5382.973085134549;5387.464572596791;5426.62013659048;3065.203047207742;3066.432709948667;3070.6929307069563;3077.4152910947296;3078.2150551609197;5808.912866307009;5876.970615146897;5941.227640406806;5962;5971.611845230046;5961.821145365646;5994.1608174855555;5996.700164991733;6010.398960103955;6011.549133814894;6025.969870150621;6039.245094363205;6052.364501727343;6127.050307202391;6131.448169664752;5644.971775141098;5859.707014649336;5863.706814659165;5882.941170588239;5892.587518873809;5793.073108302651;5805.680687562056;5807.245058142584;5811.186433899173;5815.302163740378;5739.397363276963;6003.6998150093195;6090.23872015998;6103.938960610337;6105.389461053859;4059.071940828083;4083.8355671494155;4086.85568040949;4088.34586466164;4095.078607313331;5949.720810025865;5951.583389162717;5951.821445356646;5961.612495187799;5976.46211840919;5705.572082093942;5706.714664266853;5712.942870571241;5741.196232527366;5841.357450680507;5708.714564271853;5709.20071190042;5722.313322401338;5741.684207368635;5743.310802703702;5274.340707411574;5422.213779002102;5661.773529058792;5697.658140511582;5700.572457065654;5679.148127780866;5724.713764311685;5739.368669446441;5741.8564535886935;5790.160426738184;5654.717264136859;5656.632318899258;5666.376698563073;5672.659640421422;5682.261306030189 12:14:16 INFO - PROCESS | 23298 | |5;yui.html;731.7438896386269;738.3207449146785;791.5646394483036;793.8253584211474;802.8835818806273;14956.252187390628;15847.128407937573;15878.444254451097;15893.84106158938;15902.602434939132;14274.429022839075;14304.642383940407;14320.427182912697;14356.569302920929;14365.49720759771;659.2287024181695;776.2043904997366;792.2631952284358;799.2646764976207;812.3298278919885;809.0897740042452;813.5403497024192;816.0615292413712;817.1542453560565;817.5094943034162;865.909079546648;872.6727477196051;872.7512658892217;874.1564390363299;876.2289185516736;696.4150113904319;712.707789806177;713.7644577289482;836.0552575589605;863.201538576814;625.1123404765244;628.2523796681934;628.5537268539342;628.7013668507468;628.8427893026743;15375.308111135026;15400.53798386039;15426.305816238297;15432.3827046918;15444.922775386163;14728.631784205374;14805.259737013106;14886.255687215596;14907.70184596302;14920.179390133539;13857.792133118011;13932.651683707887;14048.508302209475;14210.644733881632;14255.215963122024;4200.810963506649;4275.4228179195725;4309;4402.273624851896;4435.600795928382;74054;79458;80888;81308;82466.58766706187;11814.704632384259;11939.820902686466;12000.459979300953;12026.699332516757;12110.515579376726;21774.45563860916;21788.782112178993;21812.563748725253;21849.235276765237;21863.453413664785;8403.747887563382;8584.098669639723;8771.956140219385;8843.292536597057;8942.195202431813;4982.252662100642;5024.045431368028;5060.493950604984;5077.974610126999;5113.309703190096;3399.5070714746225;3489.354469423165;3515.5605549306338;3515.57813062429;3522.8062456565135;640.4428147511695;642.2261175283771;646.2406672160193;659.6240143118376;674.521090026087;676.6481429656551;723.489939592586;734.0823970037453;737.5095561451682;805.0540614777613;9920.454375009444;10122.54448549817;10284.691459256233;10333.431661258552;10749.19381046409;718.306833905284;729.278014765389;743.5092838726415;746.0301607909696;752.0966185497251;5042.773075211624;5051.26756620288;5067.062593420229;5076.492350764894;5113.1052065888325;64209.67895160494;65877;66413.66793166003;66606.66696666485;75061;98327;98471.50764246134;99224;100483.49758251161;101608.49195753974;58439.70780146072;85444.57277713572;87472;87721.56139219391;88474.99999999872;10222.53998570081;10285;10374.118199953089;10453.947730261605;10544;53531;54353.45646543642;54601.45398545964;54810.45189548213;55005.72497137489;68179.31820681928;68687;75752.62123689346;76898.61550692211;78723;13076.607701768962;13217.537386191438;13395.464181433023;13445.327733613476;13829.377678004716;14246.572602821932;14364.640883977983;15047.247637617857;15358.15530145808;16598.83401165973;14416.783748243995;14781.113133212042;15061.17163556015;15517.5344739658;15560;14348.784768228694;14431.206283654079;14601.26993650292;15248.923755381153;15614.687706246039;13394.866051339362;13664.248466334448;13970.371333289822;14022.088564243291;14472.204028778518;11604.535818567501;12181.817272741093;12334.691632708895;12345.135840491079;12549.247045177319;11650.184487085822;11846.289222646668;11880.584179553674;12042.939785301367;12199.69500762488 12:14:16 INFO - PROCESS | 23298 | __end_tp_report 12:14:16 INFO - PROCESS | 23298 | __start_cc_report 12:14:16 INFO - PROCESS | 23298 | _x_x_mozilla_cycle_collect,1113 12:14:16 INFO - PROCESS | 23298 | __end_cc_report 12:14:16 INFO - PROCESS | 23298 | __startTimestamp1468610056019__endTimestamp 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | ------- Summary: start ------- 12:14:16 INFO - PROCESS | 23298 | Number of tests: 6 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | [#0] dojo.html Cycles:165 Average:5273.18 Median:5486.29 stddev:596.45 (10.9%) stddev-sans-first:571.62 12:14:16 INFO - PROCESS | 23298 | Values: 3025.3 3038.1 3059.0 3069.6 3073.7 5235.6 5254.3 5304.9 5355.8 5368.4 5272.8 5275.2 5378.4 5408.9 5409.7 4741.9 4794.8 4805.5 4815.7 5002.2 4917.4 4936.1 4937.5 4993.6 5004.2 4752.5 4792.6 4800.4 4839.0 4839.6 4781.5 4784.2 4784.7 4788.6 4874.1 4765.3 4795.1 4810.9 4815.2 4888.5 5207.9 5237.6 5249.0 5354.7 5361.0 5555.8 5609.6 5619.7 5627.2 5632.9 5610.4 5616.5 5621.7 5670.5 5673.0 5235.4 5237.9 5277.6 5330.8 5335.3 5867.3 5878.6 5880.1 5884.7 5885.1 5614.5 5620.0 5628.7 5629.7 5631.3 5544.8 5638.1 5645.3 5690.6 5733.7 5654.1 5674.1 5678.3 5679.9 5720.9 5577.9 5688.3 5689.7 5691.0 5697.0 4302.7 4321.0 4330.2 4338.7 4421.6 3887.0 3902.7 3902.7 3929.8 3931.1 5373.5 5415.6 5427.0 5429.8 5435.2 5609.9 5648.1 5691.0 5692.1 5701.7 5347.9 5348.3 5352.5 5353.3 5359.2 4699.4 4819.6 4828.1 4830.3 4858.4 5775.6 5786.0 5789.7 5799.0 5824.1 5642.2 5644.1 5646.6 5652.9 5653.1 5733.8 5814.2 5819.0 5827.6 5851.6 5637.4 5654.0 5659.4 5661.3 5720.0 5701.3 5709.2 5712.6 5714.3 5715.0 5425.2 5573.1 5581.2 5584.8 5629.7 5577.0 5590.1 5593.1 5597.2 5627.5 5481.3 5486.3 5520.9 5530.9 5532.5 5534.5 5578.3 5616.2 5638.2 5672.8 5330.4 5375.8 5378.5 5402.9 5404.2 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | [#1] ext.html Cycles:180 Average:63602.36 Median:391410.00 stddev:122739.91 (31.4%) stddev-sans-first:122993.03 12:14:16 INFO - PROCESS | 23298 | Values: 585.0 609.6 640.0 640.7 643.2 43919.6 44978.1 45733.8 45789.1 45827.0 47092.3 47380.5 47624.3 47634.0 47673.8 8139.4 9145.6 9303.3 10847.4 11135.9 10636.4 11025.4 11095.9 11099.6 11108.8 11065.2 11140.7 11142.5 11142.9 11148.7 11081.1 11091.3 11152.6 11152.9 11154.3 11058.7 11157.4 11162.3 11164.6 11164.9 42610.4 42623.1 42692.1 42914.6 42962.6 3484.1 3515.2 3551.2 3582.7 3602.6 33631.7 33745.8 33917.0 33960.5 34185.5 4519.8 4563.7 4570.6 4589.6 4699.9 386822.0 389442.0 391079.0 391165.0 391655.0 64012.4 64478.7 65148.3 65227.3 65227.3 68817.3 69316.0 69510.0 69527.0 69572.3 16625.4 17036.7 17098.0 18001.6 18025.5 13698.5 13769.6 13802.7 13820.6 13830.4 19380.5 20169.8 20364.3 20450.5 20471.9 7225.4 7416.2 7547.4 7558.7 7578.0 1007.2 1102.5 1105.5 1118.0 1129.0 33900.5 33956.7 34061.3 34184.5 34316.7 995.5 1128.4 1143.6 1172.6 1203.0 17236.2 17782.7 17862.6 17992.8 18524.4 547082.0 551550.0 553293.0 555716.0 556137.0 60553.7 62103.4 62188.4 62191.4 62195.4 1278.6 1364.4 1365.0 1380.4 1389.7 33413.8 35388.1 35571.8 35744.3 35799.0 52852.0 53719.2 53756.7 53769.7 53815.5 424596.0 435419.0 435479.0 436520.0 437205.0 25296.6 27989.7 28447.0 28629.3 30419.8 48287.5 49013.0 49714.0 50506.5 53679.5 43347.6 43726.3 44200.0 45051.8 45359.3 43280.1 44077.8 44190.3 45329.0 45412.0 43825.8 44130.8 45110.8 45300.3 45456.1 38795.6 39395.0 39759.2 40045.8 40428.4 29499.7 29910.4 30170.1 30285.0 30511.8 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | [#2] jquery.html Cycles:180 Average:29800.85 Median:11154.07 stddev:83398.49 (747.7%) stddev-sans-first:83603.20 12:14:16 INFO - PROCESS | 23298 | Values: 384.5 392.2 396.7 399.6 409.7 13623.4 18631.4 19802.5 20186.5 20248.3 14967.3 18429.8 19258.9 19353.4 19643.4 5671.5 5844.4 5852.7 5863.9 5885.4 5743.3 5817.4 5820.1 5820.8 5823.9 4243.4 4275.4 6300.6 6345.2 6451.9 6196.9 6391.3 6425.9 6439.8 6446.4 5669.8 5680.2 5697.8 5700.1 5711.4 19587.1 22624.8 23112.7 23485.7 23776.0 2190.8 2342.8 2365.1 2377.7 2388.3 10622.9 11104.6 11845.0 13298.3 15191.1 1924.7 1975.0 1984.8 2003.8 2006.7 83326.6 84028.0 85186.0 86243.6 87254.0 9109.9 9411.6 9688.1 9792.5 11148.6 12341.3 12408.1 12456.0 12460.3 12464.8 7543.1 7777.1 7785.8 7828.3 7894.3 7488.9 7510.7 7607.4 7645.4 7693.6 4096.2 4120.0 4167.9 4350.4 4483.4 1948.3 1964.5 1980.1 1998.3 2044.4 928.3 1009.4 1013.8 1029.3 1096.8 19412.7 20002.8 20020.7 20263.7 20599.9 1042.1 1049.0 1063.6 1086.7 1089.1 10594.2 11066.3 11195.5 11200.2 11200.8 418899.0 425585.0 531098.0 558351.0 564974.0 97766.5 98498.0 100788.5 105310.0 106701.5 463.4 479.6 481.5 486.8 487.8 11135.7 11477.0 11624.3 11659.5 12010.0 42830.4 43504.0 43631.0 45642.0 48665.5 57315.7 58694.7 60802.7 63504.7 65942.7 11929.3 12717.6 12806.9 12880.3 12962.8 8584.7 13183.9 13283.3 13421.5 13482.5 11437.8 11693.1 11748.0 11749.2 11751.8 11454.5 11545.6 11607.5 11669.7 11832.8 11650.7 11800.5 12000.9 12014.5 12036.9 12646.4 12812.8 12877.9 12909.1 12970.0 11631.2 12043.9 12047.6 12236.3 12253.9 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | [#3] mootools.html Cycles:180 Average:5762.97 Median:454.66 stddev:3292.56 (724.2%) stddev-sans-first:3285.64 12:14:16 INFO - PROCESS | 23298 | Values: 1422.9 1490.7 1587.8 1732.5 1788.8 8387.4 8863.4 8949.2 8950.5 8953.4 7175.4 7332.4 7345.3 7548.8 8745.6 2251.5 2328.3 2329.9 2347.6 2354.2 2285.4 2327.2 2336.4 2337.5 2346.7 2014.1 2199.1 2290.5 2307.4 2309.8 2293.6 2317.5 2323.7 2334.8 2349.8 2279.9 2282.3 2318.7 2319.1 2322.8 8877.3 8988.6 8992.1 8992.2 8993.5 5099.9 5210.9 5245.8 5251.5 5253.9 3223.1 3275.8 3366.8 3367.9 3369.4 982.2 1007.5 1010.6 1018.2 1041.1 12043.0 12077.5 12109.8 12119.6 12148.1 4345.3 4589.9 4606.4 4673.1 4677.8 10185.1 10217.1 10277.3 10311.8 10326.5 5228.2 5230.4 5234.5 5236.8 5242.6 3969.6 4611.4 4815.6 4818.9 4821.0 3933.4 3959.3 4031.2 4036.3 4044.3 1240.3 1252.6 1255.3 1260.7 1269.3 1017.4 1020.4 1022.4 1027.2 1029.1 6138.7 6158.9 6240.3 6306.9 6483.0 453.6 455.7 463.5 465.6 472.9 4179.8 4333.8 4380.4 4386.4 4411.3 10608.2 10967.7 11054.2 11134.1 11136.2 10966.1 10999.2 11000.3 11000.9 11016.6 7435.1 8260.5 8366.9 8371.0 8372.2 6207.9 6275.6 6278.3 6330.0 6464.5 9974.0 10127.2 10293.2 10310.9 10318.1 10293.2 10309.3 10468.4 10476.8 10613.4 6903.9 7334.4 7915.4 8052.5 8076.3 7993.5 8300.7 8306.0 8313.9 8315.9 7132.8 7217.8 7240.2 7268.3 7475.2 7317.6 7322.9 7358.5 7390.4 7422.8 6757.5 6827.4 6828.9 6830.6 6837.2 5089.6 5168.0 5213.6 5365.6 5388.5 5537.4 5549.3 5591.7 5606.5 5657.0 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | [#4] prototype.html Cycles:180 Average:4771.30 Median:5461.98 stddev:1429.70 (26.2%) stddev-sans-first:1394.04 12:14:16 INFO - PROCESS | 23298 | Values: 315.2 317.3 319.9 320.1 322.1 5433.3 5497.2 5503.2 5530.4 5556.8 5385.2 5433.0 5461.5 5462.4 5474.9 4045.5 4059.5 4205.9 4214.3 4237.4 4173.7 4182.2 4210.2 4216.8 4221.0 3996.8 4165.5 4171.8 4177.8 4187.9 4074.2 4164.4 4166.9 4202.9 4221.4 3676.9 3681.4 3691.7 3697.9 3710.1 5185.9 5319.1 5319.4 5323.6 5327.3 4745.5 4761.5 4767.2 4777.9 4791.8 5544.9 5571.5 5573.7 5587.7 5675.7 3845.0 3991.2 4000.8 4037.3 4043.0 5857.7 5862.1 5868.4 5871.4 5885.3 4059.6 4072.7 4105.9 4107.8 4116.8 4041.9 4043.3 4104.6 4161.3 4166.0 4705.9 4708.0 4718.1 4730.4 4736.2 5632.7 5699.9 5728.3 5745.0 5750.0 1354.4 1420.7 1423.0 1439.9 1440.4 883.6 893.5 903.0 929.2 953.8 5399.4 5411.4 5414.7 5424.8 5426.0 5723.7 5727.7 5757.2 5760.7 5767.7 5257.6 5378.2 5383.0 5387.5 5426.6 3065.2 3066.4 3070.7 3077.4 3078.2 5808.9 5877.0 5941.2 5962.0 5971.6 5961.8 5994.2 5996.7 6010.4 6011.5 6026.0 6039.2 6052.4 6127.1 6131.4 5645.0 5859.7 5863.7 5882.9 5892.6 5793.1 5805.7 5807.2 5811.2 5815.3 5739.4 6003.7 6090.2 6103.9 6105.4 4059.1 4083.8 4086.9 4088.3 4095.1 5949.7 5951.6 5951.8 5961.6 5976.5 5705.6 5706.7 5712.9 5741.2 5841.4 5708.7 5709.2 5722.3 5741.7 5743.3 5274.3 5422.2 5661.8 5697.7 5700.6 5679.1 5724.7 5739.4 5741.9 5790.2 5654.7 5656.6 5666.4 5672.7 5682.3 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - PROCESS | 23298 | [#5] yui.html Cycles:180 Average:19865.66 Median:4708.93 stddev:26579.10 (564.4%) stddev-sans-first:26614.82 12:14:16 INFO - PROCESS | 23298 | Values: 731.7 738.3 791.6 793.8 802.9 14956.3 15847.1 15878.4 15893.8 15902.6 14274.4 14304.6 14320.4 14356.6 14365.5 659.2 776.2 792.3 799.3 812.3 809.1 813.5 816.1 817.2 817.5 865.9 872.7 872.8 874.2 876.2 696.4 712.7 713.8 836.1 863.2 625.1 628.3 628.6 628.7 628.8 15375.3 15400.5 15426.3 15432.4 15444.9 14728.6 14805.3 14886.3 14907.7 14920.2 13857.8 13932.7 14048.5 14210.6 14255.2 4200.8 4275.4 4309.0 4402.3 4435.6 74054.0 79458.0 80888.0 81308.0 82466.6 11814.7 11939.8 12000.5 12026.7 12110.5 21774.5 21788.8 21812.6 21849.2 21863.5 8403.7 8584.1 8772.0 8843.3 8942.2 4982.3 5024.0 5060.5 5078.0 5113.3 3399.5 3489.4 3515.6 3515.6 3522.8 640.4 642.2 646.2 659.6 674.5 676.6 723.5 734.1 737.5 805.1 9920.5 10122.5 10284.7 10333.4 10749.2 718.3 729.3 743.5 746.0 752.1 5042.8 5051.3 5067.1 5076.5 5113.1 64209.7 65877.0 66413.7 66606.7 75061.0 98327.0 98471.5 99224.0 100483.5 101608.5 58439.7 85444.6 87472.0 87721.6 88475.0 10222.5 10285.0 10374.1 10453.9 10544.0 53531.0 54353.5 54601.5 54810.5 55005.7 68179.3 68687.0 75752.6 76898.6 78723.0 13076.6 13217.5 13395.5 13445.3 13829.4 14246.6 14364.6 15047.2 15358.2 16598.8 14416.8 14781.1 15061.2 15517.5 15560.0 14348.8 14431.2 14601.3 15248.9 15614.7 13394.9 13664.2 13970.4 14022.1 14472.2 11604.5 12181.8 12334.7 12345.1 12549.2 11650.2 11846.3 11880.6 12042.9 12199.7 12:14:16 INFO - PROCESS | 23298 | -------- Summary: end -------- 12:14:16 INFO - PROCESS | 23298 | 12:14:16 INFO - TEST-INFO | 23298: exit 0 12:14:16 INFO - TEST-OK | dromaeo_css | took 1112716ms 12:14:16 INFO - TEST-START | kraken 12:14:16 INFO - operating with platform_type : linux_ 12:14:16 INFO - Initialising browser for kraken test... 12:14:16 INFO - TEST-INFO | started process 23488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsaMIxR/profile http://localhost:38396/getInfo.html) 12:14:20 INFO - PROCESS | 23488 | __metrics Screen width/height:1600/1200 12:14:20 INFO - PROCESS | 23488 | colorDepth:24 12:14:20 INFO - PROCESS | 23488 | Browser inner width/height: 1024/697 12:14:20 INFO - PROCESS | 23488 | __metrics 12:14:21 INFO - PROCESS | 23488 | 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" 12:14:21 INFO - PROCESS | 23488 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:22 INFO - TEST-INFO | 23488: exit 0 12:14:22 INFO - Browser initialized. 12:14:22 INFO - Running cycle 1/1 for kraken test... 12:14:22 INFO - TEST-INFO | started process 23568 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsaMIxR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:33 INFO - PROCESS | 23568 | RSS: Main: 157839360 12:14:33 INFO - PROCESS | 23568 | 12:14:36 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38396/tests/kraken/driver.html?testName=audio-beat-detection) 12:14:37 INFO - PROCESS | 23568 | RSS: Main: 216502272 12:14:37 INFO - PROCESS | 23568 | 12:14:39 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38396/tests/kraken/driver.html?testName=audio-dft) 12:14:40 INFO - PROCESS | 23568 | RSS: Main: 204730368 12:14:40 INFO - PROCESS | 23568 | 12:14:43 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38396/tests/kraken/driver.html?testName=audio-fft) 12:14:44 INFO - PROCESS | 23568 | RSS: Main: 270635008 12:14:44 INFO - PROCESS | 23568 | 12:14:46 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38396/tests/kraken/driver.html?testName=audio-oscillator) 12:14:46 INFO - PROCESS | 23568 | RSS: Main: 180580352 12:14:46 INFO - PROCESS | 23568 | 12:14:49 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38396/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:14:49 INFO - PROCESS | 23568 | RSS: Main: 184565760 12:14:49 INFO - PROCESS | 23568 | 12:14:52 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38396/tests/kraken/driver.html?testName=imaging-darkroom) 12:14:53 INFO - PROCESS | 23568 | RSS: Main: 219762688 12:14:53 INFO - PROCESS | 23568 | 12:14:56 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38396/tests/kraken/driver.html?testName=imaging-desaturate) 12:14:57 INFO - PROCESS | 23568 | RSS: Main: 221618176 12:14:57 INFO - PROCESS | 23568 | 12:15:00 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38396/tests/kraken/driver.html?testName=json-parse-financial) 12:15:00 INFO - PROCESS | 23568 | RSS: Main: 221622272 12:15:00 INFO - PROCESS | 23568 | 12:15:02 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38396/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:15:02 INFO - PROCESS | 23568 | RSS: Main: 209285120 12:15:02 INFO - PROCESS | 23568 | 12:15:04 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:15:05 INFO - PROCESS | 23568 | RSS: Main: 212897792 12:15:05 INFO - PROCESS | 23568 | 12:15:07 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:15:07 INFO - PROCESS | 23568 | RSS: Main: 210546688 12:15:07 INFO - PROCESS | 23568 | 12:15:10 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:15:10 INFO - PROCESS | 23568 | RSS: Main: 229425152 12:15:10 INFO - PROCESS | 23568 | 12:15:13 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:15:14 INFO - PROCESS | 23568 | RSS: Main: 223645696 12:15:14 INFO - PROCESS | 23568 | 12:15:15 INFO - PROCESS | 23568 | Cycle 1(1): loaded http://localhost:38396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38396/tests/kraken/driver.html?testName=ai-astar) 12:15:16 INFO - PROCESS | 23568 | RSS: Main: 266465280 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | __start_tp_report 12:15:16 INFO - PROCESS | 23568 | _x_x_mozilla_page_load 12:15:16 INFO - PROCESS | 23568 | _x_x_mozilla_page_load_details 12:15:16 INFO - PROCESS | 23568 | |i|pagename|runs| 12:15:16 INFO - PROCESS | 23568 | |0;ai-astar;102;113;111;111;110;110;111;110;111;110 12:15:16 INFO - PROCESS | 23568 | |1;audio-beat-detection;149;117;118;183;119;119;148;120;148;120 12:15:16 INFO - PROCESS | 23568 | |2;audio-dft;162;162;161;160;162;162;161;161;162;160 12:15:16 INFO - PROCESS | 23568 | |3;audio-fft;75;75;76;126;75;77;74;74;103;74 12:15:16 INFO - PROCESS | 23568 | |4;audio-oscillator;83;84;118;84;84;84;128;84;84;125 12:15:16 INFO - PROCESS | 23568 | |5;imaging-gaussian-blur;116;116;116;117;116;117;116;117;116;117 12:15:16 INFO - PROCESS | 23568 | |6;imaging-darkroom;148;147;146;147;146;146;146;146;145;146 12:15:16 INFO - PROCESS | 23568 | |7;imaging-desaturate;94;95;95;95;94;94;95;95;94;94 12:15:16 INFO - PROCESS | 23568 | |8;json-parse-financial;54;51;52;52;52;54;53;52;52;53 12:15:16 INFO - PROCESS | 23568 | |9;json-stringify-tinderbox;63;63;63;62;63;62;63;63;63;63 12:15:16 INFO - PROCESS | 23568 | |10;stanford-crypto-aes;76;78;76;75;75;84;75;79;75;78 12:15:16 INFO - PROCESS | 23568 | |11;stanford-crypto-ccm;118;118;119;127;132;118;123;119;160;128 12:15:16 INFO - PROCESS | 23568 | |12;stanford-crypto-pbkdf2;158;155;156;153;160;159;157;159;155;155 12:15:16 INFO - PROCESS | 23568 | |13;stanford-crypto-sha256-iterative;68;62;62;62;63;61;61;64;61;61 12:15:16 INFO - PROCESS | 23568 | __end_tp_report 12:15:16 INFO - PROCESS | 23568 | __start_cc_report 12:15:16 INFO - PROCESS | 23568 | _x_x_mozilla_cycle_collect,1671 12:15:16 INFO - PROCESS | 23568 | __end_cc_report 12:15:16 INFO - PROCESS | 23568 | __startTimestamp1468610116066__endTimestamp 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | ------- Summary: start ------- 12:15:16 INFO - PROCESS | 23568 | Number of tests: 14 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.92 (2.6%) stddev-sans-first:0.97 12:15:16 INFO - PROCESS | 23568 | Values: 102.0 113.0 111.0 111.0 110.0 110.0 111.0 110.0 111.0 110.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:134.00 stddev:22.12 (16.5%) stddev-sans-first:22.80 12:15:16 INFO - PROCESS | 23568 | Values: 149.0 117.0 118.0 183.0 119.0 119.0 148.0 120.0 148.0 120.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 12:15:16 INFO - PROCESS | 23568 | Values: 162.0 162.0 161.0 160.0 162.0 162.0 161.0 161.0 162.0 160.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:17.54 (23.4%) stddev-sans-first:18.37 12:15:16 INFO - PROCESS | 23568 | Values: 75.0 75.0 76.0 126.0 75.0 77.0 74.0 74.0 103.0 74.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:84.00 stddev:19.38 (23.1%) stddev-sans-first:20.00 12:15:16 INFO - PROCESS | 23568 | Values: 83.0 84.0 118.0 84.0 84.0 84.0 128.0 84.0 84.0 125.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 12:15:16 INFO - PROCESS | 23568 | Values: 116.0 116.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.60 12:15:16 INFO - PROCESS | 23568 | Values: 148.0 147.0 146.0 147.0 146.0 146.0 146.0 146.0 145.0 146.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 12:15:16 INFO - PROCESS | 23568 | Values: 94.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:0.97 (1.9%) stddev-sans-first:0.87 12:15:16 INFO - PROCESS | 23568 | Values: 54.0 51.0 52.0 52.0 52.0 54.0 53.0 52.0 52.0 53.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 12:15:16 INFO - PROCESS | 23568 | Values: 63.0 63.0 63.0 62.0 63.0 62.0 63.0 63.0 63.0 63.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#10] stanford-crypto-aes Cycles:10 Average:77.10 Median:77.00 stddev:2.85 (3.7%) stddev-sans-first:2.99 12:15:16 INFO - PROCESS | 23568 | Values: 76.0 78.0 76.0 75.0 75.0 84.0 75.0 79.0 75.0 78.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:125.00 stddev:12.89 (10.3%) stddev-sans-first:13.33 12:15:16 INFO - PROCESS | 23568 | Values: 118.0 118.0 119.0 127.0 132.0 118.0 123.0 119.0 160.0 128.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:157.50 stddev:2.26 (1.4%) stddev-sans-first:2.35 12:15:16 INFO - PROCESS | 23568 | Values: 158.0 155.0 156.0 153.0 160.0 159.0 157.0 159.0 155.0 155.0 12:15:16 INFO - PROCESS | 23568 | 12:15:16 INFO - PROCESS | 23568 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:2.17 (3.5%) stddev-sans-first:1.05 12:15:16 INFO - PROCESS | 23568 | Values: 68.0 62.0 62.0 62.0 63.0 61.0 61.0 64.0 61.0 61.0 12:15:16 INFO - PROCESS | 23568 | -------- Summary: end -------- 12:15:16 INFO - PROCESS | 23568 | 12:15:17 INFO - TEST-INFO | 23568: exit 0 12:15:17 INFO - TEST-OK | kraken | took 60418ms 12:15:17 INFO - SUITE-END | took 1173s 12:15:17 INFO - Completed test suite (00:19:33) 12:15:17 INFO - javascript benchmark 12:15:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3025.2739342557784, 3038.1189455058034, 3059.036403532887, 3069.5702601635776, 3073.738732207762, 5235.55497782688, 5254.316938797951, 5304.946950530493, 5355.812546560871, 5368.382635996857, 5272.789088436458, 5275.235090911816, 5378.435264297251, 5408.891822163554, 5409.675419474835, 4741.881452963669, 4794.760261986904, 4805.471398146201, 4815.66290359675, 5002.199648056312, 4917.4344950330815, 4936.062148191832, 4937.530934561211, 4993.550580447759, 5004.224345226496, 4752.5247475252545, 4792.616590672756, 4800.399950006232, 4838.975805120987, 4839.564439200489, 4781.497942716034, 4784.186688263004, 4784.712917224978, 4788.640851936118, 4874.073925954093, 4765.261384485376, 4795.136875362434, 4810.927836082462, 4815.2054910940005, 4888.535589119028, 5207.895842083175, 5237.607179461517, 5249.002689488985, 5354.678719276856, 5361.0, 5555.777768889239, 5609.635373700697, 5619.7471113799975, 5627.155926611, 5632.9436705632825, 5610.410906854762, 5616.52259557938, 5621.747021384048, 5670.5463562914865, 5673.035583950697, 5235.424103348629, 5237.921431178535, 5277.604179686539, 5330.760115794798, 5335.306410166692, 5867.295924489089, 5878.647281163144, 5880.059190529495, 5884.735186916513, 5885.058390657474, 5614.466625670555, 5620.044592419298, 5628.662280263197, 5629.690367029771, 5631.267935168402, 5544.750486228129, 5638.126090455904, 5645.322561292671, 5690.601657883908, 5733.71331433435, 5654.067078932012, 5674.0637794764225, 5678.261825962681, 5679.914801277984, 5720.885582288331, 5577.888442231133, 5688.260526131577, 5689.6586204827845, 5691.0, 5697.003024470701, 4302.65578753699, 4320.978395108004, 4330.155619654137, 4338.739675619473, 4421.646268298528, 3887.028242939265, 3902.687784977195, 3902.746321489094, 3929.7838618876235, 3931.0565464288366, 5373.489518495737, 5415.620906536505, 5427.0, 5429.809956651498, 5435.211894275308, 5609.915851262234, 5648.068068768689, 5691.004074286983, 5692.1461780733225, 5701.71491425427, 5347.919781203285, 5348.251244825728, 5352.4915133062295, 5353.304070470814, 5359.169328754049, 4699.365585645963, 4819.614430845524, 4828.130936431476, 4830.275458681226, 4858.416989961227, 5775.5668324875805, 5786.0, 5789.652620842763, 5799.0, 5824.126381042877, 5642.153676948491, 5644.096944488862, 5646.576506761927, 5652.915206271991, 5653.095504719226, 5733.770649174153, 5814.244148260699, 5819.0, 5827.5629327801435, 5851.61964472308, 5637.379888212376, 5654.0, 5659.377468478382, 5661.264035675501, 5719.999000174869, 5701.258836351248, 5709.172170035405, 5712.6286791358425, 5714.314282286152, 5714.971425142848, 5425.186222066722, 5573.136163894603, 5581.190727344593, 5584.804531841366, 5629.690367029689, 5577.024020796425, 5590.105583106846, 5593.133064375028, 5597.160425935979, 5627.5497960162265, 5481.287432633732, 5486.286782718221, 5520.889582208413, 5530.889382212253, 5532.5020748132865, 5534.501894829485, 5578.30271216098, 5616.241807355955, 5638.23883775685, 5672.801451949162, 5330.413654498079, 5375.81184658535, 5378.462153784591, 5402.918956215739, 5404.243405923253], "value": 5232.802993524455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.9941500584996, 609.6098496961944, 639.9904001439979, 640.6700549217154, 643.1863692429081, 43919.56080439195, 44978.10043799122, 45733.771331143296, 45789.08421831561, 45827.0, 47092.293615595794, 47380.52619473804, 47624.28563571541, 47634.0, 47673.761631191795, 8139.430239883211, 9145.634174633007, 9303.25573954082, 10847.403392813392, 11135.944320278428, 10636.361818290889, 11025.44872756363, 11095.889041109607, 11099.55601775928, 11108.833367499494, 11065.170112241574, 11140.665780026613, 11142.49858756358, 11142.888571114267, 11148.721281967935, 11081.113510919107, 11091.33451992883, 11152.609658661908, 11152.944235278852, 11154.274972126786, 11058.723531911686, 11157.38634375109, 11162.274452160584, 11164.553417863275, 11164.944175279154, 42610.360844587354, 42623.14753704939, 42692.146157076684, 42914.570854291684, 42962.57037429617, 3484.0593039879345, 3515.1739341254784, 3551.2187318789825, 3582.695470884978, 3602.585702644177, 33631.663683363346, 33745.83127084374, 33917.0, 33960.49059264113, 34185.487217691756, 4519.751413672247, 4563.657725670555, 4570.588647021784, 4589.58693717567, 4699.929501057487, 386822.0, 389442.0, 391079.0, 391164.99999999715, 391655.0, 64012.35987640157, 64478.677606611665, 65148.34851651423, 65227.347726523076, 65227.347726523076, 68817.3118268821, 69316.0, 69510.0, 69527.0, 69572.30427695658, 16625.41811036632, 17036.744448833277, 17098.0, 18001.639967200845, 18025.54936126581, 13698.452061917611, 13769.586912392644, 13802.654933626538, 13820.585382438543, 13830.446782128602, 19380.515487112934, 20169.798302017087, 20364.28724994618, 20450.488737781674, 20471.897640511703, 7225.385842203366, 7416.184219735825, 7547.396208303323, 7558.697652093854, 7578.0, 1007.1942446043137, 1102.459794700591, 1105.4528008635777, 1118.0161457916981, 1129.0290350298737, 33900.49149262763, 33956.660433395846, 34061.31877362489, 34184.48723269153, 34316.65683343184, 995.4674249276602, 1128.3906690387264, 1143.5654451308617, 1172.6013155527162, 1202.963911082669, 17236.224369903386, 17782.680529696834, 17862.567956507846, 17992.820071799375, 18524.351647692536, 547082.0, 551550.0, 553293.0, 555716.0, 556137.0, 60553.697231514445, 62103.37896620976, 62188.378116219166, 62191.37808621946, 62195.37804621896, 1278.6164150754737, 1364.447398803486, 1364.986350136486, 1380.3650320852319, 1389.7428975639539, 33413.83293083519, 35388.11529711778, 35571.82214088913, 35744.28511429809, 35799.0, 52852.0, 53719.194212087634, 53756.73121634523, 53769.73115134399, 53815.46184538105, 424596.0, 435419.0, 435479.0, 436520.0, 437205.0, 25296.596823302494, 27989.720102799525, 28447.0, 28629.28426789347, 30419.847900760353, 48287.5171248283, 49013.0, 49714.0, 50506.49493505018, 53679.46320536901, 43347.566524334354, 43726.34410483909, 44200.0, 45051.774741126086, 45359.31961020521, 43280.134397312504, 44077.77961110174, 44190.33714494221, 45329.0, 45411.956461959955, 43825.78087109544, 44130.77934610307, 45110.774446128875, 45300.32049519194, 45456.09087818291, 38795.6120438792, 39395.0, 39759.204815902944, 40045.79977100096, 40428.36144746869, 29499.672514737318, 29910.401791964476, 30170.09489715312, 30285.0, 30511.847440762653], "value": 21679.668682812, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.4751913637885, 392.17838628074173, 396.68859944943216, 399.6343345838557, 409.6804492495854, 13623.415070100004, 18631.441056768286, 19802.504937376572, 20186.495337616587, 20248.29130980416, 14967.326470308835, 18429.815701842977, 19258.903705481454, 19353.4193974181, 19643.410697679057, 5671.517920976722, 5844.386339434362, 5852.707364631751, 5863.941360586404, 5885.411458854123, 5743.3108027036815, 5817.41825817417, 5820.068788993762, 5820.767169313222, 5823.854403639901, 4243.405923170759, 4275.358696195564, 6300.621962682231, 6345.238571371442, 6451.870962580746, 6196.938030619681, 6391.265004524469, 6425.935740642604, 6439.839004024913, 6446.387593178641, 5669.773209071632, 5680.204771332017, 5697.800576979827, 5700.087985922232, 5711.428857114297, 19587.11857966395, 22624.77375226243, 23112.65331020036, 23485.695266198687, 23776.0, 2190.759016508183, 2342.8125749939963, 2365.089440565395, 2377.679013333195, 2388.3193289912465, 10622.893771062269, 11104.55581776728, 11844.993175580086, 13298.268595227259, 15191.088534687955, 1924.7112933060014, 1974.9835418038165, 1984.8412127029947, 2003.7996200379991, 2006.6989951507244, 83326.58336708277, 84028.0, 85186.0, 86243.61094595467, 87254.0, 9109.908900910907, 9411.623535058658, 9688.12806847387, 9792.461414622265, 11148.554057837595, 12341.259524428562, 12408.069394795246, 12456.0, 12460.314682692539, 12464.750704985849, 7543.1325397578885, 7777.066751989797, 7785.805354866174, 7828.256315650005, 7894.289513943772, 7488.887666685004, 7510.6620202091035, 7607.429442791813, 7645.350145237717, 7693.576853273012, 4096.180763847243, 4120.0, 4167.854125105607, 4350.369196466495, 4483.3947417098925, 1948.2791367193945, 1964.5383334916282, 1980.0990549300034, 1998.2506560039985, 2044.4479990402806, 928.3223247029571, 1009.3741880034279, 1013.7516308504464, 1029.264076185516, 1096.7735496941223, 19412.708809367872, 20002.799972000386, 20020.69968950467, 20263.696044559343, 20599.897000514902, 1042.1246153231323, 1049.040128282628, 1063.5639387850945, 1086.7174534621056, 1089.1123734156638, 10594.15246780271, 11066.336019838835, 11195.4962026709, 11200.21598488098, 11200.775984480264, 418899.0, 425585.0, 531098.0, 558351.0, 564974.0, 97766.5111674437, 98498.0, 100788.49605751924, 105310.0, 106701.4664926686, 463.4484962894145, 479.60672248756384, 481.5112660649444, 486.79554587072806, 487.8487668822676, 11135.665930022113, 11477.0, 11624.302541847515, 11659.475323610628, 12010.0, 42830.35754463748, 43504.0, 43631.0, 45642.0, 48665.5133448661, 57315.71342143263, 58694.7065264671, 60802.695986519786, 63504.682476587324, 65942.67028664827, 11929.343886086348, 12717.618471445872, 12806.88686366739, 12880.291583962971, 12962.80555791645, 8584.656613735631, 13183.934080329536, 13283.335833208108, 13421.530246441718, 13482.460701571827, 11437.771244574895, 11693.064554835422, 11748.0, 11749.177557570887, 11751.823722643996, 11454.541818327174, 11545.595904143314, 11607.535698572301, 11669.708257293636, 11832.76334473323, 11650.70873228176, 11800.52797888075, 12000.939995299968, 12014.459349329476, 12036.879631203927, 12646.367681616066, 12812.807807882702, 12877.871221288042, 12909.096363254483, 12970.0, 11631.243969141979, 12043.879561204276, 12047.638570842888, 12236.327002014974, 12253.877461225631], "value": 9173.25624789779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.857714228577, 1490.7167638148753, 1587.785648937393, 1732.5322163015994, 1788.7853457585093, 8387.370947178963, 8863.379563430562, 8949.239314658262, 8950.462972221663, 8953.418027828187, 7175.390091842187, 7332.376747976415, 7345.3021962913435, 7548.81127971801, 8745.562721863897, 2251.4821591034033, 2328.348062542491, 2329.906803727849, 2347.58917189492, 2354.2466410748566, 2285.405794493435, 2327.173853282086, 2336.3925379401394, 2337.4623836517612, 2346.730126035502, 2014.0634604908714, 2199.098369668436, 2290.5189910118893, 2307.4116100394376, 2309.8383113182085, 2293.644485104803, 2317.5133222023396, 2323.7095363079698, 2334.8015418689433, 2349.7650234976536, 2279.8746068966366, 2282.2582660635194, 2318.698569186012, 2319.1419174905222, 2322.779335963081, 8877.289816814638, 8988.550572471415, 8992.100789921022, 8992.190702836711, 8993.505357205351, 5099.923501147486, 5210.895782084375, 5245.79016839326, 5251.501107394792, 5253.947460525423, 3223.1458663454205, 3275.7706960512774, 3366.8316584170693, 3367.9494807577908, 3369.3598216339064, 982.1798798003675, 1007.4559737741685, 1010.6412223660568, 1018.241410149436, 1041.1306559023149, 12043.0, 12077.516899324017, 12109.757804843854, 12119.636410907688, 12148.088893333035, 4345.326474396474, 4589.862304130881, 4606.355110284563, 4673.088747694235, 4677.812887484462, 10185.083342499063, 10217.13154381886, 10277.280590358603, 10311.793764124675, 10326.48367581618, 5228.189630607262, 5230.372355317386, 5234.476552344735, 5236.790528378822, 5242.554382877422, 3969.583193764613, 4611.377464042378, 4815.590674792612, 4818.855434336905, 4820.975895120502, 3933.3903244997073, 3959.2675355059405, 4031.1937612477623, 4036.2936486114813, 4044.2518134145275, 1240.2682417373792, 1252.6179515247936, 1255.3095797311441, 1260.710036691566, 1269.320913311374, 1017.4200705597739, 1020.4438580973388, 1022.3610243597751, 1027.2295778166376, 1029.0686928329874, 6138.693065346715, 6158.876822463526, 6240.251169859646, 6306.905396419057, 6483.0, 453.6234925012232, 455.7015155073366, 463.4994206257234, 465.5949324088065, 472.85341544121434, 4179.832806687759, 4333.804978775963, 4380.408644832971, 4386.385905973167, 4411.250087485135, 10608.15134789215, 10967.725806854733, 11054.170937179744, 11134.053605443465, 11136.164787640797, 10966.122710183166, 10999.175061870232, 11000.339979601249, 11000.944995274973, 11016.614418495314, 7435.107787065586, 8260.463069900437, 8366.916330836735, 8371.0, 8372.246497815104, 6207.875842483035, 6275.560710750204, 6278.340774218824, 6329.968350158219, 6464.5151613630105, 9974.0, 10127.18982481385, 10293.176545876462, 10310.948445257725, 10318.071373576415, 10293.176545876462, 10309.278350515691, 10468.371897686164, 10476.790464190826, 10613.363198208132, 6903.89644155328, 7334.4132469403385, 7915.44591878586, 8052.516848989079, 8076.313513351431, 7993.520388776459, 8300.667973281243, 8306.04480484752, 8313.875291870505, 8315.875261870955, 7132.821679458055, 7217.819554511179, 7240.167380751281, 7268.27317268269, 7475.177730449537, 7317.560946343024, 7322.926770732224, 7358.521696089737, 7390.408767298711, 7422.777316680508, 6757.45940324763, 6827.351401616838, 6828.863422731418, 6830.624315662686, 6837.213720422215, 5089.567386772166, 5167.974160129175, 5213.635045546927, 5365.570754339566, 5388.515033646991, 5537.390887002346, 5549.278593782782, 5591.664500130005, 5606.495415412633, 5657.0100232458335], "value": 4535.115785395979, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.2135422121806, 317.30193574136905, 319.88963807486437, 320.11169006008333, 322.08206611158187, 5433.347998240207, 5497.202905578693, 5503.174523821429, 5530.391656917735, 5556.805511807088, 5385.21914322424, 5433.0, 5461.5357694595905, 5462.426445223244, 5474.890502189964, 4045.5145382553987, 4059.451973983504, 4205.915881682365, 4214.325707886739, 4237.427947227131, 4173.6661067114555, 4182.247195504808, 4210.179015092058, 4216.831326746927, 4220.957790422087, 3996.780177090259, 4165.479315085614, 4171.812268447927, 4177.791110444481, 4187.853425130121, 4074.2258970795456, 4164.354525048622, 4166.937495937533, 4202.936955945664, 4221.387898754694, 3676.8713095041544, 3681.3557627415094, 3691.741578089535, 3697.8890633281044, 3710.1466662667467, 5185.870353241162, 5319.122344813101, 5319.441458646864, 5323.627346085776, 5327.334083239595, 4745.477997420281, 4761.500042495522, 4767.189577771787, 4777.928331075037, 4791.832285869978, 5544.889102217973, 5571.470710282517, 5573.749181286852, 5587.69267690281, 5675.6878371689545, 3845.0002999220123, 3991.2416640838437, 4000.7599544027453, 4037.2732908076537, 4043.0, 5857.736401861926, 5862.062070068769, 5868.383819698955, 5871.3541510433815, 5885.323187833368, 4059.5534491205945, 4072.714909956334, 4105.8973525662095, 4107.753534787922, 4116.773577453209, 4041.9191616167514, 4043.332850079704, 4104.56902025289, 4161.273071675281, 4165.958510372407, 4705.929411058837, 4707.95292047082, 4718.127146477913, 4730.361401210793, 4736.194846876039, 5632.676321064547, 5699.886002279931, 5728.283964504437, 5745.0, 5750.0, 1354.3769865861616, 1420.659041829974, 1422.950196743109, 1439.9064060836013, 1440.3734375546671, 883.5626364949392, 893.5041052216083, 903.0112027330063, 929.2380248196549, 953.8140062687849, 5399.379071406838, 5411.431799661058, 5414.7021913795115, 5424.783008679608, 5426.0, 5723.713814309352, 5727.742251598688, 5757.222774925332, 5760.74076666551, 5767.653940763567, 5257.553107985787, 5378.220158077057, 5382.973085134549, 5387.464572596791, 5426.62013659048, 3065.203047207742, 3066.432709948667, 3070.6929307069563, 3077.4152910947296, 3078.2150551609197, 5808.912866307009, 5876.970615146897, 5941.227640406806, 5962.0, 5971.611845230046, 5961.821145365646, 5994.1608174855555, 5996.700164991733, 6010.398960103955, 6011.549133814894, 6025.969870150621, 6039.245094363205, 6052.364501727343, 6127.050307202391, 6131.448169664752, 5644.971775141098, 5859.707014649336, 5863.706814659165, 5882.941170588239, 5892.587518873809, 5793.073108302651, 5805.680687562056, 5807.245058142584, 5811.186433899173, 5815.302163740378, 5739.397363276963, 6003.6998150093195, 6090.23872015998, 6103.938960610337, 6105.389461053859, 4059.071940828083, 4083.8355671494155, 4086.85568040949, 4088.34586466164, 4095.078607313331, 5949.720810025865, 5951.583389162717, 5951.821445356646, 5961.612495187799, 5976.46211840919, 5705.572082093942, 5706.714664266853, 5712.942870571241, 5741.196232527366, 5841.357450680507, 5708.714564271853, 5709.20071190042, 5722.313322401338, 5741.684207368635, 5743.310802703702, 5274.340707411574, 5422.213779002102, 5661.773529058792, 5697.658140511582, 5700.572457065654, 5679.148127780866, 5724.713764311685, 5739.368669446441, 5741.8564535886935, 5790.160426738184, 5654.717264136859, 5656.632318899258, 5666.376698563073, 5672.659640421422, 5682.261306030189], "value": 4305.1079357774715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.7438896386269, 738.3207449146785, 791.5646394483036, 793.8253584211474, 802.8835818806273, 14956.252187390628, 15847.128407937573, 15878.444254451097, 15893.84106158938, 15902.602434939132, 14274.429022839075, 14304.642383940407, 14320.427182912697, 14356.569302920929, 14365.49720759771, 659.2287024181695, 776.2043904997366, 792.2631952284358, 799.2646764976207, 812.3298278919885, 809.0897740042452, 813.5403497024192, 816.0615292413712, 817.1542453560565, 817.5094943034162, 865.909079546648, 872.6727477196051, 872.7512658892217, 874.1564390363299, 876.2289185516736, 696.4150113904319, 712.707789806177, 713.7644577289482, 836.0552575589605, 863.201538576814, 625.1123404765244, 628.2523796681934, 628.5537268539342, 628.7013668507468, 628.8427893026743, 15375.308111135026, 15400.53798386039, 15426.305816238297, 15432.3827046918, 15444.922775386163, 14728.631784205374, 14805.259737013106, 14886.255687215596, 14907.70184596302, 14920.179390133539, 13857.792133118011, 13932.651683707887, 14048.508302209475, 14210.644733881632, 14255.215963122024, 4200.810963506649, 4275.4228179195725, 4309.0, 4402.273624851896, 4435.600795928382, 74054.0, 79458.0, 80888.0, 81308.0, 82466.58766706187, 11814.704632384259, 11939.820902686466, 12000.459979300953, 12026.699332516757, 12110.515579376726, 21774.45563860916, 21788.782112178993, 21812.563748725253, 21849.235276765237, 21863.453413664785, 8403.747887563382, 8584.098669639723, 8771.956140219385, 8843.292536597057, 8942.195202431813, 4982.252662100642, 5024.045431368028, 5060.493950604984, 5077.974610126999, 5113.309703190096, 3399.5070714746225, 3489.354469423165, 3515.5605549306338, 3515.57813062429, 3522.8062456565135, 640.4428147511695, 642.2261175283771, 646.2406672160193, 659.6240143118376, 674.521090026087, 676.6481429656551, 723.489939592586, 734.0823970037453, 737.5095561451682, 805.0540614777613, 9920.454375009444, 10122.54448549817, 10284.691459256233, 10333.431661258552, 10749.19381046409, 718.306833905284, 729.278014765389, 743.5092838726415, 746.0301607909696, 752.0966185497251, 5042.773075211624, 5051.26756620288, 5067.062593420229, 5076.492350764894, 5113.1052065888325, 64209.67895160494, 65877.0, 66413.66793166003, 66606.66696666485, 75061.0, 98327.0, 98471.50764246134, 99224.0, 100483.49758251161, 101608.49195753974, 58439.70780146072, 85444.57277713572, 87472.0, 87721.56139219391, 88474.99999999872, 10222.53998570081, 10285.0, 10374.118199953089, 10453.947730261605, 10544.0, 53531.0, 54353.45646543642, 54601.45398545964, 54810.45189548213, 55005.72497137489, 68179.31820681928, 68687.0, 75752.62123689346, 76898.61550692211, 78723.0, 13076.607701768962, 13217.537386191438, 13395.464181433023, 13445.327733613476, 13829.377678004716, 14246.572602821932, 14364.640883977983, 15047.247637617857, 15358.15530145808, 16598.83401165973, 14416.783748243995, 14781.113133212042, 15061.17163556015, 15517.5344739658, 15560.0, 14348.784768228694, 14431.206283654079, 14601.26993650292, 15248.923755381153, 15614.687706246039, 13394.866051339362, 13664.248466334448, 13970.371333289822, 14022.088564243291, 14472.204028778518, 11604.535818567501, 12181.817272741093, 12334.691632708895, 12345.135840491079, 12549.247045177319, 11650.184487085822, 11846.289222646668, 11880.584179553674, 12042.939785301367, 12199.69500762488], "value": 7691.896729176104, "unit": "score"}], "name": "dromaeo_css", "value": 7339.404215637842, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 111.0, 111.0, 110.0, 110.0, 111.0, 110.0, 111.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 118.0, 183.0, 119.0, 119.0, 148.0, 120.0, 148.0, 120.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 160.0, 162.0, 162.0, 161.0, 161.0, 162.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 126.0, 75.0, 77.0, 74.0, 74.0, 103.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 118.0, 84.0, 84.0, 84.0, 128.0, 84.0, 84.0, 125.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 52.0, 52.0, 54.0, 53.0, 52.0, 52.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0, 63.0, 63.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 75.0, 75.0, 84.0, 75.0, 79.0, 75.0, 78.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 127.0, 132.0, 118.0, 123.0, 119.0, 160.0, 128.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 155.0, 156.0, 153.0, 160.0, 159.0, 157.0, 159.0, 155.0, 155.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 62.0, 62.0, 63.0, 61.0, 61.0, 64.0, 61.0, 61.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1479.0, "alertThreshold": 2.0}]} 12:15:17 INFO - Return code: 0 12:15:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:15:17 INFO - # TBPL SUCCESS # 12:15:17 INFO - Running post-action listener: _resource_record_post_action 12:15:17 INFO - ##### 12:15:17 INFO - ##### Finished run-tests step (success) 12:15:17 INFO - ##### 12:15:17 INFO - Running post-run listener: _resource_record_post_run 12:15:17 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 560271360; Read time: 36; Write time: 542400 12:15:17 INFO - TinderboxPrint: CPU usage
13.0% 12:15:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 36 12:15:17 INFO - TinderboxPrint: I/O write bytes / time
560,271,360 / 542,400 12:15:17 INFO - TinderboxPrint: CPU idle
8,270.9 (87.0%) 12:15:17 INFO - TinderboxPrint: CPU user
1,195.1 (12.6%) 12:15:17 INFO - TinderboxPrint: Swap in / out
0 / 0 12:15:17 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 260878336; Read time: 0; Write time: 290792 12:15:17 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 292495360; Read time: 0; Write time: 249204 12:15:17 INFO - Running post-run listener: _upload_blobber_files 12:15:17 INFO - Blob upload gear active. 12:15:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:15:17 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 12:15:17 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:15:17 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:15:18 INFO - (blobuploader) - INFO - Open directory for files ... 12:15:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:15:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:15:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:15:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:15:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:15:19 INFO - (blobuploader) - INFO - Done attempting. 12:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:15:20 INFO - (blobuploader) - INFO - Done attempting. 12:15:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:15:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:15:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:15:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:15:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:15:23 INFO - (blobuploader) - INFO - Done attempting. 12:15:23 INFO - (blobuploader) - INFO - Iteration through files over. 12:15:23 INFO - Return code: 0 12:15:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:15:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:15:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d737ef32ae25ced00c57367e312fc02e11fbfd1bac85fa7c0a3bd2d2d4c13bd7bde92934df74121cfb6efdc81d8afd005244f3485ea9b3fd41a4dc684ac9ddd4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/85ce1f78a99ba65ebd2ebb3ef906c617f99cb10d856abb561e3240129cda9c76995c882e3eb74c8e8438247870aa7c7d40e26c3aad3299867876eac5b6864e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e754d3b34775641f38c2409150864784dffed4d52dc7fd379b451df0fe6206ad32e4248c1a5b824e54a467beccea0f510b5080312fa72b67fa04894919c9cc31"} 12:15:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:15:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:15:23 INFO - Contents: 12:15:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d737ef32ae25ced00c57367e312fc02e11fbfd1bac85fa7c0a3bd2d2d4c13bd7bde92934df74121cfb6efdc81d8afd005244f3485ea9b3fd41a4dc684ac9ddd4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/85ce1f78a99ba65ebd2ebb3ef906c617f99cb10d856abb561e3240129cda9c76995c882e3eb74c8e8438247870aa7c7d40e26c3aad3299867876eac5b6864e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e754d3b34775641f38c2409150864784dffed4d52dc7fd379b451df0fe6206ad32e4248c1a5b824e54a467beccea0f510b5080312fa72b67fa04894919c9cc31"} 12:15:23 INFO - Running post-run listener: copy_logs_to_upload_dir 12:15:23 INFO - Copying logs to upload dir... 12:15:23 INFO - mkdir: /builds/slave/test/build/upload/logs 12:15:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.303212 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-15 12:15:23.897509) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 12:15:23.900753) ========= 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=62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d737ef32ae25ced00c57367e312fc02e11fbfd1bac85fa7c0a3bd2d2d4c13bd7bde92934df74121cfb6efdc81d8afd005244f3485ea9b3fd41a4dc684ac9ddd4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/85ce1f78a99ba65ebd2ebb3ef906c617f99cb10d856abb561e3240129cda9c76995c882e3eb74c8e8438247870aa7c7d40e26c3aad3299867876eac5b6864e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e754d3b34775641f38c2409150864784dffed4d52dc7fd379b451df0fe6206ad32e4248c1a5b824e54a467beccea0f510b5080312fa72b67fa04894919c9cc31"} build_url:https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011374 build_url: 'https://queue.taskcluster.net/v1/task/B888eZ8qRBWKmMbDJFEmLw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d737ef32ae25ced00c57367e312fc02e11fbfd1bac85fa7c0a3bd2d2d4c13bd7bde92934df74121cfb6efdc81d8afd005244f3485ea9b3fd41a4dc684ac9ddd4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/85ce1f78a99ba65ebd2ebb3ef906c617f99cb10d856abb561e3240129cda9c76995c882e3eb74c8e8438247870aa7c7d40e26c3aad3299867876eac5b6864e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e754d3b34775641f38c2409150864784dffed4d52dc7fd379b451df0fe6206ad32e4248c1a5b824e54a467beccea0f510b5080312fa72b67fa04894919c9cc31"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 12:15:23.927181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:15:23.927670) ========= 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=62c568580acbfdaac3eec7be000001dc-1468608892.411905-261098817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:15:24.010595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 12:15:24.011138) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 12:15:24.011618) ========= ========= Total master_lag: 0.18 =========