builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-068 starttime: 1465500805.26 results: success (0) buildid: 20160609120133 builduid: 2ee9e6e2ee704bd18a1095cd340c0d5a revision: 4d03f2c0d7175b2f37f21b02d14316439dbc56e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.259475) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.259885) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.260156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.277293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.277611) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-09 12:33:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.5M=0s 2016-06-09 12:33:25 (66.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112968 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.590365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.590685) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015243 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.624987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:25.625306) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4d03f2c0d7175b2f37f21b02d14316439dbc56e6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4d03f2c0d7175b2f37f21b02d14316439dbc56e6 --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=97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-09 12:33:25,915 truncating revision to first 12 chars 2016-06-09 12:33:25,915 Setting DEBUG logging. 2016-06-09 12:33:25,915 attempt 1/10 2016-06-09 12:33:25,915 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4d03f2c0d717?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-09 12:33:26,214 unpacking tar archive at: fx-team-4d03f2c0d717/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627185 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:26.271165) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:26.271494) ========= script_repo_revision: 4d03f2c0d7175b2f37f21b02d14316439dbc56e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:26.271877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:26.272136) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 12:33:26.281905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-06-09 12:33:26.282414) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:33:26 INFO - MultiFileLogger online at 20160609 12:33:26 in /builds/slave/test 12:33:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:33:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:33:26 INFO - {'append_to_log': False, 12:33:26 INFO - 'base_work_dir': '/builds/slave/test', 12:33:26 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:33:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:33:26 INFO - 'branch': 'Fx-Team-Non-PGO', 12:33:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:33:26 INFO - 'config_files': ('talos/linux_config.py',), 12:33:26 INFO - 'default_actions': ('clobber', 12:33:26 INFO - 'read-buildbot-config', 12:33:26 INFO - 'download-and-extract', 12:33:26 INFO - 'populate-webroot', 12:33:26 INFO - 'create-virtualenv', 12:33:26 INFO - 'install', 12:33:26 INFO - 'run-tests'), 12:33:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:33:26 INFO - 'download_minidump_stackwalk': True, 12:33:26 INFO - 'download_symbols': 'ondemand', 12:33:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:33:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:33:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:33:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:33:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:33:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:33:26 INFO - 'installer_path': 'installer.exe', 12:33:26 INFO - 'log_level': 'info', 12:33:26 INFO - 'log_name': 'talos', 12:33:26 INFO - 'log_to_console': True, 12:33:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:33:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:33:26 INFO - 'opt_config_files': (), 12:33:26 INFO - 'pip_index': False, 12:33:26 INFO - 'sps_profile': False, 12:33:26 INFO - 'sps_profile_interval': 0, 12:33:26 INFO - 'suite': 'dromaeojs', 12:33:26 INFO - 'system_bits': '32', 12:33:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:33:26 INFO - 'title': 'talos-linux64-ix-068', 12:33:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:33:26 INFO - 'use_talos_json': True, 12:33:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:33:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:33:26 INFO - 'work_dir': 'build'} 12:33:26 INFO - ##### 12:33:26 INFO - ##### Running clobber step. 12:33:26 INFO - ##### 12:33:26 INFO - Running pre-action listener: _resource_record_pre_action 12:33:26 INFO - Running main action method: clobber 12:33:26 INFO - rmtree: /builds/slave/test/build 12:33:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:33:27 INFO - Running post-action listener: _resource_record_post_action 12:33:27 INFO - ##### 12:33:27 INFO - ##### Finished clobber step (success) 12:33:27 INFO - ##### 12:33:27 INFO - ##### 12:33:27 INFO - ##### Running read-buildbot-config step. 12:33:27 INFO - ##### 12:33:27 INFO - Running pre-action listener: _resource_record_pre_action 12:33:27 INFO - Running main action method: read_buildbot_config 12:33:27 INFO - Using buildbot properties: 12:33:27 INFO - { 12:33:27 INFO - "project": "", 12:33:27 INFO - "product": "firefox", 12:33:27 INFO - "script_repo_revision": "production", 12:33:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:33:27 INFO - "repository": "", 12:33:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 12:33:27 INFO - "buildid": "20160609120133", 12:33:27 INFO - "pgo_build": "False", 12:33:27 INFO - "basedir": "/builds/slave/test", 12:33:27 INFO - "buildnumber": 3076, 12:33:27 INFO - "slavename": "talos-linux64-ix-068", 12:33:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:33:27 INFO - "platform": "ubuntu64_hw", 12:33:27 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:33:27 INFO - "branch": "fx-team", 12:33:27 INFO - "repo_path": "integration/fx-team", 12:33:27 INFO - "revision": "4d03f2c0d7175b2f37f21b02d14316439dbc56e6", 12:33:27 INFO - "stage_platform": "linux64", 12:33:27 INFO - "builduid": "2ee9e6e2ee704bd18a1095cd340c0d5a", 12:33:27 INFO - "slavebuilddir": "test" 12:33:27 INFO - } 12:33:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 12:33:27 INFO - Running post-action listener: _resource_record_post_action 12:33:27 INFO - ##### 12:33:27 INFO - ##### Finished read-buildbot-config step (success) 12:33:27 INFO - ##### 12:33:27 INFO - ##### 12:33:27 INFO - ##### Running download-and-extract step. 12:33:27 INFO - ##### 12:33:27 INFO - Running pre-action listener: _resource_record_pre_action 12:33:27 INFO - Running main action method: download_and_extract 12:33:27 INFO - mkdir: /builds/slave/test/build/tests 12:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:33:27 INFO - https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:33:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:33:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:33:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:31 INFO - Downloaded 1361 bytes. 12:33:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:33:31 INFO - Using the following test package requirements: 12:33:31 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:33:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:33:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:33:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'jsshell-linux-x86_64.zip'], 12:33:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:33:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:33:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:33:31 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:33:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:33:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:33:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:33:31 INFO - https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:33:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:33:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:33:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:34 INFO - Downloaded 22879604 bytes. 12:33:34 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'] 12:33:34 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 12:33:36 INFO - Return code: 0 12:33:36 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 12:33:36 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')]} 12:33:36 INFO - https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:33:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:33:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:33:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:37 INFO - Downloaded 22879604 bytes. 12:33:37 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'] 12:33:37 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 12:33:38 INFO - Return code: 0 12:33:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:33:38 INFO - https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:33:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:33:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:33:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:39 INFO - Downloaded 10833377 bytes. 12:33:39 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'] 12:33:39 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 12:33:39 INFO - Return code: 0 12:33:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:33:39 INFO - https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:33:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:33:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:33:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:33:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 12:34:06 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:34:06 INFO - Running command: ['nslookup', 'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 12:34:06 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 12:34:06 INFO - Server: 10.26.75.40 12:34:06 INFO - Address: 10.26.75.40#53 12:34:06 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 12:34:06 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 12:34:06 INFO - Address: 10.26.48.46 12:34:06 INFO - Return code: 0 12:34:06 INFO - retry: attempt #1 caught exception: 12:34:06 INFO - retry: Failed, sleeping 30 seconds before retrying 12:34:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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 #2 12:34:38 INFO - Downloaded 55367236 bytes. 12:34:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:34:38 INFO - mkdir: /builds/slave/test/properties 12:34:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:34:38 INFO - Writing to file /builds/slave/test/properties/build_url 12:34:38 INFO - Contents: 12:34:38 INFO - build_url:https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:34:38 INFO - Running post-action listener: _resource_record_post_action 12:34:38 INFO - Running post-action listener: set_extra_try_arguments 12:34:38 INFO - ##### 12:34:38 INFO - ##### Finished download-and-extract step (success) 12:34:38 INFO - ##### 12:34:38 INFO - ##### 12:34:38 INFO - ##### Running populate-webroot step. 12:34:38 INFO - ##### 12:34:38 INFO - Running pre-action listener: _resource_record_pre_action 12:34:38 INFO - Running main action method: populate_webroot 12:34:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:34:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:34:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:34:38 INFO - '--tppagecycles', 12:34:38 INFO - '7'], 12:34:38 INFO - 'tests': ['tsvgm']}}, 12:34:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:34:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:34:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:34:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:34:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:34:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:34:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['damp', 'tps']}, 12:34:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'tests': ['damp', 'tps']}, 12:34:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['dromaeo_dom']}, 12:34:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:34:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['a11yr', 12:34:38 INFO - 'ts_paint', 12:34:38 INFO - 'tpaint', 12:34:38 INFO - 'sessionrestore', 12:34:38 INFO - 'sessionrestore_no_auto_restore', 12:34:38 INFO - 'tabpaint']}, 12:34:38 INFO - 'other-e10s': {'tests': ['a11yr', 12:34:38 INFO - 'ts_paint', 12:34:38 INFO - 'tpaint', 12:34:38 INFO - 'sessionrestore', 12:34:38 INFO - 'sessionrestore_no_auto_restore', 12:34:38 INFO - 'tabpaint']}, 12:34:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:34:38 INFO - 'ts_paint', 12:34:38 INFO - 'tpaint', 12:34:38 INFO - 'sessionrestore', 12:34:38 INFO - 'sessionrestore_no_auto_restore', 12:34:38 INFO - 'tabpaint']}, 12:34:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:34:38 INFO - 'ts_paint', 12:34:38 INFO - 'tpaint', 12:34:38 INFO - 'sessionrestore', 12:34:38 INFO - 'sessionrestore_no_auto_restore', 12:34:38 INFO - 'tabpaint']}, 12:34:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['a11yr', 12:34:38 INFO - 'ts_paint', 12:34:38 INFO - 'tpaint', 12:34:38 INFO - 'sessionrestore', 12:34:38 INFO - 'sessionrestore_no_auto_restore', 12:34:38 INFO - 'tabpaint']}, 12:34:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['a11yr', 12:34:38 INFO - 'ts_paint', 12:34:38 INFO - 'tpaint', 12:34:38 INFO - 'sessionrestore', 12:34:38 INFO - 'sessionrestore_no_auto_restore', 12:34:38 INFO - 'tabpaint']}, 12:34:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['tsvgx', 12:34:38 INFO - 'tsvgr_opacity', 12:34:38 INFO - 'tart', 12:34:38 INFO - 'tscrollx', 12:34:38 INFO - 'cart']}, 12:34:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:34:38 INFO - 'tsvgr_opacity', 12:34:38 INFO - 'tart', 12:34:38 INFO - 'tscrollx', 12:34:38 INFO - 'cart']}, 12:34:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'talos_options': ['--disable-e10s'], 12:34:38 INFO - 'tests': ['tp5o']}, 12:34:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'tests': ['tp5o']}, 12:34:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'talos_options': ['--disable-e10s', 12:34:38 INFO - '--xperf_path', 12:34:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:34:38 INFO - 'tests': ['tp5n']}, 12:34:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:38 INFO - 'talos_options': ['--xperf_path', 12:34:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:34:38 INFO - 'tests': ['tp5n']}}, 12:34:38 INFO - 'talos.zip': {'path': '', 12:34:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:34:38 INFO - Running post-action listener: _resource_record_post_action 12:34:38 INFO - ##### 12:34:38 INFO - ##### Finished populate-webroot step (success) 12:34:38 INFO - ##### 12:34:38 INFO - ##### 12:34:38 INFO - ##### Running create-virtualenv step. 12:34:38 INFO - ##### 12:34:38 INFO - Running pre-action listener: _resource_record_pre_action 12:34:38 INFO - Running main action method: create_virtualenv 12:34:38 INFO - Creating virtualenv /builds/slave/test/build/venv 12:34:38 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 12:34:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:34:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:34:38 INFO - Using real prefix '/usr' 12:34:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:34:39 INFO - Installing distribute.............................................................................................................................................................................................done. 12:34:41 INFO - Installing pip.................done. 12:34:41 INFO - Return code: 0 12:34:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:34:41 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')]} 12:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:41 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')]} 12:34:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:41 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:41 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 12:34:41 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 12:34:41 INFO - Using env: {'DISPLAY': ':0', 12:34:41 INFO - 'HOME': '/home/cltbld', 12:34:41 INFO - 'LANG': 'en_US.UTF-8', 12:34:41 INFO - 'LANGUAGE': 'en_US:en', 12:34:41 INFO - 'LOGNAME': 'cltbld', 12:34:41 INFO - 'MAIL': '/var/mail/cltbld', 12:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:41 INFO - 'MOZ_NO_REMOTE': '1', 12:34:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:41 INFO - 'NO_EM_RESTART': '1', 12:34:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:41 INFO - 'PWD': '/builds/slave/test', 12:34:41 INFO - 'SHELL': '/bin/bash', 12:34:41 INFO - 'SHLVL': '1', 12:34:41 INFO - 'TERM': 'linux', 12:34:41 INFO - 'TMOUT': '86400', 12:34:41 INFO - 'USER': 'cltbld', 12:34:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:41 INFO - '_': '/tools/buildbot/bin/python'} 12:34:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:41 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 12:34:41 INFO - Cleaning up... 12:34:41 INFO - Return code: 0 12:34:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:34:41 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')]} 12:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:41 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')]} 12:34:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:41 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:41 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 12:34:41 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 12:34:41 INFO - Using env: {'DISPLAY': ':0', 12:34:41 INFO - 'HOME': '/home/cltbld', 12:34:41 INFO - 'LANG': 'en_US.UTF-8', 12:34:41 INFO - 'LANGUAGE': 'en_US:en', 12:34:41 INFO - 'LOGNAME': 'cltbld', 12:34:41 INFO - 'MAIL': '/var/mail/cltbld', 12:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:41 INFO - 'MOZ_NO_REMOTE': '1', 12:34:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:41 INFO - 'NO_EM_RESTART': '1', 12:34:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:41 INFO - 'PWD': '/builds/slave/test', 12:34:41 INFO - 'SHELL': '/bin/bash', 12:34:41 INFO - 'SHLVL': '1', 12:34:41 INFO - 'TERM': 'linux', 12:34:41 INFO - 'TMOUT': '86400', 12:34:41 INFO - 'USER': 'cltbld', 12:34:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:41 INFO - '_': '/tools/buildbot/bin/python'} 12:34:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:41 INFO - Downloading/unpacking psutil>=3.1.1 12:34:41 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 12:34:41 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 12:34:41 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 12:34:41 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 12:34:41 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 12:34:41 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 12:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:34:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:34:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:34:43 INFO - Installing collected packages: psutil 12:34:43 INFO - Running setup.py install for psutil 12:34:43 INFO - building 'psutil._psutil_linux' extension 12:34:43 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 12:34:44 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 12:34:44 INFO - building 'psutil._psutil_posix' extension 12:34:44 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 12:34:44 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 12:34:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:34:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:34:44 INFO - Successfully installed psutil 12:34:44 INFO - Cleaning up... 12:34:44 INFO - Return code: 0 12:34:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:34:44 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')]} 12:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:44 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')]} 12:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:44 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:44 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 12:34:44 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 12:34:44 INFO - Using env: {'DISPLAY': ':0', 12:34:44 INFO - 'HOME': '/home/cltbld', 12:34:44 INFO - 'LANG': 'en_US.UTF-8', 12:34:44 INFO - 'LANGUAGE': 'en_US:en', 12:34:44 INFO - 'LOGNAME': 'cltbld', 12:34:44 INFO - 'MAIL': '/var/mail/cltbld', 12:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:44 INFO - 'MOZ_NO_REMOTE': '1', 12:34:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:44 INFO - 'NO_EM_RESTART': '1', 12:34:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:44 INFO - 'PWD': '/builds/slave/test', 12:34:44 INFO - 'SHELL': '/bin/bash', 12:34:44 INFO - 'SHLVL': '1', 12:34:44 INFO - 'TERM': 'linux', 12:34:44 INFO - 'TMOUT': '86400', 12:34:44 INFO - 'USER': 'cltbld', 12:34:44 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:44 INFO - '_': '/tools/buildbot/bin/python'} 12:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:34:44 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 12:34:44 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 12:34:44 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 12:34:44 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 12:34:44 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 12:34:44 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 12:34:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:34:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:34:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:34:46 INFO - Installing collected packages: mozsystemmonitor 12:34:46 INFO - Running setup.py install for mozsystemmonitor 12:34:46 INFO - Successfully installed mozsystemmonitor 12:34:46 INFO - Cleaning up... 12:34:46 INFO - Return code: 0 12:34:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:34:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:34:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:34:46 INFO - Using env: {'DISPLAY': ':0', 12:34:46 INFO - 'HOME': '/home/cltbld', 12:34:46 INFO - 'LANG': 'en_US.UTF-8', 12:34:46 INFO - 'LANGUAGE': 'en_US:en', 12:34:46 INFO - 'LOGNAME': 'cltbld', 12:34:46 INFO - 'MAIL': '/var/mail/cltbld', 12:34:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:46 INFO - 'MOZ_NO_REMOTE': '1', 12:34:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:46 INFO - 'NO_EM_RESTART': '1', 12:34:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:46 INFO - 'PWD': '/builds/slave/test', 12:34:46 INFO - 'SHELL': '/bin/bash', 12:34:46 INFO - 'SHLVL': '1', 12:34:46 INFO - 'TERM': 'linux', 12:34:46 INFO - 'TMOUT': '86400', 12:34:46 INFO - 'USER': 'cltbld', 12:34:46 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:46 INFO - '_': '/tools/buildbot/bin/python'} 12:34:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:46 INFO - Downloading/unpacking jsonschema==2.5.1 12:34:46 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 12:34:46 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 12:34:46 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 12:34:46 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 12:34:46 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 12:34:46 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 12:34:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:34:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:34:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:34:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:34:49 INFO - Downloading functools32-3.2.3-2.tar.gz 12:34:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:34:49 INFO - warning: no files found matching '*.txt' 12:34:49 INFO - no previously-included directories found matching 'build' 12:34:49 INFO - no previously-included directories found matching 'dist' 12:34:49 INFO - no previously-included directories found matching '.git*' 12:34:49 INFO - Installing collected packages: jsonschema, functools32 12:34:49 INFO - Running setup.py install for functools32 12:34:49 INFO - warning: no files found matching '*.txt' 12:34:49 INFO - no previously-included directories found matching 'build' 12:34:49 INFO - no previously-included directories found matching 'dist' 12:34:49 INFO - no previously-included directories found matching '.git*' 12:34:49 INFO - Successfully installed jsonschema functools32 12:34:49 INFO - Cleaning up... 12:34:49 INFO - Return code: 0 12:34:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:34:49 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')]} 12:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:49 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')]} 12:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:49 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:49 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 12:34:49 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 12:34:49 INFO - Using env: {'DISPLAY': ':0', 12:34:49 INFO - 'HOME': '/home/cltbld', 12:34:49 INFO - 'LANG': 'en_US.UTF-8', 12:34:49 INFO - 'LANGUAGE': 'en_US:en', 12:34:49 INFO - 'LOGNAME': 'cltbld', 12:34:49 INFO - 'MAIL': '/var/mail/cltbld', 12:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:49 INFO - 'MOZ_NO_REMOTE': '1', 12:34:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:49 INFO - 'NO_EM_RESTART': '1', 12:34:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:49 INFO - 'PWD': '/builds/slave/test', 12:34:49 INFO - 'SHELL': '/bin/bash', 12:34:49 INFO - 'SHLVL': '1', 12:34:49 INFO - 'TERM': 'linux', 12:34:49 INFO - 'TMOUT': '86400', 12:34:49 INFO - 'USER': 'cltbld', 12:34:49 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:49 INFO - '_': '/tools/buildbot/bin/python'} 12:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:49 INFO - Downloading/unpacking blobuploader==1.2.4 12:34:49 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 12:34:49 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 12:34:49 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 12:34:49 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 12:34:49 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 12:34:49 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 12:34:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:34:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:34:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 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 12:34:51 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 12:34:52 INFO - Downloading docopt-0.6.1.tar.gz 12:34:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:34:52 INFO - Installing collected packages: blobuploader, requests, docopt 12:34:52 INFO - Running setup.py install for blobuploader 12:34:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:34:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:34:52 INFO - Running setup.py install for requests 12:34:52 INFO - Running setup.py install for docopt 12:34:52 INFO - Successfully installed blobuploader requests docopt 12:34:52 INFO - Cleaning up... 12:34:52 INFO - Return code: 0 12:34:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:52 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:52 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 12:34:52 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 12:34:52 INFO - Using env: {'DISPLAY': ':0', 12:34:52 INFO - 'HOME': '/home/cltbld', 12:34:52 INFO - 'LANG': 'en_US.UTF-8', 12:34:52 INFO - 'LANGUAGE': 'en_US:en', 12:34:52 INFO - 'LOGNAME': 'cltbld', 12:34:52 INFO - 'MAIL': '/var/mail/cltbld', 12:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:52 INFO - 'MOZ_NO_REMOTE': '1', 12:34:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:52 INFO - 'NO_EM_RESTART': '1', 12:34:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:52 INFO - 'PWD': '/builds/slave/test', 12:34:52 INFO - 'SHELL': '/bin/bash', 12:34:52 INFO - 'SHLVL': '1', 12:34:52 INFO - 'TERM': 'linux', 12:34:52 INFO - 'TMOUT': '86400', 12:34:52 INFO - 'USER': 'cltbld', 12:34:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:52 INFO - '_': '/tools/buildbot/bin/python'} 12:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:34:53 INFO - Running setup.py (path:/tmp/pip-jOA_C8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:34:53 INFO - Running setup.py (path:/tmp/pip-yCFza5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:34:53 INFO - Running setup.py (path:/tmp/pip-IAtjxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:34:53 INFO - Running setup.py (path:/tmp/pip-5QmvtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:34:53 INFO - Running setup.py (path:/tmp/pip-CkGh3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:34:53 INFO - Running setup.py (path:/tmp/pip-2zm6Uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:34:53 INFO - Running setup.py (path:/tmp/pip-kUkgzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:34:53 INFO - Running setup.py (path:/tmp/pip-PAMGkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:34:53 INFO - Running setup.py (path:/tmp/pip-H4QL7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:34:53 INFO - Running setup.py (path:/tmp/pip-4Wkiht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:34:53 INFO - Running setup.py (path:/tmp/pip-Z53E70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:34:53 INFO - Running setup.py (path:/tmp/pip-jN_Nxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:34:53 INFO - Running setup.py (path:/tmp/pip-hDJ48H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:34:53 INFO - Running setup.py (path:/tmp/pip-i3fuxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:54 INFO - Running setup.py (path:/tmp/pip-6eO3_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:34:54 INFO - Running setup.py (path:/tmp/pip-Iuctep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:34:54 INFO - Running setup.py (path:/tmp/pip-_ZnMfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:34:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:34:54 INFO - Running setup.py install for manifestparser 12:34:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:34:54 INFO - Running setup.py install for mozcrash 12:34:54 INFO - Running setup.py install for mozdebug 12:34:54 INFO - Running setup.py install for mozdevice 12:34:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:34:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:34:54 INFO - Running setup.py install for mozfile 12:34:54 INFO - Running setup.py install for mozhttpd 12:34:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:34:54 INFO - Running setup.py install for mozinfo 12:34:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:34:54 INFO - Running setup.py install for mozInstall 12:34:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Running setup.py install for mozleak 12:34:55 INFO - Running setup.py install for mozlog 12:34:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Running setup.py install for moznetwork 12:34:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Running setup.py install for mozprocess 12:34:55 INFO - Running setup.py install for mozprofile 12:34:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Running setup.py install for mozrunner 12:34:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:34:55 INFO - Running setup.py install for mozscreenshot 12:34:55 INFO - Running setup.py install for moztest 12:34:55 INFO - Running setup.py install for mozversion 12:34:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:34:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:34:56 INFO - Cleaning up... 12:34:56 INFO - Return code: 0 12:34:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:34: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')]} 12:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34: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')]} 12:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:56 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:56 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 12:34:56 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 12:34:56 INFO - Using env: {'DISPLAY': ':0', 12:34:56 INFO - 'HOME': '/home/cltbld', 12:34:56 INFO - 'LANG': 'en_US.UTF-8', 12:34:56 INFO - 'LANGUAGE': 'en_US:en', 12:34:56 INFO - 'LOGNAME': 'cltbld', 12:34:56 INFO - 'MAIL': '/var/mail/cltbld', 12:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:56 INFO - 'MOZ_NO_REMOTE': '1', 12:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:56 INFO - 'NO_EM_RESTART': '1', 12:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:56 INFO - 'PWD': '/builds/slave/test', 12:34:56 INFO - 'SHELL': '/bin/bash', 12:34:56 INFO - 'SHLVL': '1', 12:34:56 INFO - 'TERM': 'linux', 12:34:56 INFO - 'TMOUT': '86400', 12:34:56 INFO - 'USER': 'cltbld', 12:34:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:56 INFO - '_': '/tools/buildbot/bin/python'} 12:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:34:56 INFO - Running setup.py (path:/tmp/pip-RiwDeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:34:56 INFO - Running setup.py (path:/tmp/pip-rRObOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:34:56 INFO - Running setup.py (path:/tmp/pip-VvqQCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:34:56 INFO - Running setup.py (path:/tmp/pip-MwLUut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:34:56 INFO - Running setup.py (path:/tmp/pip-IVopve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:34:56 INFO - Running setup.py (path:/tmp/pip-aTM7up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:34:56 INFO - Running setup.py (path:/tmp/pip-5HtiIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:34:56 INFO - Running setup.py (path:/tmp/pip-7pWc0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:34:56 INFO - Running setup.py (path:/tmp/pip-s3artI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:34:56 INFO - Running setup.py (path:/tmp/pip-wu4tx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:34:56 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)) 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:34:56 INFO - Running setup.py (path:/tmp/pip-3wL2mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:34:57 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)) 12:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:34:57 INFO - Running setup.py (path:/tmp/pip-lVj_w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 12:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:34:57 INFO - Running setup.py (path:/tmp/pip-BNni0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:34:57 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)) 12:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:34:57 INFO - Running setup.py (path:/tmp/pip-boSE6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 12:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:57 INFO - Running setup.py (path:/tmp/pip-GeBQ4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:57 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)) 12:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:34:57 INFO - Running setup.py (path:/tmp/pip-Owj8R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:34:57 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)) 12:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:34:57 INFO - Running setup.py (path:/tmp/pip-NUIjdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:34:57 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)) 12:34:57 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)) 12:34:57 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)) 12:34:57 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)) 12:34:57 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)) 12:34:57 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)) 12:34:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:34:57 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 12:34:57 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 12:34:57 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 12:34:57 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 12:34:57 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 12:34:57 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 12:34:59 INFO - Downloading blessings-1.6.tar.gz 12:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:34:59 INFO - Installing collected packages: blessings 12:34:59 INFO - Running setup.py install for blessings 12:34:59 INFO - Successfully installed blessings 12:34:59 INFO - Cleaning up... 12:34:59 INFO - Return code: 0 12:34:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:34:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:34:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:34:59 INFO - Reading from file tmpfile_stdout 12:34:59 INFO - Current package versions: 12:34:59 INFO - argparse == 1.2.1 12:34:59 INFO - blessings == 1.6 12:34:59 INFO - blobuploader == 1.2.4 12:34:59 INFO - docopt == 0.6.1 12:34:59 INFO - functools32 == 3.2.3-2 12:34:59 INFO - jsonschema == 2.5.1 12:34:59 INFO - manifestparser == 1.1 12:34:59 INFO - mozInstall == 1.12 12:34:59 INFO - mozcrash == 0.17 12:34:59 INFO - mozdebug == 0.1 12:34:59 INFO - mozdevice == 0.48 12:34:59 INFO - mozfile == 1.2 12:34:59 INFO - mozhttpd == 0.7 12:34:59 INFO - mozinfo == 0.9 12:34:59 INFO - mozleak == 0.1 12:34:59 INFO - mozlog == 3.2 12:34:59 INFO - moznetwork == 0.27 12:34:59 INFO - mozprocess == 0.22 12:34:59 INFO - mozprofile == 0.28 12:34:59 INFO - mozrunner == 6.11 12:34:59 INFO - mozscreenshot == 0.1 12:34:59 INFO - mozsystemmonitor == 0.3 12:34:59 INFO - moztest == 0.7 12:34:59 INFO - mozversion == 1.4 12:34:59 INFO - psutil == 3.1.1 12:34:59 INFO - requests == 1.2.3 12:34:59 INFO - wsgiref == 0.1.2 12:34:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:34:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:59 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:34:59 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 12:34:59 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 12:34:59 INFO - Using env: {'DISPLAY': ':0', 12:34:59 INFO - 'HOME': '/home/cltbld', 12:34:59 INFO - 'LANG': 'en_US.UTF-8', 12:34:59 INFO - 'LANGUAGE': 'en_US:en', 12:34:59 INFO - 'LOGNAME': 'cltbld', 12:34:59 INFO - 'MAIL': '/var/mail/cltbld', 12:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:59 INFO - 'MOZ_NO_REMOTE': '1', 12:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:59 INFO - 'NO_EM_RESTART': '1', 12:34:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:59 INFO - 'PWD': '/builds/slave/test', 12:34:59 INFO - 'SHELL': '/bin/bash', 12:34:59 INFO - 'SHLVL': '1', 12:34:59 INFO - 'TERM': 'linux', 12:34:59 INFO - 'TMOUT': '86400', 12:34:59 INFO - 'USER': 'cltbld', 12:34:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:59 INFO - '_': '/tools/buildbot/bin/python'} 12:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 INFO - Cleaning up... 12:35:00 INFO - Return code: 0 12:35:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:35:00 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')]} 12:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:00 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')]} 12:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:00 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 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:35:00 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 12:35:00 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 12:35:00 INFO - Using env: {'DISPLAY': ':0', 12:35:00 INFO - 'HOME': '/home/cltbld', 12:35:00 INFO - 'LANG': 'en_US.UTF-8', 12:35:00 INFO - 'LANGUAGE': 'en_US:en', 12:35:00 INFO - 'LOGNAME': 'cltbld', 12:35:00 INFO - 'MAIL': '/var/mail/cltbld', 12:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:00 INFO - 'MOZ_NO_REMOTE': '1', 12:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:35:00 INFO - 'NO_EM_RESTART': '1', 12:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:00 INFO - 'PWD': '/builds/slave/test', 12:35:00 INFO - 'SHELL': '/bin/bash', 12:35:00 INFO - 'SHLVL': '1', 12:35:00 INFO - 'TERM': 'linux', 12:35:00 INFO - 'TMOUT': '86400', 12:35:00 INFO - 'USER': 'cltbld', 12:35:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:00 INFO - '_': '/tools/buildbot/bin/python'} 12:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:35:00 INFO - Cleaning up... 12:35:00 INFO - Return code: 0 12:35:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:35:00 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')]} 12:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:00 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')]} 12:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:00 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7c1aedff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ef350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', '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 12:35:00 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', 'flake8'] in /builds/slave/test/build 12:35:00 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 flake8 12:35:00 INFO - Using env: {'DISPLAY': ':0', 12:35:00 INFO - 'HOME': '/home/cltbld', 12:35:00 INFO - 'LANG': 'en_US.UTF-8', 12:35:00 INFO - 'LANGUAGE': 'en_US:en', 12:35:00 INFO - 'LOGNAME': 'cltbld', 12:35:00 INFO - 'MAIL': '/var/mail/cltbld', 12:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:00 INFO - 'MOZ_NO_REMOTE': '1', 12:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:35:00 INFO - 'NO_EM_RESTART': '1', 12:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:00 INFO - 'PWD': '/builds/slave/test', 12:35:00 INFO - 'SHELL': '/bin/bash', 12:35:00 INFO - 'SHLVL': '1', 12:35:00 INFO - 'TERM': 'linux', 12:35:00 INFO - 'TMOUT': '86400', 12:35:00 INFO - 'USER': 'cltbld', 12:35:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:00 INFO - '_': '/tools/buildbot/bin/python'} 12:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:00 INFO - Downloading/unpacking flake8 12:35:00 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 12:35:00 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 12:35:00 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 12:35:00 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 12:35:00 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 12:35:00 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 12:35:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:35:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:35:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:35:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:35:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:35:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:35:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:35:03 INFO - no previously-included directories found matching 'docs/_build' 12:35:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:35:03 INFO - Running setup.py install for pep8 12:35:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:35:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:35:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:35:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:35:03 INFO - no previously-included directories found matching 'docs/_build' 12:35:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:35:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:35:03 INFO - Cleaning up... 12:35:03 INFO - Return code: 0 12:35:03 INFO - Running post-action listener: _resource_record_post_action 12:35:03 INFO - Running post-action listener: _start_resource_monitoring 12:35:03 INFO - Starting resource monitoring. 12:35:03 INFO - ##### 12:35:03 INFO - ##### Finished create-virtualenv step (success) 12:35:03 INFO - ##### 12:35:03 INFO - ##### 12:35:03 INFO - ##### Running install step. 12:35:03 INFO - ##### 12:35:03 INFO - Running pre-action listener: _resource_record_pre_action 12:35:03 INFO - Running main action method: install 12:35:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:03 INFO - Reading from file tmpfile_stdout 12:35:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:35:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:35:03 INFO - Reading from file tmpfile_stdout 12:35:03 INFO - Output received: 12:35:03 INFO - Usage: mozinstall [options] installer 12:35:03 INFO - Options: 12:35:03 INFO - -h, --help show this help message and exit 12:35:03 INFO - -d DEST, --destination=DEST 12:35:03 INFO - Directory to install application into. [default: 12:35:03 INFO - "/builds/slave/test"] 12:35:03 INFO - --app=APP Application being installed. [default: firefox] 12:35:03 INFO - mkdir: /builds/slave/test/build/application 12:35:03 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'] 12:35:03 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 12:35:19 INFO - Reading from file tmpfile_stdout 12:35:19 INFO - Output received: 12:35:19 INFO - /builds/slave/test/build/application/firefox/firefox 12:35:19 INFO - Running post-action listener: _resource_record_post_action 12:35:19 INFO - ##### 12:35:19 INFO - ##### Finished install step (success) 12:35:19 INFO - ##### 12:35:19 INFO - ##### 12:35:19 INFO - ##### Running run-tests step. 12:35:19 INFO - ##### 12:35:19 INFO - Running pre-action listener: _resource_record_pre_action 12:35:19 INFO - Running main action method: run_tests 12:35:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:35:19 INFO - Python 2.7.3 12:35:19 INFO - Return code: 0 12:35:19 INFO - grabbing minidump binary from tooltool 12:35:19 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')]} 12:35:19 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 0x197e1c0>, '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 0x197f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197f550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:35:19 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 12:35:19 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 12:35:19 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 12:35:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:35:19 INFO - Return code: 0 12:35:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:35:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:35:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:35:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:35:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:35:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:35:20 INFO - Return code: 0 12:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 12:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 12:35:20 INFO - Using env: {'DISPLAY': ':0', 12:35:20 INFO - 'HOME': '/home/cltbld', 12:35:20 INFO - 'LANG': 'en_US.UTF-8', 12:35:20 INFO - 'LANGUAGE': 'en_US:en', 12:35:20 INFO - 'LOGNAME': 'cltbld', 12:35:20 INFO - 'MAIL': '/var/mail/cltbld', 12:35:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:35:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:20 INFO - 'MOZ_NO_REMOTE': '1', 12:35:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:35:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:35:20 INFO - 'NO_EM_RESTART': '1', 12:35:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:20 INFO - 'PWD': '/builds/slave/test', 12:35:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:35:20 INFO - 'SHELL': '/bin/bash', 12:35:20 INFO - 'SHLVL': '1', 12:35:20 INFO - 'TERM': 'linux', 12:35:20 INFO - 'TMOUT': '86400', 12:35:20 INFO - 'USER': 'cltbld', 12:35:20 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910', 12:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:20 INFO - '_': '/tools/buildbot/bin/python'} 12:35:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 12:35:20 INFO - mozversion application_buildid: 20160609120133 12:35:20 INFO - mozversion application_changeset: 4d03f2c0d7175b2f37f21b02d14316439dbc56e6 12:35:20 INFO - mozversion application_display_name: Nightly 12:35:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:35:20 INFO - mozversion application_name: Firefox 12:35:20 INFO - mozversion application_remotingname: firefox 12:35:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:35:20 INFO - mozversion application_vendor: Mozilla 12:35:20 INFO - mozversion application_version: 50.0a1 12:35:20 INFO - mozversion platform_buildid: 20160609120133 12:35:20 INFO - mozversion platform_changeset: 4d03f2c0d7175b2f37f21b02d14316439dbc56e6 12:35:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:35:20 INFO - mozversion platform_version: 50.0a1 12:35:20 INFO - using testdate: 1465500920 12:35:20 INFO - actual date: 1465500920 12:35:20 INFO - starting webserver on 'localhost:48270' 12:35:20 INFO - SUITE-START | Running 2 tests 12:35:20 INFO - TEST-START | dromaeo_css 12:35:20 INFO - operating with platform_type : linux_ 12:35:20 INFO - Initialising browser for dromaeo_css test... 12:35:20 INFO - TEST-INFO | started process 15889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUHyhi5/profile http://localhost:48270/getInfo.html) 12:35:25 INFO - PROCESS | 15889 | __metrics Screen width/height:1600/1200 12:35:25 INFO - PROCESS | 15889 | colorDepth:24 12:35:25 INFO - PROCESS | 15889 | Browser inner width/height: 1024/697 12:35:25 INFO - PROCESS | 15889 | __metrics 12:35:26 ERROR - PROCESS | 15889 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:35:26 INFO - PROCESS | 15889 | console.error: PushService: 12:35:26 INFO - PROCESS | 15889 | stateChangeProcessEnqueue: Error transitioning state 12:35:26 INFO - PROCESS | 15889 | UnknownError 12:35:27 INFO - TEST-INFO | 15889: exit 0 12:35:27 INFO - Browser initialized. 12:35:27 INFO - Running cycle 1/1 for dromaeo_css test... 12:35:27 INFO - TEST-INFO | started process 15969 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUHyhi5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:35:39 INFO - PROCESS | 15969 | RSS: Main: 174624768 12:35:39 INFO - PROCESS | 15969 | 12:36:31 INFO - PROCESS | 15969 | 1465500991141 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] 12:38:42 INFO - PROCESS | 15969 | Cycle 1(1): loaded http://localhost:48270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48270/tests/dromaeo/cssquery-ext.html) 12:38:43 INFO - PROCESS | 15969 | RSS: Main: 257249280 12:38:43 INFO - PROCESS | 15969 | 12:41:46 INFO - PROCESS | 15969 | Cycle 1(1): loaded http://localhost:48270/tests/dromaeo/cssquery-ext.html (next: http://localhost:48270/tests/dromaeo/cssquery-jquery.html) 12:41:47 INFO - PROCESS | 15969 | RSS: Main: 265580544 12:41:47 INFO - PROCESS | 15969 | 12:44:49 INFO - PROCESS | 15969 | Cycle 1(1): loaded http://localhost:48270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48270/tests/dromaeo/cssquery-mootools.html) 12:44:49 INFO - PROCESS | 15969 | RSS: Main: 260075520 12:44:49 INFO - PROCESS | 15969 | 12:47:52 INFO - PROCESS | 15969 | Cycle 1(1): loaded http://localhost:48270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48270/tests/dromaeo/cssquery-prototype.html) 12:47:52 INFO - PROCESS | 15969 | RSS: Main: 260644864 12:47:52 INFO - PROCESS | 15969 | 12:50:55 INFO - PROCESS | 15969 | Cycle 1(1): loaded http://localhost:48270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48270/tests/dromaeo/cssquery-yui.html) 12:50:56 INFO - PROCESS | 15969 | RSS: Main: 248266752 12:50:56 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | Cycle 1(1): loaded http://localhost:48270/tests/dromaeo/cssquery-yui.html (next: http://localhost:48270/tests/dromaeo/cssquery-dojo.html) 12:53:59 INFO - PROCESS | 15969 | RSS: Main: 295657472 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | __start_tp_report 12:53:59 INFO - PROCESS | 15969 | _x_x_mozilla_page_load 12:53:59 INFO - PROCESS | 15969 | _x_x_mozilla_page_load_details 12:53:59 INFO - PROCESS | 15969 | |i|pagename|runs| 12:53:59 INFO - PROCESS | 15969 | |0;dojo.html;2550.1329547953696;2573.3309339571706;2588.844669319842;2592.4815036992595;2592.624069509921;4567.4062371891605;4667.089917466087;4673.252279635255;4725.834595789148;4740.691855029425;4657.115148121855;4660.650451216164;4684.695494792844;4691.5073917238715;4694.507076756942;4268.42376279203;4302.161078589676;4340.782960851945;4366.934495982547;4493.033997690496;4408.052268762215;4442.511323754385;4447.621952134073;4468.307412351074;4499.347594598781;4173.123644034741;4189.7695626740515;4192.098698779777;4223.64099051581;4249.830006799724;4305.13897220554;4316.3957046013265;4330.39374487572;4354.54277300882;4365.2142614329405;4345.782710864444;4346.1525002624185;4363.454568178978;4396.670249731283;4397.956020439787;4522.547745225451;4645.442546894361;4664.160451118797;4673.228917228653;4789.784459699322;4988.8503344899345;4991.625628077909;5030.3963524377305;5044.066847633199;5048.29323894655;5026.6983980960895;5054.039732450822;5058.367704036996;5062.949370506285;5071.8985620287385;4495.707778994388;4680.110778951988;4726.243800991825;4731.858044258678;4789.712617242976;5255.710935898563;5262.789488420497;5273.499017593323;5275.604329675213;5283.6037297202865;4707.058588282357;5039.143345631252;5040.79836806531;5048;5054.747262636927;4946.802127914915;4988.5260900214425;4991.201407774811;4991.226359914219;5014.498550145029;4954.677945933503;4957.553820156131;4957.727324997088;4966.478519755446;5013.498650134958;4762.023785124041;4899.485554016777;4934.481879402683;4942.555170034714;4993.101241776442;4179.080602267496;4200.642945349679;4204.2432362175105;4230.386593943861;4246.723962942398;3672.375696131664;3699.130704284491;3703.500027496307;3732.79469629173;3739.7195210358786;4292.441982542312;4347.6304514116655;4371.650267978554;4412.448443944507;4418.668599855024;5008.949910500848;5019.598432125421;5020.974895125501;5038.118329292359;5055.772490237948;4364.738115713068;4393.978030109829;4408.669349798778;4411.963188650665;4459.687821852504;4349.543297953733;4401.097774956126;4401.427814384109;4505.504394516601;4520.728756274569;4749.050189961952;4791.928121078186;4810.8797280068275;4816.927746083882;4850.442199147002;5070.391553013662;5083.796648134034;5088.516590923857;5096.974515127401;5115.846524604268;4653.139169253699;4765.9046819064115;4803.159447096674;4807.471178170468;4851.126797176401;4879.316895634543;4953.479884612064;4954.603631709527;4957.529034741694;4960.082384758831;5002.499750024968;5014.222795466709;5017.799288028584;5018.447970723145;5018.84943451697;4865.56209941107;4878;4915.287283343967;4933;5010.323606313101;4878.219484882402;4903.509649035068;4913.213885778258;4915.262710593297;4966;4866.732329721899;4877.146499362527;4893.09477746618;4919.065377578248;4933.753312334297;4710.882227944328;4912.361393018885;4971.37857767779;4980.203167493184;4994.875128121826;4879.4876537963;4884.63365247615;4887.511248875084;4888;4939 12:53:59 INFO - PROCESS | 15969 | |1;ext.html;569.0241236279782;572.2617823008319;574.4198359656747;578.6007654718244;579.9565032622554;37969.62030379695;39729.40405893914;40077.599224007754;43312;43336.56663433365;41039;41502.79248603753;41531;41539.58460415395;41586.79206603963;7897.684092636302;8017.559034253114;8031.518108913455;8034.799130021767;8043.03483581971;7868.6852525899185;7962.442629015971;7964.641591128384;7966.5618390988475;7972.402069844756;7993.1607181246;8014.158513356072;8020.919790802105;8025.357971362306;8026.518408895485;7950.045994480642;7975;7988.880166797503;7991.640376183086;7991.680332786682;7871.4883532570775;7907.48601340911;7908.683652653887;7910.881336779953;7938.206179382073;38134.42798357999;38378.23243535141;38458.80770596157;38460.80769596162;38483.61516384856;2708.1740069278862;2800.3979144484033;2895.580140879582;2899.304166999926;3037.407705497429;28901.710982890323;29364.26589335262;29509;29673.70326296731;30607.540886886716;3473.5484387029524;3488.4069708149677;3534.7525673202886;3559.3771090059136;3599.8740044098595;245273;245970;247040;247194.76402618052;248819;55404.44595553993;55436.16845747317;55708.72145639246;55796;55813.16280255799;49628.75185624049;55977.44022559804;57165;57326.7133664329;57379;15187.696246075016;15633.218339083;15650.295736691876;15669;15681.607959800867;11811.586594469152;11928.224665396721;11933.761324773455;11933.940330298467;11937.28376297425;15639.68720625581;18290;18354.35759748429;19103;19130.330438434867;6689.6989635466525;6706.798796036127;6935.375816176568;6942.271061538567;7028.894566581505;790.5414859381544;829.2329595124486;982.2534873496052;1024.4621573673733;1026.0611540440564;27663.58504622432;28217.71782282192;28218;28253.293667658472;28563.57154642682;962.7737481691797;987.9012098790207;1005.3163848583034;1035.880873699519;1049.8897615750388;14556.708865822837;14900.472611915467;15020.699586008219;15036.323365448581;15109.168995705122;394595;394635;397233;399825;400962;50850;51327.743361283705;51334.74332628313;51393.229101563506;51512;1168.3574034280941;1174.7557820765207;1186.851643544557;1219.524385489642;1225.7609766095525;25434.872825635754;26844;26945.191644250703;27191.184264472096;27512.862435687694;41965.58034419617;42672.1465570693;42699.57300426956;42740.78629606832;42751.78624106859;327188;338632;340792;341125;341134;21594;22210.222642207445;24258.63612045856;25193.748062519142;25574.36064098338;37089.25821483609;42760.358594620484;42814.35778463263;43002.78498607487;43141.784291078344;36094.8195259022;36454.8177259112;36751.632483674824;37164.81417592895;37374.8131259342;36231.27537449289;36275.45586816253;36615.81692091629;36629.450558241115;37026.62973370232;35341;35736.821315894296;36365;36738.8163059183;36784.44823327706;31793.5230971531;32954.34091318112;32985.34029319352;32992.67007329896;33156.1710957228;24415.208328132696;24993.50012999766;25073.623895641216;25151.572659987494;25280.873595631903 12:53:59 INFO - PROCESS | 15969 | |2;jquery.html;335.4582349505549;353.33043881843906;359.2581319575077;360.8286064119543;375.13344174955836;12581.622551323451;13665.863341366596;15746.84253157468;15850.20748962553;16160.434384796534;16038.679226415465;16229.91885040576;16404.09777462239;16519.504414867544;16520.917395413035;4858.149823780833;5008.9499105008845;5065.670731402465;5070.239464080399;5092.057969275677;4910.975445122769;4933.531314525132;4993.700377977333;4998.8500344989525;5037.899242015157;4954.826581069663;4987.1521841286885;4992.425871024842;4993.176125939216;4993.226049962262;5231.581473482112;5538.6399884007615;5540;5601.187827764992;5615.241942337814;5242.292290540767;5344.43883392246;5385.515303622654;5404.135338345885;5422.783088676488;12077.879221207764;12135.575254866126;12171.634850954486;12222.144449888512;12257.203281786655;1792.0770803036446;1836.2563161919445;1910.7993660665709;2132.968005479919;2284.303287497313;9004.59479323432;9054.909450905472;9554.761130971712;9719.757006074835;13217.867821321855;1513.2887542854842;1545.3509525998995;1563.9061656300657;1583.8617865881083;1629.8125715542747;64349;66590.66704666446;67352;68853.31146688569;69149;8534.146585341541;9603.37578057438;9723.124918757347;9870.60517579303;9957.153641940371;12120.75758484843;12171.939140304241;13086;13106;13491.595252142451;7262.092238470192;7343.228960959129;7408.666610002563;7503.587302698404;7537.283958023979;6122.387761223842;6465.77369792055;7185.353318201387;7206.387457066208;7337.816554586178;3791.6018818024263;3992.6007399259843;4050.7772072536295;4068.06434520062;4085.3667681509414;1720.8881422707734;1731.5930756272267;1758.9384371546935;1790.3465235189062;1799.8650101242458;823.6417158536055;852.0882655558493;864.265374431728;874.4141425245101;935.3218916285639;15982.280797364147;16082.597935051483;16317.265723042254;16456.012639241446;16675.332986680398;831.2934006094771;837.3385025829648;847.9830403391898;893.3523195683208;899.150302963706;8926.152015558511;9222.584983675752;9265.861012084824;9304.069593040642;9471.147596716328;378171;380120;381894;386318;392969;79633.60183199047;80090.5995470019;80227.59886200649;80290.59854700688;80755;889.6085722282176;896.9596368163449;903.7966457547;907.3603109807569;908.6047569307373;14563.976655661085;14569.34437950316;14616.049956752777;14641.19473428929;15144.772828407804;35255.64744352524;35593.82203088968;36462;36638.267234654624;36874.262514750095;47416.7629161852;51246;52463.85216886999;52726;52867.20699189438;7612.429067820047;7679.347255483346;7733.613319333898;7889.6449659766595;7932.841343172989;8389.538575378412;11202.887971120184;11321.094312455149;11482.368469734245;11806.527738890349;9593.472359020321;9962.252831037846;10014.347632640533;10178.592856285666;10259.794804103727;9800.754981125529;10193.541290642088;10205.795884082425;10450.320729152885;10536.736581585215;9495.762605934631;9979.309724429506;10134.138598218939;10135.645058786064;10344.327618705061;6498;6722.495812813961;6818.045473633596;7598.430117741302;7974.282314591715;9310.72067837966;9640.90359096428;9851.901480985098;9868.706678443768;9963.103320701171 12:53:59 INFO - PROCESS | 15969 | |3;mootools.html;1119.972000699982;1373.9587812365633;1382.7511048011356;1481.859223373779;1676.4383931382986;6629.602223866571;7517.060367454068;7679.654415551299;7752.728654497094;7811.3360364368955;7632.427567932399;7808.258215469536;7814.609269536515;7831.216878312166;7842.451028428012;1832.743415921772;1879.4455635587535;1882.8305452509271;2168.4470460032708;2207.0178770447155;1817.6728188925988;2120.300300900699;2211.8783466909395;2215.46828761097;2221.6556433752794;2145.0776166248506;2174.662927246279;2175.771543987882;2178.0852042142337;2182.2362173239317;1764.5412192829829;2116.9364919052455;2121.459027947879;2127.9680804788018;2128.4891626009644;2081.3651836189956;2168.0568952505555;2168.9891550542147;2173.163332117146;2173.4349069241953;7857.607119643995;7866.488678235954;7896.565688887109;7910.7231246906085;7913.920860791376;4438.423005009328;4546.590806827401;4547.795349209294;4551.203539380595;4564.7717614119165;2630.395009147907;2739.2193224930797;2770.473610014111;2780;2785.9860700696568;859.8332202848849;881.1320848963766;888.4047688049022;897.3270047464401;899.5592159841696;10229.38623682574;10240.948795255976;10262.435566043865;10284.742881428025;10319.79360412795;4033.092554175287;4061.3704875744306;4109.835606575763;4153.813078411479;4164.208800327928;8715.782105447415;8718.912810871938;8730.257928076058;8759.956200218958;8760.91239087601;4454.487733910577;4459.10817836434;4476.664250181184;4479.148961697267;4485.753283569435;3456.2396272819938;3545.2909418115964;4159.251334759772;4201.369794530845;4219.894502637459;3510.140015696171;3625.9093522661615;3678.448232765053;3690.538682664667;3717.0893131183034;1141.8972292493715;1197.108154424959;1208.2086233517061;1210.3369316323221;1210.5460452330376;815.9143289954588;833.3458235285271;849.1381248033251;850.8425941200898;851.2041241439274;5161.690298582097;5318.867028324323;5380.757865896044;5396;5509.201165830932;853.3557164341007;866.8699695045793;889.7508697564692;892.7054072156172;905.6875377994581;3493.7205023598053;3533.169705119295;3649.7445178837756;3680.4479328100465;3690.9630903691154;9248.815023699623;9490.952545237367;9659.951700241594;9665.71002869915;9694.61221551144;9396.67111651089;9525.57134928916;9539.57071931751;9561.856572151424;9592.040795920351;6723.798286051328;7285.854282914312;7353.742619008416;7380.852382952311;7409.962950185322;5480.6437581557075;5509.779608815603;5541.362743284574;5620.100783874561;5664.631798933056;8830.205281524693;8894.822103558023;8941.239994600272;8956.507392093497;8984.820303594022;8123.5532045738055;8442.704505342284;8447.113053129333;8586.441881277697;9037.50293733851;5537.640053396516;6132.816015519542;6484.578502397329;6638.203415589967;6671.032700258533;7250.202477727551;7426.74006409773;7427.665755040928;7456.47804653669;7468.5145465544565;6520.673966301761;6680.331966803281;6692.364225398579;6729.798106056826;6761.0534525167495;6290.24517057956;6385;6408.487321014214;6619.6028238303925;6634.004899264955;6059.151718759289;6062.090686397076;6108.725107370268;6129.84675383119;6152.354002829638;4205.032842446193;4389.648828093808;4500.369948207188;4570.6800523963;4615.353850460871;4431.512533621235;4454.420925279823;4500.054988452331;4565.954340456553;4627.074585083064 12:53:59 INFO - PROCESS | 15969 | |4;prototype.html;287.81579788935085;294.9616549848519;295.28540930947105;296.0777179087143;299.36683913522904;4754.714717116975;4800.759962001895;4807.855764327067;4814.9037019259595;4820.325154478376;4706.764661766915;4734.100520901035;4764.475907650156;4778.856634300968;4813.326134341195;3544.184837487373;3636.4181730923056;3648.7628304160276;3750.5499340079227;3780.962190378102;3700.5374328208973;3720.795356255405;3739.551253849542;3751.8686845960397;3763.833211704367;3538.4161613333767;3711.7772933623933;3722.571904231021;3724.2179142380132;3749.887503374903;3491.7555771096036;3625.673689367943;3639.909002274938;3655.232401195752;3726.850925962958;3268.8038717677014;3295.40682677118;3297.3405318936307;3298.950515742266;3299.472084466498;4587.8623641290815;4617.884552886171;4634.55971682693;4640.83757068501;4652.767361631905;3946.9013274668077;4153.979230103861;4163.895902602429;4259.446271984622;4284.443022407098;4933.7286449245275;4934.284528743348;4946.356973593446;4948;4986.656271552364;3495.370833250014;3521.1725244567506;3533.4169861973;3534.487499312611;3539.699125574329;5052.115879721071;5203.245529398254;5209.557187639055;5221.686698798047;5245.816396426107;3546;3547.9290414192087;3577.6422357764013;3579.319929213441;3584.229390681016;3591.964080359163;3610.909727256787;3614.2771445710964;3635.581908080552;3635.690966267897;4184.351425529047;4186.832526698898;4190.559991200941;4206.705530612889;4214.641755450821;4854.538818812207;4878.365812444359;4902.803887844518;4971.552560269593;4991.975040124777;1237.9195352302072;1250.106174085515;1253.8401978170157;1255.5605538061789;1262.419287127913;775.3409601838392;784.807093218305;797.960101994898;799.880017997305;814.6497006287353;4332.848350307724;4439.689221754511;4443.311286750559;4453.799579018952;4472.195004899084;5084.872878178076;5106.234064890296;5108.514691104339;5126.66676666023;5158.174692049329;4398.098389830141;4404.71369360997;4434.623057040123;4445.37743723545;4445.599896009375;2559.987200063988;2726.7954903382324;2731.426400455887;2731.562949928002;2734.234414363981;4821.252705830602;4851.205054129994;4890.731009794425;4898.485659005824;4903.289023091632;4949.451032718153;5240.318758561439;5252.684838909678;5264.9736751315995;5290.603204759658;4809.134355815917;4846.95153048465;4854.417469903634;4885.2427873679635;4892.241702536113;5127.230915362726;5129.9743501282255;5135.10135726239;5143.742812859267;5160.845174644766;4788.186008378653;5020.447750747488;5032.27032080339;5033.92449113271;5036.823711170092;4918.4343800461975;4925.556699896883;4935.09998650515;4936.2969775428855;4959.611308833532;3589.766665166756;3616.5479315085613;3620;3634.655177584311;3640.3265395901844;5056.443791183041;5195.298634684269;5197.376314842243;5229.13719236337;5232.790688372423;4973;4976.2286845539;4984.1526940420945;4989.575886049726;5017.523335283142;4953.529414705597;5002.224655178307;5023.497650234947;5031.773570189277;5055.140626093499;4798.49615790337;4949.183384741621;4964.826231081895;5015.724135172601;5016.749162541785;4843.927341089816;4947.727874966911;4956;4985.326980857538;5116.437191908813;4798.616110711065;4831.275308703723;4839.854804355875;4909;4933.605311575137 12:53:59 INFO - PROCESS | 15969 | |5;yui.html;593.7773334999378;605.3918644876536;614.6557927560564;615.8430593493918;636.369993706231;11631.534738610457;11709.53161873524;12016.639500814968;12144.878551214486;12187.634370968863;11930.343831089287;12130.211536250159;12137.696557586065;12151.513939442433;12156.088293377989;622.2564035976986;758.3402439877287;768.6310570925945;787.1066339704448;789.1556035042507;769.9538027718327;782.0654318089885;782.295933659708;782.6986610155103;782.8316911863948;816.3183741575791;821.195228675898;822.0505316359585;822.411975437562;822.9753107406757;653.8757636049183;799.2606838674203;815.824597711492;817.0236567302077;817.9631916563769;600.0848705723806;601.2424345324879;601.2724603230097;601.7081715368043;602.0066889632099;11759.118066145073;11762.647120586396;11776.411179440993;11778.293302401884;11780.882191178065;11520.366379849105;11835.22471629254;11916.212568114766;11936.641900742992;12055.517779288817;10046;10100;10423.114035307008;10512.526936287886;10545.947270263676;4101.835926562934;4140.171965606861;4165.208610364021;4179.2895207814745;4242.787860606957;53828.461715382735;53885.1917221242;53988.46011539913;54038;54055.729721351534;8830.86753698695;9022.729318120468;9083.50040747752;9248.630054797733;9922.305438619373;17690.29238830458;17720.645587088446;17726.11369431549;17734.20196091179;17735.556611084827;6269.937300627026;6318.399752023551;6385.872282554323;6404.647744374104;6501.804945851632;3551.6270791566512;3619.438986957026;3663.8351274192723;3723.72072094594;3782.205736795304;2483.987580062088;2565.961510577343;2566.820322577402;2574.4207553300357;2579.4454192348735;443.4169067675996;445.37202544412224;456.17887801956346;458.13869924542314;460.3922821875093;438.8595649392164;467.2267397457214;469.6407248454935;528.7435593737034;576.7548791763568;6836.965815170893;7177.318154775288;7181.3895818854935;7378.29906158914;7389.371903388273;434.311616088497;479.5564103204522;487.55875932281344;493.4719849760722;520.8671788693935;3246.513023046562;3265.2086050192192;3415.573053368329;3457.3949558827103;3503.8598456061472;91170.54414727884;91183;93509;93997.5300123495;94046.52976735073;65794.34205657979;74910;79534.60232698916;80884;83021;46844.5315546847;61724.69137654373;70204;72365.27634723691;73616;7513.361364284097;7600.809979750329;7639.197884222077;7825.139234684067;7895.407844411807;39357.212855743295;41327.58672413358;41856;42195.7890210547;42675;56604.43395566157;61978;62163.37836621576;62503;63187.68406158124;8277.420580559303;10336.0697537222;10384.42885641297;10809;11324.263922844988;9572.617095316109;9629;10635;11702.70743231426;12128.454219559964;8069.636866340911;10178.541965611417;10565.683029509128;10897.891021089688;10942.781144377228;10455.738606534898;10583.100436462986;10609.575616975235;10694.62568810088;11404;10572.788544228919;10635.574577016832;10947.124230061736;11177.105831533621;11308.43457827089;8773.912260877309;8967.237784788365;9059.773505662411;9063.909360906306;9150.862737059082;8873.290136788912;8949.194572488508;9528.618855245713;9762.219022478326;9916.588509967381 12:53:59 INFO - PROCESS | 15969 | __end_tp_report 12:53:59 INFO - PROCESS | 15969 | __start_cc_report 12:53:59 INFO - PROCESS | 15969 | _x_x_mozilla_cycle_collect,1590 12:53:59 INFO - PROCESS | 15969 | __end_cc_report 12:53:59 INFO - PROCESS | 15969 | __startTimestamp1465502039392__endTimestamp 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | ------- Summary: start ------- 12:53:59 INFO - PROCESS | 15969 | Number of tests: 6 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | [#0] dojo.html Cycles:165 Average:4651.87 Median:4803.16 stddev:498.29 (10.4%) stddev-sans-first:471.76 12:53:59 INFO - PROCESS | 15969 | Values: 2550.1 2573.3 2588.8 2592.5 2592.6 4567.4 4667.1 4673.3 4725.8 4740.7 4657.1 4660.7 4684.7 4691.5 4694.5 4268.4 4302.2 4340.8 4366.9 4493.0 4408.1 4442.5 4447.6 4468.3 4499.3 4173.1 4189.8 4192.1 4223.6 4249.8 4305.1 4316.4 4330.4 4354.5 4365.2 4345.8 4346.2 4363.5 4396.7 4398.0 4522.5 4645.4 4664.2 4673.2 4789.8 4988.9 4991.6 5030.4 5044.1 5048.3 5026.7 5054.0 5058.4 5062.9 5071.9 4495.7 4680.1 4726.2 4731.9 4789.7 5255.7 5262.8 5273.5 5275.6 5283.6 4707.1 5039.1 5040.8 5048.0 5054.7 4946.8 4988.5 4991.2 4991.2 5014.5 4954.7 4957.6 4957.7 4966.5 5013.5 4762.0 4899.5 4934.5 4942.6 4993.1 4179.1 4200.6 4204.2 4230.4 4246.7 3672.4 3699.1 3703.5 3732.8 3739.7 4292.4 4347.6 4371.7 4412.4 4418.7 5008.9 5019.6 5021.0 5038.1 5055.8 4364.7 4394.0 4408.7 4412.0 4459.7 4349.5 4401.1 4401.4 4505.5 4520.7 4749.1 4791.9 4810.9 4816.9 4850.4 5070.4 5083.8 5088.5 5097.0 5115.8 4653.1 4765.9 4803.2 4807.5 4851.1 4879.3 4953.5 4954.6 4957.5 4960.1 5002.5 5014.2 5017.8 5018.4 5018.8 4865.6 4878.0 4915.3 4933.0 5010.3 4878.2 4903.5 4913.2 4915.3 4966.0 4866.7 4877.1 4893.1 4919.1 4933.8 4710.9 4912.4 4971.4 4980.2 4994.9 4879.5 4884.6 4887.5 4888.0 4939.0 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | [#1] ext.html Cycles:180 Average:48402.16 Median:36768.04 stddev:87790.46 (238.8%) stddev-sans-first:87963.27 12:53:59 INFO - PROCESS | 15969 | Values: 569.0 572.3 574.4 578.6 580.0 37969.6 39729.4 40077.6 43312.0 43336.6 41039.0 41502.8 41531.0 41539.6 41586.8 7897.7 8017.6 8031.5 8034.8 8043.0 7868.7 7962.4 7964.6 7966.6 7972.4 7993.2 8014.2 8020.9 8025.4 8026.5 7950.0 7975.0 7988.9 7991.6 7991.7 7871.5 7907.5 7908.7 7910.9 7938.2 38134.4 38378.2 38458.8 38460.8 38483.6 2708.2 2800.4 2895.6 2899.3 3037.4 28901.7 29364.3 29509.0 29673.7 30607.5 3473.5 3488.4 3534.8 3559.4 3599.9 245273.0 245970.0 247040.0 247194.8 248819.0 55404.4 55436.2 55708.7 55796.0 55813.2 49628.8 55977.4 57165.0 57326.7 57379.0 15187.7 15633.2 15650.3 15669.0 15681.6 11811.6 11928.2 11933.8 11933.9 11937.3 15639.7 18290.0 18354.4 19103.0 19130.3 6689.7 6706.8 6935.4 6942.3 7028.9 790.5 829.2 982.3 1024.5 1026.1 27663.6 28217.7 28218.0 28253.3 28563.6 962.8 987.9 1005.3 1035.9 1049.9 14556.7 14900.5 15020.7 15036.3 15109.2 394595.0 394635.0 397233.0 399825.0 400962.0 50850.0 51327.7 51334.7 51393.2 51512.0 1168.4 1174.8 1186.9 1219.5 1225.8 25434.9 26844.0 26945.2 27191.2 27512.9 41965.6 42672.1 42699.6 42740.8 42751.8 327188.0 338632.0 340792.0 341125.0 341134.0 21594.0 22210.2 24258.6 25193.7 25574.4 37089.3 42760.4 42814.4 43002.8 43141.8 36094.8 36454.8 36751.6 37164.8 37374.8 36231.3 36275.5 36615.8 36629.5 37026.6 35341.0 35736.8 36365.0 36738.8 36784.4 31793.5 32954.3 32985.3 32992.7 33156.2 24415.2 24993.5 25073.6 25151.6 25280.9 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | [#2] jquery.html Cycles:180 Average:23694.71 Median:5003.90 stddev:63544.52 (1269.9%) stddev-sans-first:63698.57 12:53:59 INFO - PROCESS | 15969 | Values: 335.5 353.3 359.3 360.8 375.1 12581.6 13665.9 15746.8 15850.2 16160.4 16038.7 16229.9 16404.1 16519.5 16520.9 4858.1 5008.9 5065.7 5070.2 5092.1 4911.0 4933.5 4993.7 4998.9 5037.9 4954.8 4987.2 4992.4 4993.2 4993.2 5231.6 5538.6 5540.0 5601.2 5615.2 5242.3 5344.4 5385.5 5404.1 5422.8 12077.9 12135.6 12171.6 12222.1 12257.2 1792.1 1836.3 1910.8 2133.0 2284.3 9004.6 9054.9 9554.8 9719.8 13217.9 1513.3 1545.4 1563.9 1583.9 1629.8 64349.0 66590.7 67352.0 68853.3 69149.0 8534.1 9603.4 9723.1 9870.6 9957.2 12120.8 12171.9 13086.0 13106.0 13491.6 7262.1 7343.2 7408.7 7503.6 7537.3 6122.4 6465.8 7185.4 7206.4 7337.8 3791.6 3992.6 4050.8 4068.1 4085.4 1720.9 1731.6 1758.9 1790.3 1799.9 823.6 852.1 864.3 874.4 935.3 15982.3 16082.6 16317.3 16456.0 16675.3 831.3 837.3 848.0 893.4 899.2 8926.2 9222.6 9265.9 9304.1 9471.1 378171.0 380120.0 381894.0 386318.0 392969.0 79633.6 80090.6 80227.6 80290.6 80755.0 889.6 897.0 903.8 907.4 908.6 14564.0 14569.3 14616.0 14641.2 15144.8 35255.6 35593.8 36462.0 36638.3 36874.3 47416.8 51246.0 52463.9 52726.0 52867.2 7612.4 7679.3 7733.6 7889.6 7932.8 8389.5 11202.9 11321.1 11482.4 11806.5 9593.5 9962.3 10014.3 10178.6 10259.8 9800.8 10193.5 10205.8 10450.3 10536.7 9495.8 9979.3 10134.1 10135.6 10344.3 6498.0 6722.5 6818.0 7598.4 7974.3 9310.7 9640.9 9851.9 9868.7 9963.1 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | [#3] mootools.html Cycles:180 Average:5040.52 Median:5509.49 stddev:2823.81 (51.3%) stddev-sans-first:2816.36 12:53:59 INFO - PROCESS | 15969 | Values: 1120.0 1374.0 1382.8 1481.9 1676.4 6629.6 7517.1 7679.7 7752.7 7811.3 7632.4 7808.3 7814.6 7831.2 7842.5 1832.7 1879.4 1882.8 2168.4 2207.0 1817.7 2120.3 2211.9 2215.5 2221.7 2145.1 2174.7 2175.8 2178.1 2182.2 1764.5 2116.9 2121.5 2128.0 2128.5 2081.4 2168.1 2169.0 2173.2 2173.4 7857.6 7866.5 7896.6 7910.7 7913.9 4438.4 4546.6 4547.8 4551.2 4564.8 2630.4 2739.2 2770.5 2780.0 2786.0 859.8 881.1 888.4 897.3 899.6 10229.4 10240.9 10262.4 10284.7 10319.8 4033.1 4061.4 4109.8 4153.8 4164.2 8715.8 8718.9 8730.3 8760.0 8760.9 4454.5 4459.1 4476.7 4479.1 4485.8 3456.2 3545.3 4159.3 4201.4 4219.9 3510.1 3625.9 3678.4 3690.5 3717.1 1141.9 1197.1 1208.2 1210.3 1210.5 815.9 833.3 849.1 850.8 851.2 5161.7 5318.9 5380.8 5396.0 5509.2 853.4 866.9 889.8 892.7 905.7 3493.7 3533.2 3649.7 3680.4 3691.0 9248.8 9491.0 9660.0 9665.7 9694.6 9396.7 9525.6 9539.6 9561.9 9592.0 6723.8 7285.9 7353.7 7380.9 7410.0 5480.6 5509.8 5541.4 5620.1 5664.6 8830.2 8894.8 8941.2 8956.5 8984.8 8123.6 8442.7 8447.1 8586.4 9037.5 5537.6 6132.8 6484.6 6638.2 6671.0 7250.2 7426.7 7427.7 7456.5 7468.5 6520.7 6680.3 6692.4 6729.8 6761.1 6290.2 6385.0 6408.5 6619.6 6634.0 6059.2 6062.1 6108.7 6129.8 6152.4 4205.0 4389.6 4500.4 4570.7 4615.4 4431.5 4454.4 4500.1 4566.0 4627.1 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | [#4] prototype.html Cycles:180 Average:4136.33 Median:4793.34 stddev:1214.53 (25.3%) stddev-sans-first:1183.09 12:53:59 INFO - PROCESS | 15969 | Values: 287.8 295.0 295.3 296.1 299.4 4754.7 4800.8 4807.9 4814.9 4820.3 4706.8 4734.1 4764.5 4778.9 4813.3 3544.2 3636.4 3648.8 3750.5 3781.0 3700.5 3720.8 3739.6 3751.9 3763.8 3538.4 3711.8 3722.6 3724.2 3749.9 3491.8 3625.7 3639.9 3655.2 3726.9 3268.8 3295.4 3297.3 3299.0 3299.5 4587.9 4617.9 4634.6 4640.8 4652.8 3946.9 4154.0 4163.9 4259.4 4284.4 4933.7 4934.3 4946.4 4948.0 4986.7 3495.4 3521.2 3533.4 3534.5 3539.7 5052.1 5203.2 5209.6 5221.7 5245.8 3546.0 3547.9 3577.6 3579.3 3584.2 3592.0 3610.9 3614.3 3635.6 3635.7 4184.4 4186.8 4190.6 4206.7 4214.6 4854.5 4878.4 4902.8 4971.6 4992.0 1237.9 1250.1 1253.8 1255.6 1262.4 775.3 784.8 798.0 799.9 814.6 4332.8 4439.7 4443.3 4453.8 4472.2 5084.9 5106.2 5108.5 5126.7 5158.2 4398.1 4404.7 4434.6 4445.4 4445.6 2560.0 2726.8 2731.4 2731.6 2734.2 4821.3 4851.2 4890.7 4898.5 4903.3 4949.5 5240.3 5252.7 5265.0 5290.6 4809.1 4847.0 4854.4 4885.2 4892.2 5127.2 5130.0 5135.1 5143.7 5160.8 4788.2 5020.4 5032.3 5033.9 5036.8 4918.4 4925.6 4935.1 4936.3 4959.6 3589.8 3616.5 3620.0 3634.7 3640.3 5056.4 5195.3 5197.4 5229.1 5232.8 4973.0 4976.2 4984.2 4989.6 5017.5 4953.5 5002.2 5023.5 5031.8 5055.1 4798.5 4949.2 4964.8 5015.7 5016.7 4843.9 4947.7 4956.0 4985.3 5116.4 4798.6 4831.3 4839.9 4909.0 4933.6 12:53:59 INFO - PROCESS | 15969 | 12:53:59 INFO - PROCESS | 15969 | [#5] yui.html Cycles:180 Average:16338.95 Median:27178.60 stddev:23435.34 (86.2%) stddev-sans-first:23471.26 12:53:59 INFO - PROCESS | 15969 | Values: 593.8 605.4 614.7 615.8 636.4 11631.5 11709.5 12016.6 12144.9 12187.6 11930.3 12130.2 12137.7 12151.5 12156.1 622.3 758.3 768.6 787.1 789.2 770.0 782.1 782.3 782.7 782.8 816.3 821.2 822.1 822.4 823.0 653.9 799.3 815.8 817.0 818.0 600.1 601.2 601.3 601.7 602.0 11759.1 11762.6 11776.4 11778.3 11780.9 11520.4 11835.2 11916.2 11936.6 12055.5 10046.0 10100.0 10423.1 10512.5 10545.9 4101.8 4140.2 4165.2 4179.3 4242.8 53828.5 53885.2 53988.5 54038.0 54055.7 8830.9 9022.7 9083.5 9248.6 9922.3 17690.3 17720.6 17726.1 17734.2 17735.6 6269.9 6318.4 6385.9 6404.6 6501.8 3551.6 3619.4 3663.8 3723.7 3782.2 2484.0 2566.0 2566.8 2574.4 2579.4 443.4 445.4 456.2 458.1 460.4 438.9 467.2 469.6 528.7 576.8 6837.0 7177.3 7181.4 7378.3 7389.4 434.3 479.6 487.6 493.5 520.9 3246.5 3265.2 3415.6 3457.4 3503.9 91170.5 91183.0 93509.0 93997.5 94046.5 65794.3 74910.0 79534.6 80884.0 83021.0 46844.5 61724.7 70204.0 72365.3 73616.0 7513.4 7600.8 7639.2 7825.1 7895.4 39357.2 41327.6 41856.0 42195.8 42675.0 56604.4 61978.0 62163.4 62503.0 63187.7 8277.4 10336.1 10384.4 10809.0 11324.3 9572.6 9629.0 10635.0 11702.7 12128.5 8069.6 10178.5 10565.7 10897.9 10942.8 10455.7 10583.1 10609.6 10694.6 11404.0 10572.8 10635.6 10947.1 11177.1 11308.4 8773.9 8967.2 9059.8 9063.9 9150.9 8873.3 8949.2 9528.6 9762.2 9916.6 12:53:59 INFO - PROCESS | 15969 | -------- Summary: end -------- 12:53:59 INFO - PROCESS | 15969 | 12:54:00 INFO - TEST-INFO | 15969: exit 0 12:54:00 INFO - TEST-OK | dromaeo_css | took 1119390ms 12:54:00 INFO - TEST-START | kraken 12:54:00 INFO - operating with platform_type : linux_ 12:54:00 INFO - Initialising browser for kraken test... 12:54:00 INFO - TEST-INFO | started process 16149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7ODrIu/profile http://localhost:48270/getInfo.html) 12:54:04 INFO - PROCESS | 16149 | __metrics Screen width/height:1600/1200 12:54:04 INFO - PROCESS | 16149 | colorDepth:24 12:54:04 INFO - PROCESS | 16149 | Browser inner width/height: 1024/697 12:54:04 INFO - PROCESS | 16149 | __metrics 12:54:05 ERROR - PROCESS | 16149 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:54:06 INFO - PROCESS | 16149 | console.error: PushService: 12:54:06 INFO - PROCESS | 16149 | stateChangeProcessEnqueue: Error transitioning state 12:54:06 INFO - PROCESS | 16149 | UnknownError 12:54:06 INFO - TEST-INFO | 16149: exit 0 12:54:06 INFO - Browser initialized. 12:54:06 INFO - Running cycle 1/1 for kraken test... 12:54:06 INFO - TEST-INFO | started process 16231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7ODrIu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:18 INFO - PROCESS | 16231 | RSS: Main: 174817280 12:54:18 INFO - PROCESS | 16231 | 12:54:21 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48270/tests/kraken/driver.html?testName=audio-beat-detection) 12:54:22 INFO - PROCESS | 16231 | RSS: Main: 232087552 12:54:22 INFO - PROCESS | 16231 | 12:54:25 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48270/tests/kraken/driver.html?testName=audio-dft) 12:54:25 INFO - PROCESS | 16231 | RSS: Main: 220684288 12:54:25 INFO - PROCESS | 16231 | 12:54:29 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48270/tests/kraken/driver.html?testName=audio-fft) 12:54:29 INFO - PROCESS | 16231 | RSS: Main: 288272384 12:54:29 INFO - PROCESS | 16231 | 12:54:31 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48270/tests/kraken/driver.html?testName=audio-oscillator) 12:54:32 INFO - PROCESS | 16231 | RSS: Main: 198205440 12:54:32 INFO - PROCESS | 16231 | 12:54:34 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:54:35 INFO - PROCESS | 16231 | RSS: Main: 201879552 12:54:35 INFO - PROCESS | 16231 | 12:54:38 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48270/tests/kraken/driver.html?testName=imaging-darkroom) 12:54:39 INFO - PROCESS | 16231 | RSS: Main: 238538752 12:54:39 INFO - PROCESS | 16231 | 12:54:42 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48270/tests/kraken/driver.html?testName=imaging-desaturate) 12:54:43 INFO - PROCESS | 16231 | RSS: Main: 233689088 12:54:43 INFO - PROCESS | 16231 | 12:54:46 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48270/tests/kraken/driver.html?testName=json-parse-financial) 12:54:46 INFO - PROCESS | 16231 | RSS: Main: 236343296 12:54:46 INFO - PROCESS | 16231 | 12:54:48 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:54:49 INFO - PROCESS | 16231 | RSS: Main: 223178752 12:54:49 INFO - PROCESS | 16231 | 12:54:51 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:54:51 INFO - PROCESS | 16231 | RSS: Main: 227500032 12:54:51 INFO - PROCESS | 16231 | 12:54:53 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:54:54 INFO - PROCESS | 16231 | RSS: Main: 235634688 12:54:54 INFO - PROCESS | 16231 | 12:54:57 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:54:58 INFO - PROCESS | 16231 | RSS: Main: 240037888 12:54:58 INFO - PROCESS | 16231 | 12:55:00 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:55:01 INFO - PROCESS | 16231 | RSS: Main: 239759360 12:55:01 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | Cycle 1(1): loaded http://localhost:48270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48270/tests/kraken/driver.html?testName=ai-astar) 12:55:03 INFO - PROCESS | 16231 | RSS: Main: 282210304 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | __start_tp_report 12:55:03 INFO - PROCESS | 16231 | _x_x_mozilla_page_load 12:55:03 INFO - PROCESS | 16231 | _x_x_mozilla_page_load_details 12:55:03 INFO - PROCESS | 16231 | |i|pagename|runs| 12:55:03 INFO - PROCESS | 16231 | |0;ai-astar;115;110;114;113;112;116;112;113;112;113 12:55:03 INFO - PROCESS | 16231 | |1;audio-beat-detection;162;132;132;183;145;134;151;132;199;136 12:55:03 INFO - PROCESS | 16231 | |2;audio-dft;162;161;163;161;163;161;161;162;161;161 12:55:03 INFO - PROCESS | 16231 | |3;audio-fft;76;76;75;108;76;77;74;77;100;75 12:55:03 INFO - PROCESS | 16231 | |4;audio-oscillator;87;87;115;87;86;88;117;88;85;128 12:55:03 INFO - PROCESS | 16231 | |5;imaging-gaussian-blur;117;117;117;119;119;117;117;118;117;119 12:55:03 INFO - PROCESS | 16231 | |6;imaging-darkroom;145;147;147;145;146;146;146;147;145;146 12:55:03 INFO - PROCESS | 16231 | |7;imaging-desaturate;95;96;95;95;96;95;96;95;96;95 12:55:03 INFO - PROCESS | 16231 | |8;json-parse-financial;63;63;63;64;64;63;64;64;64;63 12:55:03 INFO - PROCESS | 16231 | |9;json-stringify-tinderbox;65;65;65;65;66;65;66;65;65;65 12:55:03 INFO - PROCESS | 16231 | |10;stanford-crypto-aes;88;89;87;83;92;85;85;90;114;86 12:55:03 INFO - PROCESS | 16231 | |11;stanford-crypto-ccm;134;142;129;129;130;133;129;131;145;146 12:55:03 INFO - PROCESS | 16231 | |12;stanford-crypto-pbkdf2;172;169;168;167;168;166;165;166;167;165 12:55:03 INFO - PROCESS | 16231 | |13;stanford-crypto-sha256-iterative;69;70;68;68;67;67;70;67;67;67 12:55:03 INFO - PROCESS | 16231 | __end_tp_report 12:55:03 INFO - PROCESS | 16231 | __start_cc_report 12:55:03 INFO - PROCESS | 16231 | _x_x_mozilla_cycle_collect,1867 12:55:03 INFO - PROCESS | 16231 | __end_cc_report 12:55:03 INFO - PROCESS | 16231 | __startTimestamp1465502103557__endTimestamp 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | ------- Summary: start ------- 12:55:03 INFO - PROCESS | 16231 | Number of tests: 14 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:1.70 (1.5%) stddev-sans-first:1.64 12:55:03 INFO - PROCESS | 16231 | Values: 115.0 110.0 114.0 113.0 112.0 116.0 112.0 113.0 112.0 113.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:148.00 stddev:23.76 (16.1%) stddev-sans-first:24.84 12:55:03 INFO - PROCESS | 16231 | Values: 162.0 132.0 132.0 183.0 145.0 134.0 151.0 132.0 199.0 136.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 12:55:03 INFO - PROCESS | 16231 | Values: 162.0 161.0 163.0 161.0 163.0 161.0 161.0 162.0 161.0 161.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:12.09 (15.9%) stddev-sans-first:12.67 12:55:03 INFO - PROCESS | 16231 | Values: 76.0 76.0 75.0 108.0 76.0 77.0 74.0 77.0 100.0 75.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:87.00 stddev:16.37 (18.8%) stddev-sans-first:16.97 12:55:03 INFO - PROCESS | 16231 | Values: 87.0 87.0 115.0 87.0 86.0 88.0 117.0 88.0 85.0 128.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:117.50 stddev:0.95 (0.8%) stddev-sans-first:0.97 12:55:03 INFO - PROCESS | 16231 | Values: 117.0 117.0 117.0 119.0 119.0 117.0 117.0 118.0 117.0 119.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 12:55:03 INFO - PROCESS | 16231 | Values: 145.0 147.0 147.0 145.0 146.0 146.0 146.0 147.0 145.0 146.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 12:55:03 INFO - PROCESS | 16231 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 12:55:03 INFO - PROCESS | 16231 | Values: 63.0 63.0 63.0 64.0 64.0 63.0 64.0 64.0 64.0 63.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 12:55:03 INFO - PROCESS | 16231 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 66.0 65.0 65.0 65.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.50 stddev:8.88 (10.1%) stddev-sans-first:9.39 12:55:03 INFO - PROCESS | 16231 | Values: 88.0 89.0 87.0 83.0 92.0 85.0 85.0 90.0 114.0 86.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#11] stanford-crypto-ccm Cycles:10 Average:134.80 Median:133.50 stddev:6.86 (5.1%) stddev-sans-first:7.27 12:55:03 INFO - PROCESS | 16231 | Values: 134.0 142.0 129.0 129.0 130.0 133.0 129.0 131.0 145.0 146.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:2.11 (1.3%) stddev-sans-first:1.39 12:55:03 INFO - PROCESS | 16231 | Values: 172.0 169.0 168.0 167.0 168.0 166.0 165.0 166.0 167.0 165.0 12:55:03 INFO - PROCESS | 16231 | 12:55:03 INFO - PROCESS | 16231 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.25 (1.8%) stddev-sans-first:1.27 12:55:03 INFO - PROCESS | 16231 | Values: 69.0 70.0 68.0 68.0 67.0 67.0 70.0 67.0 67.0 67.0 12:55:03 INFO - PROCESS | 16231 | -------- Summary: end -------- 12:55:03 INFO - PROCESS | 16231 | 12:55:04 INFO - TEST-INFO | 16231: exit 0 12:55:04 INFO - TEST-OK | kraken | took 64425ms 12:55:04 INFO - SUITE-END | took 1183s 12:55:04 INFO - Completed test suite (00:19:43) 12:55:04 INFO - javascript benchmark 12:55:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2550.1329547953696, 2573.3309339571706, 2588.844669319842, 2592.4815036992595, 2592.624069509921, 4567.4062371891605, 4667.089917466087, 4673.252279635255, 4725.834595789148, 4740.691855029425, 4657.115148121855, 4660.650451216164, 4684.695494792844, 4691.5073917238715, 4694.507076756942, 4268.42376279203, 4302.161078589676, 4340.782960851945, 4366.934495982547, 4493.033997690496, 4408.052268762215, 4442.511323754385, 4447.621952134073, 4468.307412351074, 4499.347594598781, 4173.123644034741, 4189.7695626740515, 4192.098698779777, 4223.64099051581, 4249.830006799724, 4305.13897220554, 4316.3957046013265, 4330.39374487572, 4354.54277300882, 4365.2142614329405, 4345.782710864444, 4346.1525002624185, 4363.454568178978, 4396.670249731283, 4397.956020439787, 4522.547745225451, 4645.442546894361, 4664.160451118797, 4673.228917228653, 4789.784459699322, 4988.8503344899345, 4991.625628077909, 5030.3963524377305, 5044.066847633199, 5048.29323894655, 5026.6983980960895, 5054.039732450822, 5058.367704036996, 5062.949370506285, 5071.8985620287385, 4495.707778994388, 4680.110778951988, 4726.243800991825, 4731.858044258678, 4789.712617242976, 5255.710935898563, 5262.789488420497, 5273.499017593323, 5275.604329675213, 5283.6037297202865, 4707.058588282357, 5039.143345631252, 5040.79836806531, 5048.0, 5054.747262636927, 4946.802127914915, 4988.5260900214425, 4991.201407774811, 4991.226359914219, 5014.498550145029, 4954.677945933503, 4957.553820156131, 4957.727324997088, 4966.478519755446, 5013.498650134958, 4762.023785124041, 4899.485554016777, 4934.481879402683, 4942.555170034714, 4993.101241776442, 4179.080602267496, 4200.642945349679, 4204.2432362175105, 4230.386593943861, 4246.723962942398, 3672.375696131664, 3699.130704284491, 3703.500027496307, 3732.79469629173, 3739.7195210358786, 4292.441982542312, 4347.6304514116655, 4371.650267978554, 4412.448443944507, 4418.668599855024, 5008.949910500848, 5019.598432125421, 5020.974895125501, 5038.118329292359, 5055.772490237948, 4364.738115713068, 4393.978030109829, 4408.669349798778, 4411.963188650665, 4459.687821852504, 4349.543297953733, 4401.097774956126, 4401.427814384109, 4505.504394516601, 4520.728756274569, 4749.050189961952, 4791.928121078186, 4810.8797280068275, 4816.927746083882, 4850.442199147002, 5070.391553013662, 5083.796648134034, 5088.516590923857, 5096.974515127401, 5115.846524604268, 4653.139169253699, 4765.9046819064115, 4803.159447096674, 4807.471178170468, 4851.126797176401, 4879.316895634543, 4953.479884612064, 4954.603631709527, 4957.529034741694, 4960.082384758831, 5002.499750024968, 5014.222795466709, 5017.799288028584, 5018.447970723145, 5018.84943451697, 4865.56209941107, 4878.0, 4915.287283343967, 4933.0, 5010.323606313101, 4878.219484882402, 4903.509649035068, 4913.213885778258, 4915.262710593297, 4966.0, 4866.732329721899, 4877.146499362527, 4893.09477746618, 4919.065377578248, 4933.753312334297, 4710.882227944328, 4912.361393018885, 4971.37857767779, 4980.203167493184, 4994.875128121826, 4879.4876537963, 4884.63365247615, 4887.511248875084, 4888.0, 4939.0], "value": 4619.146276966855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.0241236279782, 572.2617823008319, 574.4198359656747, 578.6007654718244, 579.9565032622554, 37969.62030379695, 39729.40405893914, 40077.599224007754, 43312.0, 43336.56663433365, 41039.0, 41502.79248603753, 41531.0, 41539.58460415395, 41586.79206603963, 7897.684092636302, 8017.559034253114, 8031.518108913455, 8034.799130021767, 8043.03483581971, 7868.6852525899185, 7962.442629015971, 7964.641591128384, 7966.5618390988475, 7972.402069844756, 7993.1607181246, 8014.158513356072, 8020.919790802105, 8025.357971362306, 8026.518408895485, 7950.045994480642, 7975.0, 7988.880166797503, 7991.640376183086, 7991.680332786682, 7871.4883532570775, 7907.48601340911, 7908.683652653887, 7910.881336779953, 7938.206179382073, 38134.42798357999, 38378.23243535141, 38458.80770596157, 38460.80769596162, 38483.61516384856, 2708.1740069278862, 2800.3979144484033, 2895.580140879582, 2899.304166999926, 3037.407705497429, 28901.710982890323, 29364.26589335262, 29509.0, 29673.70326296731, 30607.540886886716, 3473.5484387029524, 3488.4069708149677, 3534.7525673202886, 3559.3771090059136, 3599.8740044098595, 245273.0, 245970.0, 247040.0, 247194.76402618052, 248819.0, 55404.44595553993, 55436.16845747317, 55708.72145639246, 55796.0, 55813.16280255799, 49628.75185624049, 55977.44022559804, 57165.0, 57326.7133664329, 57379.0, 15187.696246075016, 15633.218339083, 15650.295736691876, 15669.0, 15681.607959800867, 11811.586594469152, 11928.224665396721, 11933.761324773455, 11933.940330298467, 11937.28376297425, 15639.68720625581, 18290.0, 18354.35759748429, 19103.0, 19130.330438434867, 6689.6989635466525, 6706.798796036127, 6935.375816176568, 6942.271061538567, 7028.894566581505, 790.5414859381544, 829.2329595124486, 982.2534873496052, 1024.4621573673733, 1026.0611540440564, 27663.58504622432, 28217.71782282192, 28218.0, 28253.293667658472, 28563.57154642682, 962.7737481691797, 987.9012098790207, 1005.3163848583034, 1035.880873699519, 1049.8897615750388, 14556.708865822837, 14900.472611915467, 15020.699586008219, 15036.323365448581, 15109.168995705122, 394595.0, 394635.0, 397233.0, 399825.0, 400962.0, 50850.0, 51327.743361283705, 51334.74332628313, 51393.229101563506, 51512.0, 1168.3574034280941, 1174.7557820765207, 1186.851643544557, 1219.524385489642, 1225.7609766095525, 25434.872825635754, 26844.0, 26945.191644250703, 27191.184264472096, 27512.862435687694, 41965.58034419617, 42672.1465570693, 42699.57300426956, 42740.78629606832, 42751.78624106859, 327188.0, 338632.0, 340792.0, 341125.0, 341134.0, 21594.0, 22210.222642207445, 24258.63612045856, 25193.748062519142, 25574.36064098338, 37089.25821483609, 42760.358594620484, 42814.35778463263, 43002.78498607487, 43141.784291078344, 36094.8195259022, 36454.8177259112, 36751.632483674824, 37164.81417592895, 37374.8131259342, 36231.27537449289, 36275.45586816253, 36615.81692091629, 36629.450558241115, 37026.62973370232, 35341.0, 35736.821315894296, 36365.0, 36738.8163059183, 36784.44823327706, 31793.5230971531, 32954.34091318112, 32985.34029319352, 32992.67007329896, 33156.1710957228, 24415.208328132696, 24993.50012999766, 25073.623895641216, 25151.572659987494, 25280.873595631903], "value": 17747.433237064117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.4582349505549, 353.33043881843906, 359.2581319575077, 360.8286064119543, 375.13344174955836, 12581.622551323451, 13665.863341366596, 15746.84253157468, 15850.20748962553, 16160.434384796534, 16038.679226415465, 16229.91885040576, 16404.09777462239, 16519.504414867544, 16520.917395413035, 4858.149823780833, 5008.9499105008845, 5065.670731402465, 5070.239464080399, 5092.057969275677, 4910.975445122769, 4933.531314525132, 4993.700377977333, 4998.8500344989525, 5037.899242015157, 4954.826581069663, 4987.1521841286885, 4992.425871024842, 4993.176125939216, 4993.226049962262, 5231.581473482112, 5538.6399884007615, 5540.0, 5601.187827764992, 5615.241942337814, 5242.292290540767, 5344.43883392246, 5385.515303622654, 5404.135338345885, 5422.783088676488, 12077.879221207764, 12135.575254866126, 12171.634850954486, 12222.144449888512, 12257.203281786655, 1792.0770803036446, 1836.2563161919445, 1910.7993660665709, 2132.968005479919, 2284.303287497313, 9004.59479323432, 9054.909450905472, 9554.761130971712, 9719.757006074835, 13217.867821321855, 1513.2887542854842, 1545.3509525998995, 1563.9061656300657, 1583.8617865881083, 1629.8125715542747, 64349.0, 66590.66704666446, 67352.0, 68853.31146688569, 69149.0, 8534.146585341541, 9603.37578057438, 9723.124918757347, 9870.60517579303, 9957.153641940371, 12120.75758484843, 12171.939140304241, 13086.0, 13106.0, 13491.595252142451, 7262.092238470192, 7343.228960959129, 7408.666610002563, 7503.587302698404, 7537.283958023979, 6122.387761223842, 6465.77369792055, 7185.353318201387, 7206.387457066208, 7337.816554586178, 3791.6018818024263, 3992.6007399259843, 4050.7772072536295, 4068.06434520062, 4085.3667681509414, 1720.8881422707734, 1731.5930756272267, 1758.9384371546935, 1790.3465235189062, 1799.8650101242458, 823.6417158536055, 852.0882655558493, 864.265374431728, 874.4141425245101, 935.3218916285639, 15982.280797364147, 16082.597935051483, 16317.265723042254, 16456.012639241446, 16675.332986680398, 831.2934006094771, 837.3385025829648, 847.9830403391898, 893.3523195683208, 899.150302963706, 8926.152015558511, 9222.584983675752, 9265.861012084824, 9304.069593040642, 9471.147596716328, 378171.0, 380120.0, 381894.0, 386318.0, 392969.0, 79633.60183199047, 80090.5995470019, 80227.59886200649, 80290.59854700688, 80755.0, 889.6085722282176, 896.9596368163449, 903.7966457547, 907.3603109807569, 908.6047569307373, 14563.976655661085, 14569.34437950316, 14616.049956752777, 14641.19473428929, 15144.772828407804, 35255.64744352524, 35593.82203088968, 36462.0, 36638.267234654624, 36874.262514750095, 47416.7629161852, 51246.0, 52463.85216886999, 52726.0, 52867.20699189438, 7612.429067820047, 7679.347255483346, 7733.613319333898, 7889.6449659766595, 7932.841343172989, 8389.538575378412, 11202.887971120184, 11321.094312455149, 11482.368469734245, 11806.527738890349, 9593.472359020321, 9962.252831037846, 10014.347632640533, 10178.592856285666, 10259.794804103727, 9800.754981125529, 10193.541290642088, 10205.795884082425, 10450.320729152885, 10536.736581585215, 9495.762605934631, 9979.309724429506, 10134.138598218939, 10135.645058786064, 10344.327618705061, 6498.0, 6722.495812813961, 6818.045473633596, 7598.430117741302, 7974.282314591715, 9310.72067837966, 9640.90359096428, 9851.901480985098, 9868.706678443768, 9963.103320701171], "value": 7875.307103099764, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1119.972000699982, 1373.9587812365633, 1382.7511048011356, 1481.859223373779, 1676.4383931382986, 6629.602223866571, 7517.060367454068, 7679.654415551299, 7752.728654497094, 7811.3360364368955, 7632.427567932399, 7808.258215469536, 7814.609269536515, 7831.216878312166, 7842.451028428012, 1832.743415921772, 1879.4455635587535, 1882.8305452509271, 2168.4470460032708, 2207.0178770447155, 1817.6728188925988, 2120.300300900699, 2211.8783466909395, 2215.46828761097, 2221.6556433752794, 2145.0776166248506, 2174.662927246279, 2175.771543987882, 2178.0852042142337, 2182.2362173239317, 1764.5412192829829, 2116.9364919052455, 2121.459027947879, 2127.9680804788018, 2128.4891626009644, 2081.3651836189956, 2168.0568952505555, 2168.9891550542147, 2173.163332117146, 2173.4349069241953, 7857.607119643995, 7866.488678235954, 7896.565688887109, 7910.7231246906085, 7913.920860791376, 4438.423005009328, 4546.590806827401, 4547.795349209294, 4551.203539380595, 4564.7717614119165, 2630.395009147907, 2739.2193224930797, 2770.473610014111, 2780.0, 2785.9860700696568, 859.8332202848849, 881.1320848963766, 888.4047688049022, 897.3270047464401, 899.5592159841696, 10229.38623682574, 10240.948795255976, 10262.435566043865, 10284.742881428025, 10319.79360412795, 4033.092554175287, 4061.3704875744306, 4109.835606575763, 4153.813078411479, 4164.208800327928, 8715.782105447415, 8718.912810871938, 8730.257928076058, 8759.956200218958, 8760.91239087601, 4454.487733910577, 4459.10817836434, 4476.664250181184, 4479.148961697267, 4485.753283569435, 3456.2396272819938, 3545.2909418115964, 4159.251334759772, 4201.369794530845, 4219.894502637459, 3510.140015696171, 3625.9093522661615, 3678.448232765053, 3690.538682664667, 3717.0893131183034, 1141.8972292493715, 1197.108154424959, 1208.2086233517061, 1210.3369316323221, 1210.5460452330376, 815.9143289954588, 833.3458235285271, 849.1381248033251, 850.8425941200898, 851.2041241439274, 5161.690298582097, 5318.867028324323, 5380.757865896044, 5396.0, 5509.201165830932, 853.3557164341007, 866.8699695045793, 889.7508697564692, 892.7054072156172, 905.6875377994581, 3493.7205023598053, 3533.169705119295, 3649.7445178837756, 3680.4479328100465, 3690.9630903691154, 9248.815023699623, 9490.952545237367, 9659.951700241594, 9665.71002869915, 9694.61221551144, 9396.67111651089, 9525.57134928916, 9539.57071931751, 9561.856572151424, 9592.040795920351, 6723.798286051328, 7285.854282914312, 7353.742619008416, 7380.852382952311, 7409.962950185322, 5480.6437581557075, 5509.779608815603, 5541.362743284574, 5620.100783874561, 5664.631798933056, 8830.205281524693, 8894.822103558023, 8941.239994600272, 8956.507392093497, 8984.820303594022, 8123.5532045738055, 8442.704505342284, 8447.113053129333, 8586.441881277697, 9037.50293733851, 5537.640053396516, 6132.816015519542, 6484.578502397329, 6638.203415589967, 6671.032700258533, 7250.202477727551, 7426.74006409773, 7427.665755040928, 7456.47804653669, 7468.5145465544565, 6520.673966301761, 6680.331966803281, 6692.364225398579, 6729.798106056826, 6761.0534525167495, 6290.24517057956, 6385.0, 6408.487321014214, 6619.6028238303925, 6634.004899264955, 6059.151718759289, 6062.090686397076, 6108.725107370268, 6129.84675383119, 6152.354002829638, 4205.032842446193, 4389.648828093808, 4500.369948207188, 4570.6800523963, 4615.353850460871, 4431.512533621235, 4454.420925279823, 4500.054988452331, 4565.954340456553, 4627.074585083064], "value": 4058.5830241999906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.81579788935085, 294.9616549848519, 295.28540930947105, 296.0777179087143, 299.36683913522904, 4754.714717116975, 4800.759962001895, 4807.855764327067, 4814.9037019259595, 4820.325154478376, 4706.764661766915, 4734.100520901035, 4764.475907650156, 4778.856634300968, 4813.326134341195, 3544.184837487373, 3636.4181730923056, 3648.7628304160276, 3750.5499340079227, 3780.962190378102, 3700.5374328208973, 3720.795356255405, 3739.551253849542, 3751.8686845960397, 3763.833211704367, 3538.4161613333767, 3711.7772933623933, 3722.571904231021, 3724.2179142380132, 3749.887503374903, 3491.7555771096036, 3625.673689367943, 3639.909002274938, 3655.232401195752, 3726.850925962958, 3268.8038717677014, 3295.40682677118, 3297.3405318936307, 3298.950515742266, 3299.472084466498, 4587.8623641290815, 4617.884552886171, 4634.55971682693, 4640.83757068501, 4652.767361631905, 3946.9013274668077, 4153.979230103861, 4163.895902602429, 4259.446271984622, 4284.443022407098, 4933.7286449245275, 4934.284528743348, 4946.356973593446, 4948.0, 4986.656271552364, 3495.370833250014, 3521.1725244567506, 3533.4169861973, 3534.487499312611, 3539.699125574329, 5052.115879721071, 5203.245529398254, 5209.557187639055, 5221.686698798047, 5245.816396426107, 3546.0, 3547.9290414192087, 3577.6422357764013, 3579.319929213441, 3584.229390681016, 3591.964080359163, 3610.909727256787, 3614.2771445710964, 3635.581908080552, 3635.690966267897, 4184.351425529047, 4186.832526698898, 4190.559991200941, 4206.705530612889, 4214.641755450821, 4854.538818812207, 4878.365812444359, 4902.803887844518, 4971.552560269593, 4991.975040124777, 1237.9195352302072, 1250.106174085515, 1253.8401978170157, 1255.5605538061789, 1262.419287127913, 775.3409601838392, 784.807093218305, 797.960101994898, 799.880017997305, 814.6497006287353, 4332.848350307724, 4439.689221754511, 4443.311286750559, 4453.799579018952, 4472.195004899084, 5084.872878178076, 5106.234064890296, 5108.514691104339, 5126.66676666023, 5158.174692049329, 4398.098389830141, 4404.71369360997, 4434.623057040123, 4445.37743723545, 4445.599896009375, 2559.987200063988, 2726.7954903382324, 2731.426400455887, 2731.562949928002, 2734.234414363981, 4821.252705830602, 4851.205054129994, 4890.731009794425, 4898.485659005824, 4903.289023091632, 4949.451032718153, 5240.318758561439, 5252.684838909678, 5264.9736751315995, 5290.603204759658, 4809.134355815917, 4846.95153048465, 4854.417469903634, 4885.2427873679635, 4892.241702536113, 5127.230915362726, 5129.9743501282255, 5135.10135726239, 5143.742812859267, 5160.845174644766, 4788.186008378653, 5020.447750747488, 5032.27032080339, 5033.92449113271, 5036.823711170092, 4918.4343800461975, 4925.556699896883, 4935.09998650515, 4936.2969775428855, 4959.611308833532, 3589.766665166756, 3616.5479315085613, 3620.0, 3634.655177584311, 3640.3265395901844, 5056.443791183041, 5195.298634684269, 5197.376314842243, 5229.13719236337, 5232.790688372423, 4973.0, 4976.2286845539, 4984.1526940420945, 4989.575886049726, 5017.523335283142, 4953.529414705597, 5002.224655178307, 5023.497650234947, 5031.773570189277, 5055.140626093499, 4798.49615790337, 4949.183384741621, 4964.826231081895, 5015.724135172601, 5016.749162541785, 4843.927341089816, 4947.727874966911, 4956.0, 4985.326980857538, 5116.437191908813, 4798.616110711065, 4831.275308703723, 4839.854804355875, 4909.0, 4933.605311575137], "value": 3745.978945501796, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7773334999378, 605.3918644876536, 614.6557927560564, 615.8430593493918, 636.369993706231, 11631.534738610457, 11709.53161873524, 12016.639500814968, 12144.878551214486, 12187.634370968863, 11930.343831089287, 12130.211536250159, 12137.696557586065, 12151.513939442433, 12156.088293377989, 622.2564035976986, 758.3402439877287, 768.6310570925945, 787.1066339704448, 789.1556035042507, 769.9538027718327, 782.0654318089885, 782.295933659708, 782.6986610155103, 782.8316911863948, 816.3183741575791, 821.195228675898, 822.0505316359585, 822.411975437562, 822.9753107406757, 653.8757636049183, 799.2606838674203, 815.824597711492, 817.0236567302077, 817.9631916563769, 600.0848705723806, 601.2424345324879, 601.2724603230097, 601.7081715368043, 602.0066889632099, 11759.118066145073, 11762.647120586396, 11776.411179440993, 11778.293302401884, 11780.882191178065, 11520.366379849105, 11835.22471629254, 11916.212568114766, 11936.641900742992, 12055.517779288817, 10046.0, 10100.0, 10423.114035307008, 10512.526936287886, 10545.947270263676, 4101.835926562934, 4140.171965606861, 4165.208610364021, 4179.2895207814745, 4242.787860606957, 53828.461715382735, 53885.1917221242, 53988.46011539913, 54038.0, 54055.729721351534, 8830.86753698695, 9022.729318120468, 9083.50040747752, 9248.630054797733, 9922.305438619373, 17690.29238830458, 17720.645587088446, 17726.11369431549, 17734.20196091179, 17735.556611084827, 6269.937300627026, 6318.399752023551, 6385.872282554323, 6404.647744374104, 6501.804945851632, 3551.6270791566512, 3619.438986957026, 3663.8351274192723, 3723.72072094594, 3782.205736795304, 2483.987580062088, 2565.961510577343, 2566.820322577402, 2574.4207553300357, 2579.4454192348735, 443.4169067675996, 445.37202544412224, 456.17887801956346, 458.13869924542314, 460.3922821875093, 438.8595649392164, 467.2267397457214, 469.6407248454935, 528.7435593737034, 576.7548791763568, 6836.965815170893, 7177.318154775288, 7181.3895818854935, 7378.29906158914, 7389.371903388273, 434.311616088497, 479.5564103204522, 487.55875932281344, 493.4719849760722, 520.8671788693935, 3246.513023046562, 3265.2086050192192, 3415.573053368329, 3457.3949558827103, 3503.8598456061472, 91170.54414727884, 91183.0, 93509.0, 93997.5300123495, 94046.52976735073, 65794.34205657979, 74910.0, 79534.60232698916, 80884.0, 83021.0, 46844.5315546847, 61724.69137654373, 70204.0, 72365.27634723691, 73616.0, 7513.361364284097, 7600.809979750329, 7639.197884222077, 7825.139234684067, 7895.407844411807, 39357.212855743295, 41327.58672413358, 41856.0, 42195.7890210547, 42675.0, 56604.43395566157, 61978.0, 62163.37836621576, 62503.0, 63187.68406158124, 8277.420580559303, 10336.0697537222, 10384.42885641297, 10809.0, 11324.263922844988, 9572.617095316109, 9629.0, 10635.0, 11702.70743231426, 12128.454219559964, 8069.636866340911, 10178.541965611417, 10565.683029509128, 10897.891021089688, 10942.781144377228, 10455.738606534898, 10583.100436462986, 10609.575616975235, 10694.62568810088, 11404.0, 10572.788544228919, 10635.574577016832, 10947.124230061736, 11177.105831533621, 11308.43457827089, 8773.912260877309, 8967.237784788365, 9059.773505662411, 9063.909360906306, 9150.862737059082, 8873.290136788912, 8949.194572488508, 9528.618855245713, 9762.219022478326, 9916.588509967381], "value": 6081.561887731491, "unit": "score"}], "name": "dromaeo_css", "value": 6251.656935220224, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 114.0, 113.0, 112.0, 116.0, 112.0, 113.0, 112.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 132.0, 132.0, 183.0, 145.0, 134.0, 151.0, 132.0, 199.0, 136.0], "value": 150.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 163.0, 161.0, 161.0, 162.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 108.0, 76.0, 77.0, 74.0, 77.0, 100.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 115.0, 87.0, 86.0, 88.0, 117.0, 88.0, 85.0, 128.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 119.0, 119.0, 117.0, 117.0, 118.0, 117.0, 119.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 147.0, 145.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0, 63.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 83.0, 92.0, 85.0, 85.0, 90.0, 114.0, 86.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 142.0, 129.0, 129.0, 130.0, 133.0, 129.0, 131.0, 145.0, 146.0], "value": 134.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 168.0, 167.0, 168.0, 166.0, 165.0, 166.0, 167.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 68.0, 67.0, 67.0, 70.0, 67.0, 67.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1551.2, "alertThreshold": 2.0}]} 12:55:04 INFO - Return code: 0 12:55:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:55:04 ERROR - # TBPL SUCCESS # 12:55:04 INFO - Running post-action listener: _resource_record_post_action 12:55:04 INFO - ##### 12:55:04 INFO - ##### Finished run-tests step (success) 12:55:04 INFO - ##### 12:55:04 INFO - Running post-run listener: _resource_record_post_run 12:55:04 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 306311168; Read time: 4; Write time: 233052 12:55:04 INFO - TinderboxPrint: CPU usage
13.1% 12:55:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 12:55:04 INFO - TinderboxPrint: I/O write bytes / time
306,311,168 / 233,052 12:55:04 INFO - TinderboxPrint: CPU idle
8,325.9 (86.9%) 12:55:04 INFO - TinderboxPrint: CPU user
1,213.0 (12.7%) 12:55:04 INFO - TinderboxPrint: Swap in / out
0 / 0 12:55:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 15364096; Read time: 0; Write time: 6816 12:55:04 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290947072; Read time: 4; Write time: 226236 12:55:05 INFO - Running post-run listener: _upload_blobber_files 12:55:05 INFO - Blob upload gear active. 12:55:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:55:05 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:55:05 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:55:05 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:55:05 INFO - (blobuploader) - INFO - Open directory for files ... 12:55:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:55:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:55:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:10 INFO - (blobuploader) - INFO - Done attempting. 12:55:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:55:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:55:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:10 INFO - (blobuploader) - INFO - Done attempting. 12:55:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:55:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:55:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:11 INFO - (blobuploader) - INFO - Done attempting. 12:55:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:55:11 INFO - Return code: 0 12:55:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:55:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:55:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e4b8655b38ff2be3eb90cf469c4b3613ef0e4641722c5eb2c74dd57d1e2ff224e2192a2ef55ea4e2d3651391f75e2132968627a4e7429dbf293fd623af318ee0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8cccc83c9d665740882e6dfc5652703f9b3e74e914ffe49a8166882a5f3389fd30bab2eb56515a5715dbc8952bb4a4cb81c3edf93e4534c04eff1e1639e943a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7672a8445a4ecc5505737f0703dd0776e01666798d95bc35342e3e260f8cf226c82daa924778f7cc39781a0ddb05bd7114aceff96ba21b51116df4bbc760dc32"} 12:55:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:55:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:55:11 INFO - Contents: 12:55:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e4b8655b38ff2be3eb90cf469c4b3613ef0e4641722c5eb2c74dd57d1e2ff224e2192a2ef55ea4e2d3651391f75e2132968627a4e7429dbf293fd623af318ee0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8cccc83c9d665740882e6dfc5652703f9b3e74e914ffe49a8166882a5f3389fd30bab2eb56515a5715dbc8952bb4a4cb81c3edf93e4534c04eff1e1639e943a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7672a8445a4ecc5505737f0703dd0776e01666798d95bc35342e3e260f8cf226c82daa924778f7cc39781a0ddb05bd7114aceff96ba21b51116df4bbc760dc32"} 12:55:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:55:11 INFO - Copying logs to upload dir... 12:55:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:55:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1305.258306 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-06-09 12:55:11.537954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 12:55:11.542009) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e4b8655b38ff2be3eb90cf469c4b3613ef0e4641722c5eb2c74dd57d1e2ff224e2192a2ef55ea4e2d3651391f75e2132968627a4e7429dbf293fd623af318ee0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8cccc83c9d665740882e6dfc5652703f9b3e74e914ffe49a8166882a5f3389fd30bab2eb56515a5715dbc8952bb4a4cb81c3edf93e4534c04eff1e1639e943a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7672a8445a4ecc5505737f0703dd0776e01666798d95bc35342e3e260f8cf226c82daa924778f7cc39781a0ddb05bd7114aceff96ba21b51116df4bbc760dc32"} build_url:https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011424 build_url: 'https://queue.taskcluster.net/v1/task/e1EOlh7jTO6EmgIwA2QRQw/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/Fx-Team-Non-PGO/sha512/e4b8655b38ff2be3eb90cf469c4b3613ef0e4641722c5eb2c74dd57d1e2ff224e2192a2ef55ea4e2d3651391f75e2132968627a4e7429dbf293fd623af318ee0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8cccc83c9d665740882e6dfc5652703f9b3e74e914ffe49a8166882a5f3389fd30bab2eb56515a5715dbc8952bb4a4cb81c3edf93e4534c04eff1e1639e943a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7672a8445a4ecc5505737f0703dd0776e01666798d95bc35342e3e260f8cf226c82daa924778f7cc39781a0ddb05bd7114aceff96ba21b51116df4bbc760dc32"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 12:55:11.568000) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:55:11.568348) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1465500564.992775-1480397910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 12:55:11.651449) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 12:55:11.651773) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 12:55:11.652055) ========= ========= Total master_lag: 0.33 =========