builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1466226039.0 results: success (0) buildid: 20160617203824 builduid: c39a5cc8d29a486c83c711c7c21b1780 revision: 3ce53bd1e25b93140484d3933c9339a829e0c1eb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.001547) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.002045) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.002344) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.073670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.074541) ========= 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=923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-17 22:00:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-06-17 22:00:39 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.210113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.210397) ========= 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=923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019186 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:39.264266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-17 22:00:39.264603) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3ce53bd1e25b93140484d3933c9339a829e0c1eb --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 3ce53bd1e25b93140484d3933c9339a829e0c1eb --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=923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-17 22:00:39,931 truncating revision to first 12 chars 2016-06-17 22:00:39,931 Setting DEBUG logging. 2016-06-17 22:00:39,931 attempt 1/10 2016-06-17 22:00:39,931 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3ce53bd1e25b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-17 22:00:40,832 unpacking tar archive at: fx-team-3ce53bd1e25b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.237500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-17 22:00:40.526271) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:40.526798) ========= script_repo_revision: 3ce53bd1e25b93140484d3933c9339a829e0c1eb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:40.527323) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:40.527698) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:40.536981) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-06-17 22:00:40.537413) ========= /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=923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:41 INFO - MultiFileLogger online at 20160617 22:00:41 in /builds/slave/test 22:00:41 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 22:00:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:41 INFO - {'append_to_log': False, 22:00:41 INFO - 'base_work_dir': '/builds/slave/test', 22:00:41 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:00:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:41 INFO - 'branch': 'Fx-Team-Non-PGO', 22:00:41 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:41 INFO - 'config_files': ('talos/linux_config.py',), 22:00:41 INFO - 'default_actions': ('clobber', 22:00:41 INFO - 'read-buildbot-config', 22:00:41 INFO - 'download-and-extract', 22:00:41 INFO - 'populate-webroot', 22:00:41 INFO - 'create-virtualenv', 22:00:41 INFO - 'install', 22:00:41 INFO - 'run-tests'), 22:00:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:41 INFO - 'download_minidump_stackwalk': True, 22:00:41 INFO - 'download_symbols': 'ondemand', 22:00:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:41 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:41 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:41 INFO - 'installer_path': 'installer.exe', 22:00:41 INFO - 'log_level': 'info', 22:00:41 INFO - 'log_name': 'talos', 22:00:41 INFO - 'log_to_console': True, 22:00:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:41 INFO - 'opt_config_files': (), 22:00:41 INFO - 'pip_index': False, 22:00:41 INFO - 'sps_profile': False, 22:00:41 INFO - 'sps_profile_interval': 0, 22:00:41 INFO - 'suite': 'dromaeojs', 22:00:41 INFO - 'system_bits': '32', 22:00:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:41 INFO - 'title': 'talos-linux64-ix-007', 22:00:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:41 INFO - 'use_talos_json': True, 22:00:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:41 INFO - 'work_dir': 'build'} 22:00:41 INFO - ##### 22:00:41 INFO - ##### Running clobber step. 22:00:41 INFO - ##### 22:00:41 INFO - Running pre-action listener: _resource_record_pre_action 22:00:41 INFO - Running main action method: clobber 22:00:41 INFO - rmtree: /builds/slave/test/build 22:00:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:41 INFO - Running post-action listener: _resource_record_post_action 22:00:41 INFO - ##### 22:00:41 INFO - ##### Finished clobber step (success) 22:00:41 INFO - ##### 22:00:41 INFO - ##### 22:00:41 INFO - ##### Running read-buildbot-config step. 22:00:41 INFO - ##### 22:00:41 INFO - Running pre-action listener: _resource_record_pre_action 22:00:41 INFO - Running main action method: read_buildbot_config 22:00:41 INFO - Using buildbot properties: 22:00:41 INFO - { 22:00:41 INFO - "project": "", 22:00:41 INFO - "product": "firefox", 22:00:41 INFO - "script_repo_revision": "production", 22:00:41 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:00:41 INFO - "repository": "", 22:00:41 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:00:41 INFO - "buildid": "20160617203824", 22:00:41 INFO - "pgo_build": "False", 22:00:41 INFO - "basedir": "/builds/slave/test", 22:00:41 INFO - "buildnumber": 3092, 22:00:41 INFO - "slavename": "talos-linux64-ix-007", 22:00:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:00:41 INFO - "platform": "ubuntu64_hw", 22:00:41 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:00:41 INFO - "branch": "fx-team", 22:00:41 INFO - "repo_path": "integration/fx-team", 22:00:41 INFO - "revision": "3ce53bd1e25b93140484d3933c9339a829e0c1eb", 22:00:41 INFO - "stage_platform": "linux64", 22:00:41 INFO - "builduid": "c39a5cc8d29a486c83c711c7c21b1780", 22:00:41 INFO - "slavebuilddir": "test" 22:00:41 INFO - } 22:00:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:00:41 INFO - Running post-action listener: _resource_record_post_action 22:00:41 INFO - ##### 22:00:41 INFO - ##### Finished read-buildbot-config step (success) 22:00:41 INFO - ##### 22:00:41 INFO - ##### 22:00:41 INFO - ##### Running download-and-extract step. 22:00:41 INFO - ##### 22:00:41 INFO - Running pre-action listener: _resource_record_pre_action 22:00:41 INFO - Running main action method: download_and_extract 22:00:41 INFO - mkdir: /builds/slave/test/build/tests 22:00: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')]} 22:00:41 INFO - https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:00:45 INFO - Downloaded 1361 bytes. 22:00:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:45 INFO - Using the following test package requirements: 22:00:45 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:45 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:45 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:45 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'jsshell-linux-x86_64.zip'], 22:00:45 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:45 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:45 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:45 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:45 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:45 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:45 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:45 INFO - https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:46 INFO - Downloaded 22654592 bytes. 22:00:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:47 INFO - Return code: 0 22:00:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:47 INFO - https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:52 INFO - Downloaded 22654592 bytes. 22:00:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:53 INFO - Return code: 0 22:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:53 INFO - https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:00:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:00:54 INFO - Downloaded 14405384 bytes. 22:00:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:00:54 INFO - Return code: 0 22:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:54 INFO - https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:00:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:00:56 INFO - Downloaded 56226218 bytes. 22:00:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:56 INFO - mkdir: /builds/slave/test/properties 22:00:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:00:56 INFO - Writing to file /builds/slave/test/properties/build_url 22:00:56 INFO - Contents: 22:00:56 INFO - build_url:https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:57 INFO - Running post-action listener: _resource_record_post_action 22:00:57 INFO - Running post-action listener: set_extra_try_arguments 22:00:57 INFO - ##### 22:00:57 INFO - ##### Finished download-and-extract step (success) 22:00:57 INFO - ##### 22:00:57 INFO - ##### 22:00:57 INFO - ##### Running populate-webroot step. 22:00:57 INFO - ##### 22:00:57 INFO - Running pre-action listener: _resource_record_pre_action 22:00:57 INFO - Running main action method: populate_webroot 22:00:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:57 INFO - '--tppagecycles', 22:00:57 INFO - '7'], 22:00:57 INFO - 'tests': ['tsvgm']}}, 22:00:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:00:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:00:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:00:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['damp', 'tps']}, 22:00:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'tests': ['damp', 'tps']}, 22:00:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['dromaeo_dom']}, 22:00:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['basic_compositor_video']}, 22:00:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:00:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['a11yr', 22:00:57 INFO - 'ts_paint', 22:00:57 INFO - 'tpaint', 22:00:57 INFO - 'sessionrestore', 22:00:57 INFO - 'sessionrestore_no_auto_restore', 22:00:57 INFO - 'tabpaint']}, 22:00:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:00:57 INFO - 'ts_paint', 22:00:57 INFO - 'tpaint', 22:00:57 INFO - 'sessionrestore', 22:00:57 INFO - 'sessionrestore_no_auto_restore', 22:00:57 INFO - 'tabpaint']}, 22:00:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:00:57 INFO - 'ts_paint', 22:00:57 INFO - 'tpaint', 22:00:57 INFO - 'sessionrestore', 22:00:57 INFO - 'sessionrestore_no_auto_restore', 22:00:57 INFO - 'tabpaint']}, 22:00:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:00:57 INFO - 'ts_paint', 22:00:57 INFO - 'tpaint', 22:00:57 INFO - 'sessionrestore', 22:00:57 INFO - 'sessionrestore_no_auto_restore', 22:00:57 INFO - 'tabpaint']}, 22:00:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['a11yr', 22:00:57 INFO - 'ts_paint', 22:00:57 INFO - 'tpaint', 22:00:57 INFO - 'sessionrestore', 22:00:57 INFO - 'sessionrestore_no_auto_restore', 22:00:57 INFO - 'tabpaint']}, 22:00:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['a11yr', 22:00:57 INFO - 'ts_paint', 22:00:57 INFO - 'tpaint', 22:00:57 INFO - 'sessionrestore', 22:00:57 INFO - 'sessionrestore_no_auto_restore', 22:00:57 INFO - 'tabpaint']}, 22:00:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['tsvgx', 22:00:57 INFO - 'tsvgr_opacity', 22:00:57 INFO - 'tart', 22:00:57 INFO - 'tscrollx', 22:00:57 INFO - 'cart']}, 22:00:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:00:57 INFO - 'tsvgr_opacity', 22:00:57 INFO - 'tart', 22:00:57 INFO - 'tscrollx', 22:00:57 INFO - 'cart']}, 22:00:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'talos_options': ['--disable-e10s'], 22:00:57 INFO - 'tests': ['tp5o']}, 22:00:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'tests': ['tp5o']}, 22:00:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'talos_options': ['--disable-e10s', 22:00:57 INFO - '--xperf_path', 22:00:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:57 INFO - 'tests': ['tp5n']}, 22:00:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:57 INFO - 'talos_options': ['--xperf_path', 22:00:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:57 INFO - 'tests': ['tp5n']}}, 22:00:57 INFO - 'talos.zip': {'path': '', 22:00:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:57 INFO - Running post-action listener: _resource_record_post_action 22:00:57 INFO - ##### 22:00:57 INFO - ##### Finished populate-webroot step (success) 22:00:57 INFO - ##### 22:00:57 INFO - ##### 22:00:57 INFO - ##### Running create-virtualenv step. 22:00:57 INFO - ##### 22:00:57 INFO - Running pre-action listener: _resource_record_pre_action 22:00:57 INFO - Running main action method: create_virtualenv 22:00:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:00:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:00:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:00:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:00:57 INFO - Using real prefix '/usr' 22:00:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:00:57 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:59 INFO - Installing pip.................done. 22:00:59 INFO - Return code: 0 22:00:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:59 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:00:59 INFO - Using env: {'DISPLAY': ':0', 22:00:59 INFO - 'HOME': '/home/cltbld', 22:00:59 INFO - 'LANG': 'en_US.UTF-8', 22:00:59 INFO - 'LANGUAGE': 'en_US:en', 22:00:59 INFO - 'LOGNAME': 'cltbld', 22:00:59 INFO - 'MAIL': '/var/mail/cltbld', 22:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:59 INFO - 'MOZ_NO_REMOTE': '1', 22:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:59 INFO - 'NO_EM_RESTART': '1', 22:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:59 INFO - 'PWD': '/builds/slave/test', 22:00:59 INFO - 'SHELL': '/bin/bash', 22:00:59 INFO - 'SHLVL': '1', 22:00:59 INFO - 'TERM': 'linux', 22:00:59 INFO - 'TMOUT': '86400', 22:00:59 INFO - 'USER': 'cltbld', 22:00:59 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:59 INFO - '_': '/tools/buildbot/bin/python'} 22:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:01:00 INFO - Cleaning up... 22:01:00 INFO - Return code: 0 22:01:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:01: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 psutil>=3.1.1 22:01:00 INFO - Using env: {'DISPLAY': ':0', 22:01:00 INFO - 'HOME': '/home/cltbld', 22:01:00 INFO - 'LANG': 'en_US.UTF-8', 22:01:00 INFO - 'LANGUAGE': 'en_US:en', 22:01:00 INFO - 'LOGNAME': 'cltbld', 22:01:00 INFO - 'MAIL': '/var/mail/cltbld', 22:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:00 INFO - 'MOZ_NO_REMOTE': '1', 22:01:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:00 INFO - 'NO_EM_RESTART': '1', 22:01:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:00 INFO - 'PWD': '/builds/slave/test', 22:01:00 INFO - 'SHELL': '/bin/bash', 22:01:00 INFO - 'SHLVL': '1', 22:01:00 INFO - 'TERM': 'linux', 22:01:00 INFO - 'TMOUT': '86400', 22:01:00 INFO - 'USER': 'cltbld', 22:01:00 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:00 INFO - '_': '/tools/buildbot/bin/python'} 22:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:00 INFO - Downloading/unpacking psutil>=3.1.1 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:02 INFO - Installing collected packages: psutil 22:01:02 INFO - Running setup.py install for psutil 22:01:02 INFO - building 'psutil._psutil_linux' extension 22:01:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:01:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:01:02 INFO - building 'psutil._psutil_posix' extension 22:01:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:01:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:01:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:02 INFO - Successfully installed psutil 22:01:02 INFO - Cleaning up... 22:01:02 INFO - Return code: 0 22:01:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:02 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:01:02 INFO - Using env: {'DISPLAY': ':0', 22:01:02 INFO - 'HOME': '/home/cltbld', 22:01:02 INFO - 'LANG': 'en_US.UTF-8', 22:01:02 INFO - 'LANGUAGE': 'en_US:en', 22:01:02 INFO - 'LOGNAME': 'cltbld', 22:01:02 INFO - 'MAIL': '/var/mail/cltbld', 22:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:02 INFO - 'MOZ_NO_REMOTE': '1', 22:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:02 INFO - 'NO_EM_RESTART': '1', 22:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:02 INFO - 'PWD': '/builds/slave/test', 22:01:02 INFO - 'SHELL': '/bin/bash', 22:01:02 INFO - 'SHLVL': '1', 22:01:02 INFO - 'TERM': 'linux', 22:01:02 INFO - 'TMOUT': '86400', 22:01:02 INFO - 'USER': 'cltbld', 22:01:02 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:02 INFO - '_': '/tools/buildbot/bin/python'} 22:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:05 INFO - Installing collected packages: mozsystemmonitor 22:01:05 INFO - Running setup.py install for mozsystemmonitor 22:01:05 INFO - Successfully installed mozsystemmonitor 22:01:05 INFO - Cleaning up... 22:01:05 INFO - Return code: 0 22:01:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:05 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:01:05 INFO - Using env: {'DISPLAY': ':0', 22:01:05 INFO - 'HOME': '/home/cltbld', 22:01:05 INFO - 'LANG': 'en_US.UTF-8', 22:01:05 INFO - 'LANGUAGE': 'en_US:en', 22:01:05 INFO - 'LOGNAME': 'cltbld', 22:01:05 INFO - 'MAIL': '/var/mail/cltbld', 22:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:05 INFO - 'MOZ_NO_REMOTE': '1', 22:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:05 INFO - 'NO_EM_RESTART': '1', 22:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:05 INFO - 'PWD': '/builds/slave/test', 22:01:05 INFO - 'SHELL': '/bin/bash', 22:01:05 INFO - 'SHLVL': '1', 22:01:05 INFO - 'TERM': 'linux', 22:01:05 INFO - 'TMOUT': '86400', 22:01:05 INFO - 'USER': 'cltbld', 22:01:05 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:05 INFO - '_': '/tools/buildbot/bin/python'} 22:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:05 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:07 INFO - warning: no files found matching '*.txt' 22:01:07 INFO - no previously-included directories found matching 'build' 22:01:07 INFO - no previously-included directories found matching 'dist' 22:01:07 INFO - no previously-included directories found matching '.git*' 22:01:07 INFO - Installing collected packages: jsonschema, functools32 22:01:07 INFO - Running setup.py install for functools32 22:01:07 INFO - warning: no files found matching '*.txt' 22:01:07 INFO - no previously-included directories found matching 'build' 22:01:07 INFO - no previously-included directories found matching 'dist' 22:01:07 INFO - no previously-included directories found matching '.git*' 22:01:07 INFO - Successfully installed jsonschema functools32 22:01:07 INFO - Cleaning up... 22:01:07 INFO - Return code: 0 22:01:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:01:07 INFO - Using env: {'DISPLAY': ':0', 22:01:07 INFO - 'HOME': '/home/cltbld', 22:01:07 INFO - 'LANG': 'en_US.UTF-8', 22:01:07 INFO - 'LANGUAGE': 'en_US:en', 22:01:07 INFO - 'LOGNAME': 'cltbld', 22:01:07 INFO - 'MAIL': '/var/mail/cltbld', 22:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:07 INFO - 'MOZ_NO_REMOTE': '1', 22:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:07 INFO - 'NO_EM_RESTART': '1', 22:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:07 INFO - 'PWD': '/builds/slave/test', 22:01:07 INFO - 'SHELL': '/bin/bash', 22:01:07 INFO - 'SHLVL': '1', 22:01:07 INFO - 'TERM': 'linux', 22:01:07 INFO - 'TMOUT': '86400', 22:01:07 INFO - 'USER': 'cltbld', 22:01:07 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:07 INFO - '_': '/tools/buildbot/bin/python'} 22:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:07 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:09 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:10 INFO - Downloading docopt-0.6.1.tar.gz 22:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:10 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:10 INFO - Running setup.py install for blobuploader 22:01:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:10 INFO - Running setup.py install for requests 22:01:11 INFO - Running setup.py install for docopt 22:01:11 INFO - Successfully installed blobuploader requests docopt 22:01:11 INFO - Cleaning up... 22:01:11 INFO - Return code: 0 22:01:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:01:11 INFO - Using env: {'DISPLAY': ':0', 22:01:11 INFO - 'HOME': '/home/cltbld', 22:01:11 INFO - 'LANG': 'en_US.UTF-8', 22:01:11 INFO - 'LANGUAGE': 'en_US:en', 22:01:11 INFO - 'LOGNAME': 'cltbld', 22:01:11 INFO - 'MAIL': '/var/mail/cltbld', 22:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:11 INFO - 'MOZ_NO_REMOTE': '1', 22:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:11 INFO - 'NO_EM_RESTART': '1', 22:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:11 INFO - 'PWD': '/builds/slave/test', 22:01:11 INFO - 'SHELL': '/bin/bash', 22:01:11 INFO - 'SHLVL': '1', 22:01:11 INFO - 'TERM': 'linux', 22:01:11 INFO - 'TMOUT': '86400', 22:01:11 INFO - 'USER': 'cltbld', 22:01:11 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:11 INFO - '_': '/tools/buildbot/bin/python'} 22:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:11 INFO - Running setup.py (path:/tmp/pip-7vr0yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:11 INFO - Running setup.py (path:/tmp/pip-b7WLjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:11 INFO - Running setup.py (path:/tmp/pip-6TfTcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:11 INFO - Running setup.py (path:/tmp/pip-MV4ibw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:11 INFO - Running setup.py (path:/tmp/pip-z9KI96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:11 INFO - Running setup.py (path:/tmp/pip-mbbupK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:11 INFO - Running setup.py (path:/tmp/pip-h87M7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:11 INFO - Running setup.py (path:/tmp/pip-jyWUOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:12 INFO - Running setup.py (path:/tmp/pip-4gjIib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:12 INFO - Running setup.py (path:/tmp/pip-Y_a0FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:12 INFO - Running setup.py (path:/tmp/pip-av68nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:12 INFO - Running setup.py (path:/tmp/pip-VKLEAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:12 INFO - Running setup.py (path:/tmp/pip-qylzuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:12 INFO - Running setup.py (path:/tmp/pip-y06k4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:12 INFO - Running setup.py (path:/tmp/pip-RHpA_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:12 INFO - Running setup.py (path:/tmp/pip-GEhQ7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:12 INFO - Running setup.py (path:/tmp/pip-uuEIcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:12 INFO - Running setup.py install for manifestparser 22:01:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:12 INFO - Running setup.py install for mozcrash 22:01:12 INFO - Running setup.py install for mozdebug 22:01:12 INFO - Running setup.py install for mozdevice 22:01:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Running setup.py install for mozfile 22:01:13 INFO - Running setup.py install for mozhttpd 22:01:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Running setup.py install for mozinfo 22:01:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Running setup.py install for mozInstall 22:01:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Running setup.py install for mozleak 22:01:13 INFO - Running setup.py install for mozlog 22:01:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Running setup.py install for moznetwork 22:01:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:13 INFO - Running setup.py install for mozprocess 22:01:13 INFO - Running setup.py install for mozprofile 22:01:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:14 INFO - Running setup.py install for mozrunner 22:01:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:14 INFO - Running setup.py install for mozscreenshot 22:01:14 INFO - Running setup.py install for moztest 22:01:14 INFO - Running setup.py install for mozversion 22:01:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:14 INFO - Cleaning up... 22:01:14 INFO - Return code: 0 22:01:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:14 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:01:14 INFO - Using env: {'DISPLAY': ':0', 22:01:14 INFO - 'HOME': '/home/cltbld', 22:01:14 INFO - 'LANG': 'en_US.UTF-8', 22:01:14 INFO - 'LANGUAGE': 'en_US:en', 22:01:14 INFO - 'LOGNAME': 'cltbld', 22:01:14 INFO - 'MAIL': '/var/mail/cltbld', 22:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:14 INFO - 'MOZ_NO_REMOTE': '1', 22:01:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:14 INFO - 'NO_EM_RESTART': '1', 22:01:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:14 INFO - 'PWD': '/builds/slave/test', 22:01:14 INFO - 'SHELL': '/bin/bash', 22:01:14 INFO - 'SHLVL': '1', 22:01:14 INFO - 'TERM': 'linux', 22:01:14 INFO - 'TMOUT': '86400', 22:01:14 INFO - 'USER': 'cltbld', 22:01:14 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:14 INFO - '_': '/tools/buildbot/bin/python'} 22:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:14 INFO - Running setup.py (path:/tmp/pip-WtTOZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:14 INFO - Running setup.py (path:/tmp/pip-f5d48m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:14 INFO - Running setup.py (path:/tmp/pip-pnuyjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:14 INFO - Running setup.py (path:/tmp/pip-w65KoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:15 INFO - Running setup.py (path:/tmp/pip-6zh_2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:15 INFO - Running setup.py (path:/tmp/pip-0O9Rb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:15 INFO - Running setup.py (path:/tmp/pip-RSizBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:15 INFO - Running setup.py (path:/tmp/pip-7d1gas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:15 INFO - Running setup.py (path:/tmp/pip-vvLCU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:15 INFO - Running setup.py (path:/tmp/pip-El1Jt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:15 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)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:15 INFO - Running setup.py (path:/tmp/pip-naVV_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:15 INFO - Running setup.py (path:/tmp/pip-JC_61M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:15 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)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:15 INFO - Running setup.py (path:/tmp/pip-Dy35Tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:15 INFO - Running setup.py (path:/tmp/pip-PERly8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:15 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)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:15 INFO - Running setup.py (path:/tmp/pip-cC7qfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:15 INFO - Running setup.py (path:/tmp/pip-UDHaIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:15 INFO - Running setup.py (path:/tmp/pip-Q340Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:17 INFO - Downloading blessings-1.6.tar.gz 22:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:17 INFO - Installing collected packages: blessings 22:01:17 INFO - Running setup.py install for blessings 22:01:18 INFO - Successfully installed blessings 22:01:18 INFO - Cleaning up... 22:01:18 INFO - Return code: 0 22:01:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:18 INFO - Reading from file tmpfile_stdout 22:01:18 INFO - Current package versions: 22:01:18 INFO - argparse == 1.2.1 22:01:18 INFO - blessings == 1.6 22:01:18 INFO - blobuploader == 1.2.4 22:01:18 INFO - docopt == 0.6.1 22:01:18 INFO - functools32 == 3.2.3-2 22:01:18 INFO - jsonschema == 2.5.1 22:01:18 INFO - manifestparser == 1.1 22:01:18 INFO - mozInstall == 1.12 22:01:18 INFO - mozcrash == 0.17 22:01:18 INFO - mozdebug == 0.1 22:01:18 INFO - mozdevice == 0.48 22:01:18 INFO - mozfile == 1.2 22:01:18 INFO - mozhttpd == 0.7 22:01:18 INFO - mozinfo == 0.9 22:01:18 INFO - mozleak == 0.1 22:01:18 INFO - mozlog == 3.2 22:01:18 INFO - moznetwork == 0.27 22:01:18 INFO - mozprocess == 0.22 22:01:18 INFO - mozprofile == 0.28 22:01:18 INFO - mozrunner == 6.11 22:01:18 INFO - mozscreenshot == 0.1 22:01:18 INFO - mozsystemmonitor == 0.3 22:01:18 INFO - moztest == 0.7 22:01:18 INFO - mozversion == 1.4 22:01:18 INFO - psutil == 3.1.1 22:01:18 INFO - requests == 1.2.3 22:01:18 INFO - wsgiref == 0.1.2 22:01:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:01:18 INFO - Using env: {'DISPLAY': ':0', 22:01:18 INFO - 'HOME': '/home/cltbld', 22:01:18 INFO - 'LANG': 'en_US.UTF-8', 22:01:18 INFO - 'LANGUAGE': 'en_US:en', 22:01:18 INFO - 'LOGNAME': 'cltbld', 22:01:18 INFO - 'MAIL': '/var/mail/cltbld', 22:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:18 INFO - 'MOZ_NO_REMOTE': '1', 22:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:18 INFO - 'NO_EM_RESTART': '1', 22:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:18 INFO - 'PWD': '/builds/slave/test', 22:01:18 INFO - 'SHELL': '/bin/bash', 22:01:18 INFO - 'SHLVL': '1', 22:01:18 INFO - 'TERM': 'linux', 22:01:18 INFO - 'TMOUT': '86400', 22:01:18 INFO - 'USER': 'cltbld', 22:01:18 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:18 INFO - '_': '/tools/buildbot/bin/python'} 22:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:01:18 INFO - Cleaning up... 22:01:18 INFO - Return code: 0 22:01:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:01:18 INFO - Using env: {'DISPLAY': ':0', 22:01:18 INFO - 'HOME': '/home/cltbld', 22:01:18 INFO - 'LANG': 'en_US.UTF-8', 22:01:18 INFO - 'LANGUAGE': 'en_US:en', 22:01:18 INFO - 'LOGNAME': 'cltbld', 22:01:18 INFO - 'MAIL': '/var/mail/cltbld', 22:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:18 INFO - 'MOZ_NO_REMOTE': '1', 22:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:18 INFO - 'NO_EM_RESTART': '1', 22:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:18 INFO - 'PWD': '/builds/slave/test', 22:01:18 INFO - 'SHELL': '/bin/bash', 22:01:18 INFO - 'SHLVL': '1', 22:01:18 INFO - 'TERM': 'linux', 22:01:18 INFO - 'TMOUT': '86400', 22:01:18 INFO - 'USER': 'cltbld', 22:01:18 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:18 INFO - '_': '/tools/buildbot/bin/python'} 22:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:18 INFO - Cleaning up... 22:01:18 INFO - Return code: 0 22:01:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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 0x7f7f8b7a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ad350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, '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': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:18 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 22:01:18 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 22:01:18 INFO - Using env: {'DISPLAY': ':0', 22:01:18 INFO - 'HOME': '/home/cltbld', 22:01:18 INFO - 'LANG': 'en_US.UTF-8', 22:01:18 INFO - 'LANGUAGE': 'en_US:en', 22:01:18 INFO - 'LOGNAME': 'cltbld', 22:01:18 INFO - 'MAIL': '/var/mail/cltbld', 22:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:18 INFO - 'MOZ_NO_REMOTE': '1', 22:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:18 INFO - 'NO_EM_RESTART': '1', 22:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:18 INFO - 'PWD': '/builds/slave/test', 22:01:18 INFO - 'SHELL': '/bin/bash', 22:01:18 INFO - 'SHLVL': '1', 22:01:18 INFO - 'TERM': 'linux', 22:01:18 INFO - 'TMOUT': '86400', 22:01:18 INFO - 'USER': 'cltbld', 22:01:18 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:18 INFO - '_': '/tools/buildbot/bin/python'} 22:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:18 INFO - Downloading/unpacking flake8 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:01:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:01:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:01:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:21 INFO - no previously-included directories found matching 'docs/_build' 22:01:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:01:21 INFO - Running setup.py install for pep8 22:01:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:21 INFO - no previously-included directories found matching 'docs/_build' 22:01:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:01:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:01:21 INFO - Cleaning up... 22:01:21 INFO - Return code: 0 22:01:21 INFO - Running post-action listener: _resource_record_post_action 22:01:21 INFO - Running post-action listener: _start_resource_monitoring 22:01:21 INFO - Starting resource monitoring. 22:01:21 INFO - ##### 22:01:21 INFO - ##### Finished create-virtualenv step (success) 22:01:21 INFO - ##### 22:01:21 INFO - ##### 22:01:21 INFO - ##### Running install step. 22:01:21 INFO - ##### 22:01:21 INFO - Running pre-action listener: _resource_record_pre_action 22:01:21 INFO - Running main action method: install 22:01:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:22 INFO - Reading from file tmpfile_stdout 22:01:22 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:22 INFO - Reading from file tmpfile_stdout 22:01:22 INFO - Output received: 22:01:22 INFO - Usage: mozinstall [options] installer 22:01:22 INFO - Options: 22:01:22 INFO - -h, --help show this help message and exit 22:01:22 INFO - -d DEST, --destination=DEST 22:01:22 INFO - Directory to install application into. [default: 22:01:22 INFO - "/builds/slave/test"] 22:01:22 INFO - --app=APP Application being installed. [default: firefox] 22:01:22 INFO - mkdir: /builds/slave/test/build/application 22:01:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:01:38 INFO - Reading from file tmpfile_stdout 22:01:38 INFO - Output received: 22:01:38 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:38 INFO - Running post-action listener: _resource_record_post_action 22:01:38 INFO - ##### 22:01:38 INFO - ##### Finished install step (success) 22:01:38 INFO - ##### 22:01:38 INFO - ##### 22:01:38 INFO - ##### Running run-tests step. 22:01:38 INFO - ##### 22:01:38 INFO - Running pre-action listener: _resource_record_pre_action 22:01:38 INFO - Running main action method: run_tests 22:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:38 INFO - Python 2.7.3 22:01:38 INFO - Return code: 0 22:01:38 INFO - grabbing minidump binary from tooltool 22:01: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')]} 22:01:38 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 0x263c1c0>, '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 0x263d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263d550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:01:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:01:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:01:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:38 INFO - Return code: 0 22:01:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:01:39 INFO - Return code: 0 22:01:39 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/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 22:01:39 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/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 22:01:39 INFO - Using env: {'DISPLAY': ':0', 22:01:39 INFO - 'HOME': '/home/cltbld', 22:01:39 INFO - 'LANG': 'en_US.UTF-8', 22:01:39 INFO - 'LANGUAGE': 'en_US:en', 22:01:39 INFO - 'LOGNAME': 'cltbld', 22:01:39 INFO - 'MAIL': '/var/mail/cltbld', 22:01:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:39 INFO - 'MOZ_NO_REMOTE': '1', 22:01:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:39 INFO - 'NO_EM_RESTART': '1', 22:01:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:39 INFO - 'PWD': '/builds/slave/test', 22:01:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:39 INFO - 'SHELL': '/bin/bash', 22:01:39 INFO - 'SHLVL': '1', 22:01:39 INFO - 'TERM': 'linux', 22:01:39 INFO - 'TMOUT': '86400', 22:01:39 INFO - 'USER': 'cltbld', 22:01:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446', 22:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:39 INFO - '_': '/tools/buildbot/bin/python'} 22:01:39 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/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 22:01:39 INFO - mozversion application_buildid: 20160617203824 22:01:39 INFO - mozversion application_changeset: 3ce53bd1e25b93140484d3933c9339a829e0c1eb 22:01:39 INFO - mozversion application_display_name: Nightly 22:01:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:39 INFO - mozversion application_name: Firefox 22:01:39 INFO - mozversion application_remotingname: firefox 22:01:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:01:39 INFO - mozversion application_vendor: Mozilla 22:01:39 INFO - mozversion application_version: 50.0a1 22:01:39 INFO - mozversion platform_buildid: 20160617203824 22:01:39 INFO - mozversion platform_changeset: 3ce53bd1e25b93140484d3933c9339a829e0c1eb 22:01:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:01:39 INFO - mozversion platform_version: 50.0a1 22:01:39 INFO - using testdate: 1466226099 22:01:39 INFO - actual date: 1466226099 22:01:39 INFO - starting webserver on 'localhost:41103' 22:01:39 INFO - SUITE-START | Running 2 tests 22:01:39 INFO - TEST-START | dromaeo_css 22:01:39 INFO - operating with platform_type : linux_ 22:01:39 INFO - Initialising browser for dromaeo_css test... 22:01:39 INFO - TEST-INFO | started process 14233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvPHmO1/profile http://localhost:41103/getInfo.html) 22:01:43 INFO - PROCESS | 14233 | __metrics Screen width/height:1600/1200 22:01:43 INFO - PROCESS | 14233 | colorDepth:24 22:01:43 INFO - PROCESS | 14233 | Browser inner width/height: 1024/697 22:01:43 INFO - PROCESS | 14233 | __metrics 22:01:44 INFO - PROCESS | 14233 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:01:45 INFO - TEST-INFO | 14233: exit 0 22:01:45 INFO - Browser initialized. 22:01:45 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:45 INFO - TEST-INFO | started process 14312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvPHmO1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:57 INFO - PROCESS | 14312 | RSS: Main: 175443968 22:01:57 INFO - PROCESS | 14312 | 22:02:48 INFO - PROCESS | 14312 | 1466226168864 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:05:01 INFO - PROCESS | 14312 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41103/tests/dromaeo/cssquery-ext.html) 22:05:03 INFO - PROCESS | 14312 | RSS: Main: 290611200 22:05:03 INFO - PROCESS | 14312 | 22:08:05 INFO - PROCESS | 14312 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-ext.html (next: http://localhost:41103/tests/dromaeo/cssquery-jquery.html) 22:08:06 INFO - PROCESS | 14312 | RSS: Main: 256774144 22:08:06 INFO - PROCESS | 14312 | 22:11:08 INFO - PROCESS | 14312 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41103/tests/dromaeo/cssquery-mootools.html) 22:11:08 INFO - PROCESS | 14312 | RSS: Main: 264785920 22:11:08 INFO - PROCESS | 14312 | 22:14:11 INFO - PROCESS | 14312 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41103/tests/dromaeo/cssquery-prototype.html) 22:14:11 INFO - PROCESS | 14312 | RSS: Main: 260358144 22:14:11 INFO - PROCESS | 14312 | 22:17:14 INFO - PROCESS | 14312 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41103/tests/dromaeo/cssquery-yui.html) 22:17:15 INFO - PROCESS | 14312 | RSS: Main: 237023232 22:17:15 INFO - PROCESS | 14312 | 22:20:17 INFO - PROCESS | 14312 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-yui.html (next: http://localhost:41103/tests/dromaeo/cssquery-dojo.html) 22:20:18 INFO - PROCESS | 14312 | RSS: Main: 289185792 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | __start_tp_report 22:20:18 INFO - PROCESS | 14312 | _x_x_mozilla_page_load 22:20:18 INFO - PROCESS | 14312 | _x_x_mozilla_page_load_details 22:20:18 INFO - PROCESS | 14312 | |i|pagename|runs| 22:20:18 INFO - PROCESS | 14312 | |0;dojo.html;2584.7802936750377;2645.616385624086;2667.959980600291;2672.1182009936715;2680.1423544465765;4770.666053376265;4778.952210477894;4815.951840481594;4861.708297502152;4899.020195960808;4782.473927867932;4834.9033019339595;4844.079624871272;4852.3206751054795;4908.0920029794515;4414.381986521881;4429.77851107445;4471.843485478009;4536.818527258905;4608.700434471765;4517.661175411858;4547.5452454754595;4557.794899229542;4567.817287308504;4580.083983203356;4365.585269399402;4374.387585737999;4401.185780630593;4408.316710909814;4456.955430445686;4398.450193725785;4400.867973960786;4405.691601587891;4405.977970110161;4462.687611867171;4433.866983990485;4435.3125265583885;4479.39528163697;4487.753173575452;4488.5960263576435;4663.300504924255;4756.286557016475;4810.518948105197;4856.4172299323955;4859.0767754126955;5169.172932330809;5300.01949639318;5300.867478313035;5303.178007408857;5306.628536002443;5299.947000529984;5314.388845282765;5318.73406329682;5333.839984800462;5342.412334643186;4768.403949506312;4773.618110551148;4828.758562071883;4905.509449055137;4948.38145231846;5306.62853600252;5309;5341.519263266325;5353.197020446964;5367.221752845816;5082.161443361804;5124.948750512447;5134.717590532482;5136.614753893473;5142.845714628567;5041.571466425322;5147.2279158126585;5197.142471492161;5217.713025783618;5224.738763061831;5195.376554813447;5198.116320225571;5200.427952925175;5217.895642087213;5231.346081739783;5174.974125129425;5184.948150518447;5185.5073767992735;5185.688858668492;5236.842894713165;4337.0458499130145;4396.648268138541;4410.7353558785935;4479.3057076153245;4481.932771008437;3880.825362858678;3881.4954055972544;3887.5723670396233;3918.8824335269987;3970.7419017763755;4457.710248833815;4473.642108631302;4474.7762611869275;4476.015276639134;4516.209663308908;5108.846734597968;5155.819546315861;5169.68981861082;5278.630495865329;5335.626506144533;4435.290353543418;4441.622462090694;4462.799174037176;4509.706869053567;4512.435945506812;4732.905341893143;4752.334673145762;4752.7623618818925;4752.904941901212;4800.015996720734;4701.318308845267;4740.360051393018;4767;4922.0648076866;4928.383952005999;5076.162433198629;5080.46655101209;5092.694438333712;5094.133997220407;5144.6398752087;4773.618110551217;4774.40319960005;4777.283407488904;4779.784909679142;4782.067496838084;5041.571466425395;5059.747012649279;5105.285260063668;5162.664426812245;5173.508516690908;5229.816956406507;5245.134552798745;5245.501677340647;5249.055170069424;5249.501297376743;5061.190209566452;5061.443241243386;5063.594912407072;5070.3155074064525;5087.262346959744;4938.555530002317;5045.142325804695;5068.442471328077;5084.466131056186;5101.107306221471;5006.173981293192;5042.596592272536;5099.36257967754;5106.2595923590125;5108.259302401206;4982.925256121233;5152.690838549699;5153.896922061613;5190.766415511386;5202.453742357149;4847.80608775645;4881.145799485147;4912.238603016538;4930.408350997904;4987.251912213197 22:20:18 INFO - PROCESS | 14312 | |1;ext.html;520.8939994688558;579.3192998227082;582.798934367643;584.3747190506159;601.6119602856157;27036.324091897714;30115.54826677599;30149.09552713422;30171;30186.245343866387;29456.263593410124;30036.699633003664;41527.79236103823;42043.57956420435;42408.57591424085;7749.147593764696;7856.764297071097;7879.606019699021;7880.487768295071;7915.722949696762;7829.099653539831;7917.445778795487;7919.168487308807;7924.405669574776;7925.603719814015;7550.7734767957045;10149.543270552844;10368.481575921174;10369.844452333222;10735.248532602722;10253.641122560712;10309.845352319646;10310.07209351162;10317.84523232152;10319.948400258027;7533.773986780406;9756.609735610567;10102.242331825142;10182.59269629214;10192.031756983075;37371.439428408594;37777.24445511102;40349.596504034875;40358;40400;2774.986125069382;2976.5683975823385;2989.7907146499756;3076.9384612307854;3370.7303415726683;29371;29826;29933.550996734848;30117;30668.8466557668;3519.0674471265133;3535.2045789697368;3537.6992955598803;3545.4327307630656;3596.1369271374683;264182.67908660526;264526;264944;265451;265757;55399.169012464845;55884.72057639686;55915.44084559102;55976;56002.15996760052;57485.425145748006;57492.42507574955;57721;58092.41907580954;58171.418285817446;14422.206778627276;14871.256437178314;14884.92557537227;14886.925565372103;14887.404503819942;11029.117670586334;11071.16966227536;11074.335539867634;11075.446227688582;11075.50160242791;16088.919555402383;17485.562860928578;17833.732494012602;18313.90843045803;18352.724709129372;6614.371634694697;6624.635645039569;6633.800985970429;6728.091707619409;6741.157355330583;779.6491578789568;854.9316054715608;863.5250612163285;896.4038914122036;939.3518472254123;26757.331066723487;27157;27497.725022749517;27694.169174924784;27810.582841257397;928.7167413938813;928.8606708993705;954.9140577348072;1040.8074506216374;1048.1405247696816;14640;14769.92615036918;14892.478763243234;14899.329530171168;14978.62553436151;117710.41144794221;118505;118702;118750.4062479682;118790;48430;48872.5112748875;48958.75520622374;48967.51032489629;49047.264291035666;1124.550179928019;1172.4137931034484;1177.5172179406402;1182.0484509969558;1186.5550418593027;26675.466490670467;27542.17373478799;27745;27820;27940.161795146178;41715.582844172386;42093.78953105215;42462;42473.78763106165;42481;321020;324205.757942427;324417;324958;326401;32325.35349292954;33664.831675841466;37739.05652358713;38304.659336923076;38787.224255514164;41072.79463602663;41133.382999255635;41616.167676645695;42220.15559688851;42551.574484254765;33623.327533448704;35417.46873796843;35524.64475355214;35941.921742347775;36023.819880900424;35223.82388088043;35263.294734105686;35436.29127417489;35512.644873551966;35693.64306357007;34142.31715365629;34565.65434345725;34976.65023349734;35599.28801424009;35603.82198088993;30514.237144071576;32211;32357.191070223435;32777.344453111284;32798.67201328051;24582.033437325328;24886.875565622056;25081.372965676004;25136.874315628305;25248.747512525373 22:20:18 INFO - PROCESS | 14312 | |2;jquery.html;366.6297039989611;368.2689860626656;374.46077648186593;381.7881076002817;383.7678204686163;15737.291821868015;16632;17672.116394180302;17682;17709;16346.918265408685;16590.75113873293;17396.913015434937;17411.21649525771;17428.564285892826;5132.8460146195675;5300.867478313035;5310.654807437523;5340.385855626614;5345.866353341159;3771.905702357449;5227.08526007949;5488.984537860489;6113.174721412619;6134.693265336738;6008.399160083978;6157.261128664567;6191.133241346215;6200.100985357143;6211.937880621181;5875.706214689249;6138.263408390977;6139.416755408251;6143.569950103494;6161.7227224775;5222.294990176316;5275.709835959021;5277.788888444457;5278;5294.311739473882;20508.17967281307;20725.27461538839;21037.47406314839;21058.157673693033;21065.262715804874;1901.077977181066;1905.5236190952262;1944.7277381166648;1975.683890577515;1999.9700004499944;12686.365681715877;13219.140755850935;13240.53658121961;13683.31583420835;13786.241756703377;1692.6106995391128;1797.6224992751538;1844.0318832612986;1851.574137948266;1855.1466325490292;64651;64731.67634161846;65276.347236527974;66463.33536664669;68295;8046.678132874619;8573.914260857437;8777.341699372573;9084.90915090854;9140.954295228481;11763;11794.587189448328;11836.940815295868;11882.40587970598;11883;6631.27056023837;6885.277045910208;6932.6533673316135;6942.51402401827;6982.2319544850025;6654.70053847578;6715.46276297895;6768.187817461936;6859.691313890887;6869.381755641916;3719.776813391205;3754.3992961126087;3763.6236376362144;3766.9058273542832;3838.865639702597;1775.7691500104904;1794.2553840156188;1823.1431227323137;1836.2379612460945;1865.7947625761155;733.1495465260273;802.0255389701541;815.8001289684065;878.3236907581127;893.8748575199347;15962.840371596136;17022.82977170237;17164.141792910305;17445.82554174442;17591.12044397775;826.8553003224412;836.3142223376773;836.3142223376894;854.5171977832505;854.5470900422786;9304.95347523258;9576.664816731382;9688.273379496566;9719.222462203006;9854.16239619626;384493;387809;391296;395442;395621;76869.2313076862;76963;77715;77760.61119694478;77775;885.7387070814125;896.9551522423852;914.0767824497173;918.7289749523907;934.4626839567276;15058.849411505746;15103.697926041637;15423.228838557803;15472.303746331218;15645.921770391074;25676.743232568184;26720.732792671824;33104.66895331112;37027.444588330654;37061.073473163386;39684;49002.26496602483;50830.237546436096;51130.48869511406;51470.48529514657;7789.1821358756515;7825.3739700824935;7916.722914697958;7935.444518883623;8084.83830323402;8610.956945215234;12123.818142728043;12286.324252166216;12311.56909508163;12550.686232844251;9373.85939210926;10387.065164135263;10434.947825260826;10545.420001899969;10568.577256909637;10633.096186823897;10728.839067414152;10751.731206719895;10752.301100428447;10824.512896919816;9353.39202951806;10629.521671524646;10661.840072398765;10879.510422031186;10968;7589.772306830804;7641.617919103911;7719.614019299125;7749.07011158642;7786.532808031537;9858.457784821903;10270.845937311096;10346.172306215636;10465.68602941913;10497.47512624381 22:20:18 INFO - PROCESS | 14312 | |3;mootools.html;1152.2683096233889;1318.2024874950653;1373.2378529915895;1380.7445622559828;1663.4178037686804;6621.536492445524;7623.771286861396;7850.097238817538;7858.1356050834365;8038.196180381973;7748.612569371537;7919.445638805286;7943.324817390514;7951.880721789178;7959.084705258897;1855.944321670352;1915.5977244778544;1918.8273055425009;2222.2333295013195;2256.4471704432353;1951.7853036166011;2165.2421652421604;2257.9096836126537;2259.830512711545;2266.4900397410534;2190.2443656938412;2223.3552269841725;2229.0972156276653;2229.6544035674497;2229.9219527316545;1791.4894255137353;2073.8963051847422;2078.023329035351;2078.272604588397;2081.354780018199;2094.225136699431;2159.762426133124;2163.8268938484885;2175.195177784214;2176.7823217678106;7817.921820781777;7888.80277993049;7900.841983160362;7918.841623167505;7948.443608947376;4416.955830441687;4549.545045495424;4551.772411379451;4553.203189441868;4562.771861406916;2883.3800732842537;2975.9553606695918;2992.9551056734167;3028.787984841062;3031.257341951214;889.7820034091689;892.6875593542273;914.1726737302752;915.1443400420628;920.8066306075732;10633.893661063368;10649.307794993378;10658.04077633009;10676.039156475954;10682.412467314294;4098.1189044355015;4187.309093999456;4215.051613387025;4282.957170428318;4295.892602684958;9015.459072455673;9017.504037278013;9024.774380640536;9040.09599040104;9055.320850936207;4424.513303536608;4428.977855110768;4429.512753597101;4430.579094986036;4431.40176076232;3485.3029394121277;3811.923761524754;3975.741576797499;4101.794910254535;4111.465509483748;3658.6158453362546;3734.066483379155;3776.9244615107546;3787.659110680052;3788.886333410002;1148.1618418554501;1168.7370341673056;1168.7662467506532;1176.0709039858586;1180.2564384437749;751.3650964934621;782.7025730222592;799.86402311606;838.5555655502593;871.6382701179067;5159.1487404578575;5285.788568457218;5300.045991721528;5398.433164517669;5416.51251387377;390.81631633132383;403.0166393998634;403.2801449412843;403.7456402466427;415.74016239850096;3407.778494397906;3446.3107378524396;3535.275268569937;3557.2707594942976;3561.394562924257;9394.483303418241;9739.026097390346;9780.41317520951;9794.853077203848;9815.36200146988;9599.856002159973;9674;9691.370060946016;9697.757556061155;9732.659356922473;7142.4286057115305;7216.458765592602;7223.2415596361625;7233.348998590257;7289.817754556178;5137.383513978347;5165.535101840853;5262.578993680573;5282.31329927107;5297.920531192112;8979;9101.362904596615;9123.315751318545;9150.63397464094;9180;9039.502827344559;9160.6793762218;9177.357584969255;9186.632534698805;9206.631734730536;6019.789307374221;6241.43827055549;6417.807465776034;6766.7969960901255;6845.726170953305;7728.652210650646;7768.88346674789;7770.417218708732;7852.882206766789;7869.921300787148;6668.799936001928;6850.417714494124;6892.414144797548;6902.275261097713;6909.343612356818;6710.899336510054;6783.287754785873;6923.584584924719;6940.340667636567;7025;5812.360640329645;5816.941830581809;5952.910706339495;5988.910166347596;5989.101634754647;4520.932186017147;4577.656675749266;4649.86050418488;4696.1546921554445;4703.153432382204;4429.114177164515;4511.120331535319;4572.741347450458;4577.908441831211;4654.16225079489 22:20:18 INFO - PROCESS | 14312 | |4;prototype.html;274.8114405197521;292.41662882549315;293.696024614524;294.3524246657354;302.47369576936137;4637;4813.3983252093485;4895.779689913954;4952.306677065209;4961.305417241589;4650.139724151034;4685.742284174369;4745.810167593292;4760.476347601761;4763.190257656198;3761.849526018956;3766;3781.5273090863643;3794.924101517968;3844.3272427325105;3653.3058718843336;3680;3803.50554427924;3822.7706337619693;3826.9808650956707;3765.3222419964395;3768.773873567581;3777.9811100944457;3784.205316883444;3798.791066491342;3732.869349572752;3741.8877433677035;3771.2268984858038;3782.9621703782886;3790.3366910790637;3327.7337812975147;3332.5334453176574;3332.866685332584;3336.916577085568;3365.7307415406954;4606.746628935407;4649.604783593399;4693.835715749932;4710.152172608929;4719.268513380432;4229.942514371407;4248.299030659938;4253.255680255974;4281.37920001598;4291.184674911757;4793.640476964241;4997.325361076245;5025.497450254982;5025.693319736894;5032.597392208614;3516.578010638714;3547.5565554305713;3547.9822600887087;3549.804760738158;3553.4492153716214;5260.92108618371;5290.735463226862;5299.125644268691;5303.867403314909;5330.440303768126;3641.435577485494;3646.5624125104628;3648.416253399443;3687.9262414751556;3699.1676872703965;3556.342076715816;3675.8713445029293;3677.7057835373107;3678.834452449646;3714.257148570243;4186.592709192227;4204.222218889515;4212.49450065994;4247.51153617338;4250.489941207075;4810.831620893252;5016;5056.646034777604;5074.391073071255;5108.744562771846;1235.5675513570359;1275.5854347337151;1279.680079980005;1286.3246795432474;1287.8538101090037;786.7639708087551;792.5205250823169;808.8907997420273;827.6317038917745;827.9710210142737;4019.2869812876193;4020.5175378954714;4022.8390864365097;4078.9184216315507;4105.891409319527;4703.882402939954;4725.243960966229;4728.196206644879;4730;4744.169770290255;4048.473698419187;4057.3102572562734;4062.532808726975;4069.694772892045;4073.551909289976;2358.433975845819;2510.8450112947885;2524.8106392020304;2528.8240967949623;2531.810049276837;4403.7009082320155;4435.403254828324;4438.178936896685;4453.732776033448;4461.910761784811;4779.9848127573005;4793.448753393334;4794.866284503432;4805.495422980537;4821.349117869113;4427.380166776654;4436.689431739747;4576.290674945389;4993.600511958962;5018.698878067327;5277.815276465459;5285.365756109291;5288.233206185005;5288.524032837063;5289.523942845162;5188.818391356284;5202.375714914235;5202.869928251673;5227.764750586157;5228.137357335997;4753.287006948985;4981.8329026873425;5020.974895125501;5021.297018417352;5025.522575355335;3626.437902125175;3627.945580816237;3634.63653634645;3657.9817100914324;3669.724770642266;5070.2141168119;5326.201069839555;5330.973345133405;5345.78616855337;5358.758855851418;4962.082014827269;5071.518205770446;5097.209932460474;5108.540231379194;5110.310108135503;5044.855936227163;5079.136546786981;5082.593392528663;5082.644214905069;5094.719790411415;4862.085550096043;5031.169856973558;5035.84892453227;5047.87380315495;5053.418856831511;5114.590832733446;5134.332536770196;5150.047241260229;5155.9742201288755;5164.870878228075;5021.72380519075;5039.344885165052;5052.924206136838;5063.3417655704525;5063.924041139459 22:20:18 INFO - PROCESS | 14312 | |5;yui.html;679.2392520377178;696.9895451568224;718.4109030594911;719.780466957578;742.2021327073396;13222.074454788157;13686.11040282381;13695.041347105707;13724.107932984374;13749.175049497024;12503.49986000559;12668.176568523062;12840.935795321033;12904.741905161914;13329.600111996631;770.437580566187;770.8728059870115;775.0273406874393;775.9922400776005;777.8522080804629;622.6762083716465;768.3392282636928;784.309807369514;799.400449662753;802.4783890471182;840.0423517190408;842.010637500937;842.9452085614415;843.2537204573965;843.3927572148043;654.9803505894831;834.0866750907746;834.0950069174952;834.1741675740973;837.4138103327695;588.1589327262013;594.485769809112;594.7620951619343;609.4910749524153;612.4029071655145;13184.274864882427;13188.274644894527;13196;13202.603921882359;13206.735865282737;11739.47172377245;12390.520607591881;12688.74622507554;13086.14940028895;13117.86882131176;10137.087662110445;11537;11806.114040051998;12172.330521821295;12276.202046867014;3404.097914052778;3427.0918206675;3466.2374277658873;3483.3555792178527;3525.964740352589;60480.09279860806;61058;61984.07023894645;62046;62315.6884215576;10418.791624167474;10451.268411211142;10494.370337779757;10535.894641053492;10577.259591828499;19013.33453329127;19031.238750450102;19039.52401189981;19040.80959190418;19644.214231430582;6694.297098804653;6737.831554211086;6790.388865002174;6826.726930922708;6849.520533562702;3579.874704385386;3635;3670.137517683342;3683.9263214736093;3769.3403654360377;2812.7046660100805;2924.6636636787043;2938.30949726814;2939.9265018374285;2940.3678209185127;505.6182582150453;517.3584754903892;518.1528201390753;523.0976565424688;523.3405908555247;475.4959742672778;505.858359659296;532.2814200828927;537.3256563013393;541.5478075806745;7411.407087432993;7548.396128309722;7551;7614.961925190339;7663.118741344817;474.3027749208658;512.4670342843475;520.3885434614313;522.4618642797924;522.6506851701549;3645.9453108203397;3694.9815250923575;3761.586225515191;3777.9622203777612;3855.9421608675893;88744.5562772182;91528;93211.06788932024;93485;93693;78711;80761.59619201985;80922;81445.59277203576;83006.58496707478;42318;43086.78456607759;44363.55636443659;45442.77278613586;56577.15134272989;6992.510524263253;7104.715811367694;7159.61447463444;7210.423166146591;7234.312740289664;42775.3583696251;44293.778531107135;45378.54621453743;45395.319070213314;45731;58683.413165867794;62235.688821557414;62517;62876.68561657162;63067.36932630615;9490.28822838276;9590.952045239728;9594.856077158709;9694.36986595869;9745.171660408945;10480.213983951387;10535.57857685684;10567.418791966516;10676;10780.622678206226;9373.531323433937;9398.577064031999;9498.715038548855;9523.047695230698;9708.466034368177;9456.810863782823;9710.854337185088;9715;9800.852987205055;9813.313068085452;9262.259019278306;9334.953325233331;9379.343445958717;9385.483798391155;9511.904880951379;8473.703420380258;8485.40602157843;8525.658973640984;8795.780105497157;8830.779230519289;8383.455075420079;8597.226249637313;8676.262517685815;8734.650613975624;8828.867566986371 22:20:18 INFO - PROCESS | 14312 | __end_tp_report 22:20:18 INFO - PROCESS | 14312 | __start_cc_report 22:20:18 INFO - PROCESS | 14312 | _x_x_mozilla_cycle_collect,1051 22:20:18 INFO - PROCESS | 14312 | __end_cc_report 22:20:18 INFO - PROCESS | 14312 | __startTimestamp1466227218227__endTimestamp 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | ------- Summary: start ------- 22:20:18 INFO - PROCESS | 14312 | Number of tests: 6 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | [#0] dojo.html Cycles:165 Average:4800.98 Median:4905.51 stddev:513.65 (10.5%) stddev-sans-first:484.91 22:20:18 INFO - PROCESS | 14312 | Values: 2584.8 2645.6 2668.0 2672.1 2680.1 4770.7 4779.0 4816.0 4861.7 4899.0 4782.5 4834.9 4844.1 4852.3 4908.1 4414.4 4429.8 4471.8 4536.8 4608.7 4517.7 4547.5 4557.8 4567.8 4580.1 4365.6 4374.4 4401.2 4408.3 4457.0 4398.5 4400.9 4405.7 4406.0 4462.7 4433.9 4435.3 4479.4 4487.8 4488.6 4663.3 4756.3 4810.5 4856.4 4859.1 5169.2 5300.0 5300.9 5303.2 5306.6 5299.9 5314.4 5318.7 5333.8 5342.4 4768.4 4773.6 4828.8 4905.5 4948.4 5306.6 5309.0 5341.5 5353.2 5367.2 5082.2 5124.9 5134.7 5136.6 5142.8 5041.6 5147.2 5197.1 5217.7 5224.7 5195.4 5198.1 5200.4 5217.9 5231.3 5175.0 5184.9 5185.5 5185.7 5236.8 4337.0 4396.6 4410.7 4479.3 4481.9 3880.8 3881.5 3887.6 3918.9 3970.7 4457.7 4473.6 4474.8 4476.0 4516.2 5108.8 5155.8 5169.7 5278.6 5335.6 4435.3 4441.6 4462.8 4509.7 4512.4 4732.9 4752.3 4752.8 4752.9 4800.0 4701.3 4740.4 4767.0 4922.1 4928.4 5076.2 5080.5 5092.7 5094.1 5144.6 4773.6 4774.4 4777.3 4779.8 4782.1 5041.6 5059.7 5105.3 5162.7 5173.5 5229.8 5245.1 5245.5 5249.1 5249.5 5061.2 5061.4 5063.6 5070.3 5087.3 4938.6 5045.1 5068.4 5084.5 5101.1 5006.2 5042.6 5099.4 5106.3 5108.3 4982.9 5152.7 5153.9 5190.8 5202.5 4847.8 4881.1 4912.2 4930.4 4987.3 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | [#1] ext.html Cycles:180 Average:40680.33 Median:33211.00 stddev:66107.19 (199.1%) stddev-sans-first:66223.87 22:20:18 INFO - PROCESS | 14312 | Values: 520.9 579.3 582.8 584.4 601.6 27036.3 30115.5 30149.1 30171.0 30186.2 29456.3 30036.7 41527.8 42043.6 42408.6 7749.1 7856.8 7879.6 7880.5 7915.7 7829.1 7917.4 7919.2 7924.4 7925.6 7550.8 10149.5 10368.5 10369.8 10735.2 10253.6 10309.8 10310.1 10317.8 10319.9 7533.8 9756.6 10102.2 10182.6 10192.0 37371.4 37777.2 40349.6 40358.0 40400.0 2775.0 2976.6 2989.8 3076.9 3370.7 29371.0 29826.0 29933.6 30117.0 30668.8 3519.1 3535.2 3537.7 3545.4 3596.1 264182.7 264526.0 264944.0 265451.0 265757.0 55399.2 55884.7 55915.4 55976.0 56002.2 57485.4 57492.4 57721.0 58092.4 58171.4 14422.2 14871.3 14884.9 14886.9 14887.4 11029.1 11071.2 11074.3 11075.4 11075.5 16088.9 17485.6 17833.7 18313.9 18352.7 6614.4 6624.6 6633.8 6728.1 6741.2 779.6 854.9 863.5 896.4 939.4 26757.3 27157.0 27497.7 27694.2 27810.6 928.7 928.9 954.9 1040.8 1048.1 14640.0 14769.9 14892.5 14899.3 14978.6 117710.4 118505.0 118702.0 118750.4 118790.0 48430.0 48872.5 48958.8 48967.5 49047.3 1124.6 1172.4 1177.5 1182.0 1186.6 26675.5 27542.2 27745.0 27820.0 27940.2 41715.6 42093.8 42462.0 42473.8 42481.0 321020.0 324205.8 324417.0 324958.0 326401.0 32325.4 33664.8 37739.1 38304.7 38787.2 41072.8 41133.4 41616.2 42220.2 42551.6 33623.3 35417.5 35524.6 35941.9 36023.8 35223.8 35263.3 35436.3 35512.6 35693.6 34142.3 34565.7 34976.7 35599.3 35603.8 30514.2 32211.0 32357.2 32777.3 32798.7 24582.0 24886.9 25081.4 25136.9 25248.7 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | [#2] jquery.html Cycles:180 Average:24125.57 Median:395531.50 stddev:64436.47 (16.3%) stddev-sans-first:64592.54 22:20:18 INFO - PROCESS | 14312 | Values: 366.6 368.3 374.5 381.8 383.8 15737.3 16632.0 17672.1 17682.0 17709.0 16346.9 16590.8 17396.9 17411.2 17428.6 5132.8 5300.9 5310.7 5340.4 5345.9 3771.9 5227.1 5489.0 6113.2 6134.7 6008.4 6157.3 6191.1 6200.1 6211.9 5875.7 6138.3 6139.4 6143.6 6161.7 5222.3 5275.7 5277.8 5278.0 5294.3 20508.2 20725.3 21037.5 21058.2 21065.3 1901.1 1905.5 1944.7 1975.7 2000.0 12686.4 13219.1 13240.5 13683.3 13786.2 1692.6 1797.6 1844.0 1851.6 1855.1 64651.0 64731.7 65276.3 66463.3 68295.0 8046.7 8573.9 8777.3 9084.9 9141.0 11763.0 11794.6 11836.9 11882.4 11883.0 6631.3 6885.3 6932.7 6942.5 6982.2 6654.7 6715.5 6768.2 6859.7 6869.4 3719.8 3754.4 3763.6 3766.9 3838.9 1775.8 1794.3 1823.1 1836.2 1865.8 733.1 802.0 815.8 878.3 893.9 15962.8 17022.8 17164.1 17445.8 17591.1 826.9 836.3 836.3 854.5 854.5 9305.0 9576.7 9688.3 9719.2 9854.2 384493.0 387809.0 391296.0 395442.0 395621.0 76869.2 76963.0 77715.0 77760.6 77775.0 885.7 897.0 914.1 918.7 934.5 15058.8 15103.7 15423.2 15472.3 15645.9 25676.7 26720.7 33104.7 37027.4 37061.1 39684.0 49002.3 50830.2 51130.5 51470.5 7789.2 7825.4 7916.7 7935.4 8084.8 8611.0 12123.8 12286.3 12311.6 12550.7 9373.9 10387.1 10434.9 10545.4 10568.6 10633.1 10728.8 10751.7 10752.3 10824.5 9353.4 10629.5 10661.8 10879.5 10968.0 7589.8 7641.6 7719.6 7749.1 7786.5 9858.5 10270.8 10346.2 10465.7 10497.5 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | [#3] mootools.html Cycles:180 Average:5115.00 Median:5148.27 stddev:2934.30 (57.0%) stddev-sans-first:2927.41 22:20:18 INFO - PROCESS | 14312 | Values: 1152.3 1318.2 1373.2 1380.7 1663.4 6621.5 7623.8 7850.1 7858.1 8038.2 7748.6 7919.4 7943.3 7951.9 7959.1 1855.9 1915.6 1918.8 2222.2 2256.4 1951.8 2165.2 2257.9 2259.8 2266.5 2190.2 2223.4 2229.1 2229.7 2229.9 1791.5 2073.9 2078.0 2078.3 2081.4 2094.2 2159.8 2163.8 2175.2 2176.8 7817.9 7888.8 7900.8 7918.8 7948.4 4417.0 4549.5 4551.8 4553.2 4562.8 2883.4 2976.0 2993.0 3028.8 3031.3 889.8 892.7 914.2 915.1 920.8 10633.9 10649.3 10658.0 10676.0 10682.4 4098.1 4187.3 4215.1 4283.0 4295.9 9015.5 9017.5 9024.8 9040.1 9055.3 4424.5 4429.0 4429.5 4430.6 4431.4 3485.3 3811.9 3975.7 4101.8 4111.5 3658.6 3734.1 3776.9 3787.7 3788.9 1148.2 1168.7 1168.8 1176.1 1180.3 751.4 782.7 799.9 838.6 871.6 5159.1 5285.8 5300.0 5398.4 5416.5 390.8 403.0 403.3 403.7 415.7 3407.8 3446.3 3535.3 3557.3 3561.4 9394.5 9739.0 9780.4 9794.9 9815.4 9599.9 9674.0 9691.4 9697.8 9732.7 7142.4 7216.5 7223.2 7233.3 7289.8 5137.4 5165.5 5262.6 5282.3 5297.9 8979.0 9101.4 9123.3 9150.6 9180.0 9039.5 9160.7 9177.4 9186.6 9206.6 6019.8 6241.4 6417.8 6766.8 6845.7 7728.7 7768.9 7770.4 7852.9 7869.9 6668.8 6850.4 6892.4 6902.3 6909.3 6710.9 6783.3 6923.6 6940.3 7025.0 5812.4 5816.9 5952.9 5988.9 5989.1 4520.9 4577.7 4649.9 4696.2 4703.2 4429.1 4511.1 4572.7 4577.9 4654.2 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | [#4] prototype.html Cycles:180 Average:4129.85 Median:4649.87 stddev:1218.46 (26.2%) stddev-sans-first:1187.02 22:20:18 INFO - PROCESS | 14312 | Values: 274.8 292.4 293.7 294.4 302.5 4637.0 4813.4 4895.8 4952.3 4961.3 4650.1 4685.7 4745.8 4760.5 4763.2 3761.8 3766.0 3781.5 3794.9 3844.3 3653.3 3680.0 3803.5 3822.8 3827.0 3765.3 3768.8 3778.0 3784.2 3798.8 3732.9 3741.9 3771.2 3783.0 3790.3 3327.7 3332.5 3332.9 3336.9 3365.7 4606.7 4649.6 4693.8 4710.2 4719.3 4229.9 4248.3 4253.3 4281.4 4291.2 4793.6 4997.3 5025.5 5025.7 5032.6 3516.6 3547.6 3548.0 3549.8 3553.4 5260.9 5290.7 5299.1 5303.9 5330.4 3641.4 3646.6 3648.4 3687.9 3699.2 3556.3 3675.9 3677.7 3678.8 3714.3 4186.6 4204.2 4212.5 4247.5 4250.5 4810.8 5016.0 5056.6 5074.4 5108.7 1235.6 1275.6 1279.7 1286.3 1287.9 786.8 792.5 808.9 827.6 828.0 4019.3 4020.5 4022.8 4078.9 4105.9 4703.9 4725.2 4728.2 4730.0 4744.2 4048.5 4057.3 4062.5 4069.7 4073.6 2358.4 2510.8 2524.8 2528.8 2531.8 4403.7 4435.4 4438.2 4453.7 4461.9 4780.0 4793.4 4794.9 4805.5 4821.3 4427.4 4436.7 4576.3 4993.6 5018.7 5277.8 5285.4 5288.2 5288.5 5289.5 5188.8 5202.4 5202.9 5227.8 5228.1 4753.3 4981.8 5021.0 5021.3 5025.5 3626.4 3627.9 3634.6 3658.0 3669.7 5070.2 5326.2 5331.0 5345.8 5358.8 4962.1 5071.5 5097.2 5108.5 5110.3 5044.9 5079.1 5082.6 5082.6 5094.7 4862.1 5031.2 5035.8 5047.9 5053.4 5114.6 5134.3 5150.0 5156.0 5164.9 5021.7 5039.3 5052.9 5063.3 5063.9 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - PROCESS | 14312 | [#5] yui.html Cycles:180 Average:16347.02 Median:30833.75 stddev:23253.84 (75.4%) stddev-sans-first:23289.31 22:20:18 INFO - PROCESS | 14312 | Values: 679.2 697.0 718.4 719.8 742.2 13222.1 13686.1 13695.0 13724.1 13749.2 12503.5 12668.2 12840.9 12904.7 13329.6 770.4 770.9 775.0 776.0 777.9 622.7 768.3 784.3 799.4 802.5 840.0 842.0 842.9 843.3 843.4 655.0 834.1 834.1 834.2 837.4 588.2 594.5 594.8 609.5 612.4 13184.3 13188.3 13196.0 13202.6 13206.7 11739.5 12390.5 12688.7 13086.1 13117.9 10137.1 11537.0 11806.1 12172.3 12276.2 3404.1 3427.1 3466.2 3483.4 3526.0 60480.1 61058.0 61984.1 62046.0 62315.7 10418.8 10451.3 10494.4 10535.9 10577.3 19013.3 19031.2 19039.5 19040.8 19644.2 6694.3 6737.8 6790.4 6826.7 6849.5 3579.9 3635.0 3670.1 3683.9 3769.3 2812.7 2924.7 2938.3 2939.9 2940.4 505.6 517.4 518.2 523.1 523.3 475.5 505.9 532.3 537.3 541.5 7411.4 7548.4 7551.0 7615.0 7663.1 474.3 512.5 520.4 522.5 522.7 3645.9 3695.0 3761.6 3778.0 3855.9 88744.6 91528.0 93211.1 93485.0 93693.0 78711.0 80761.6 80922.0 81445.6 83006.6 42318.0 43086.8 44363.6 45442.8 56577.2 6992.5 7104.7 7159.6 7210.4 7234.3 42775.4 44293.8 45378.5 45395.3 45731.0 58683.4 62235.7 62517.0 62876.7 63067.4 9490.3 9591.0 9594.9 9694.4 9745.2 10480.2 10535.6 10567.4 10676.0 10780.6 9373.5 9398.6 9498.7 9523.0 9708.5 9456.8 9710.9 9715.0 9800.9 9813.3 9262.3 9335.0 9379.3 9385.5 9511.9 8473.7 8485.4 8525.7 8795.8 8830.8 8383.5 8597.2 8676.3 8734.7 8828.9 22:20:18 INFO - PROCESS | 14312 | -------- Summary: end -------- 22:20:18 INFO - PROCESS | 14312 | 22:20:18 INFO - TEST-INFO | 14312: exit 0 22:20:18 INFO - TEST-OK | dromaeo_css | took 1119501ms 22:20:18 INFO - TEST-START | kraken 22:20:18 INFO - operating with platform_type : linux_ 22:20:18 INFO - Initialising browser for kraken test... 22:20:19 INFO - TEST-INFO | started process 14506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0ec2mu/profile http://localhost:41103/getInfo.html) 22:20:22 INFO - PROCESS | 14506 | __metrics Screen width/height:1600/1200 22:20:22 INFO - PROCESS | 14506 | colorDepth:24 22:20:22 INFO - PROCESS | 14506 | Browser inner width/height: 1024/697 22:20:22 INFO - PROCESS | 14506 | __metrics 22:20:23 INFO - PROCESS | 14506 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:20:24 INFO - TEST-INFO | 14506: exit 0 22:20:24 INFO - Browser initialized. 22:20:24 INFO - Running cycle 1/1 for kraken test... 22:20:24 INFO - TEST-INFO | started process 14586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0ec2mu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:36 INFO - PROCESS | 14586 | RSS: Main: 175681536 22:20:36 INFO - PROCESS | 14586 | 22:20:39 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41103/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:40 INFO - PROCESS | 14586 | RSS: Main: 233181184 22:20:40 INFO - PROCESS | 14586 | 22:20:43 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41103/tests/kraken/driver.html?testName=audio-dft) 22:20:43 INFO - PROCESS | 14586 | RSS: Main: 220459008 22:20:43 INFO - PROCESS | 14586 | 22:20:47 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41103/tests/kraken/driver.html?testName=audio-fft) 22:20:47 INFO - PROCESS | 14586 | RSS: Main: 288968704 22:20:47 INFO - PROCESS | 14586 | 22:20:50 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41103/tests/kraken/driver.html?testName=audio-oscillator) 22:20:50 INFO - PROCESS | 14586 | RSS: Main: 198795264 22:20:50 INFO - PROCESS | 14586 | 22:20:52 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41103/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:53 INFO - PROCESS | 14586 | RSS: Main: 202817536 22:20:53 INFO - PROCESS | 14586 | 22:20:56 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41103/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:57 INFO - PROCESS | 14586 | RSS: Main: 239067136 22:20:57 INFO - PROCESS | 14586 | 22:21:00 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41103/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:01 INFO - PROCESS | 14586 | RSS: Main: 234807296 22:21:01 INFO - PROCESS | 14586 | 22:21:04 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41103/tests/kraken/driver.html?testName=json-parse-financial) 22:21:04 INFO - PROCESS | 14586 | RSS: Main: 237400064 22:21:04 INFO - PROCESS | 14586 | 22:21:06 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41103/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:07 INFO - PROCESS | 14586 | RSS: Main: 226557952 22:21:07 INFO - PROCESS | 14586 | 22:21:09 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:09 INFO - PROCESS | 14586 | RSS: Main: 231112704 22:21:09 INFO - PROCESS | 14586 | 22:21:11 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:12 INFO - PROCESS | 14586 | RSS: Main: 237449216 22:21:12 INFO - PROCESS | 14586 | 22:21:15 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:15 INFO - PROCESS | 14586 | RSS: Main: 240431104 22:21:15 INFO - PROCESS | 14586 | 22:21:18 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:19 INFO - PROCESS | 14586 | RSS: Main: 240893952 22:21:19 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | Cycle 1(1): loaded http://localhost:41103/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41103/tests/kraken/driver.html?testName=ai-astar) 22:21:21 INFO - PROCESS | 14586 | RSS: Main: 284160000 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | __start_tp_report 22:21:21 INFO - PROCESS | 14586 | _x_x_mozilla_page_load 22:21:21 INFO - PROCESS | 14586 | _x_x_mozilla_page_load_details 22:21:21 INFO - PROCESS | 14586 | |i|pagename|runs| 22:21:21 INFO - PROCESS | 14586 | |0;ai-astar;115;108;114;114;115;113;113;114;113;113 22:21:21 INFO - PROCESS | 14586 | |1;audio-beat-detection;161;134;134;188;133;132;159;129;164;133 22:21:21 INFO - PROCESS | 14586 | |2;audio-dft;163;160;160;162;160;162;162;162;162;160 22:21:21 INFO - PROCESS | 14586 | |3;audio-fft;75;77;76;103;78;76;74;75;103;77 22:21:21 INFO - PROCESS | 14586 | |4;audio-oscillator;87;86;122;85;86;86;124;86;86;126 22:21:21 INFO - PROCESS | 14586 | |5;imaging-gaussian-blur;117;118;118;117;118;118;118;120;117;118 22:21:21 INFO - PROCESS | 14586 | |6;imaging-darkroom;146;146;147;146;146;146;146;146;145;146 22:21:21 INFO - PROCESS | 14586 | |7;imaging-desaturate;94;95;94;94;94;95;95;95;95;95 22:21:21 INFO - PROCESS | 14586 | |8;json-parse-financial;64;63;64;64;64;65;63;62;63;63 22:21:21 INFO - PROCESS | 14586 | |9;json-stringify-tinderbox;64;65;65;65;66;64;66;64;66;64 22:21:21 INFO - PROCESS | 14586 | |10;stanford-crypto-aes;85;85;86;84;86;86;86;86;122;85 22:21:21 INFO - PROCESS | 14586 | |11;stanford-crypto-ccm;144;139;132;140;134;134;134;139;136;134 22:21:21 INFO - PROCESS | 14586 | |12;stanford-crypto-pbkdf2;173;173;173;172;174;175;175;174;172;170 22:21:21 INFO - PROCESS | 14586 | |13;stanford-crypto-sha256-iterative;69;69;69;67;67;71;69;67;67;65 22:21:21 INFO - PROCESS | 14586 | __end_tp_report 22:21:21 INFO - PROCESS | 14586 | __start_cc_report 22:21:21 INFO - PROCESS | 14586 | _x_x_mozilla_cycle_collect,1884 22:21:21 INFO - PROCESS | 14586 | __end_cc_report 22:21:21 INFO - PROCESS | 14586 | __startTimestamp1466227281550__endTimestamp 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | ------- Summary: start ------- 22:21:21 INFO - PROCESS | 14586 | Number of tests: 14 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:1.99 (1.7%) stddev-sans-first:2.00 22:21:21 INFO - PROCESS | 14586 | Values: 115.0 108.0 114.0 114.0 115.0 113.0 113.0 114.0 113.0 113.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#1] audio-beat-detection Cycles:10 Average:146.70 Median:146.50 stddev:19.97 (13.6%) stddev-sans-first:20.50 22:21:21 INFO - PROCESS | 14586 | Values: 161.0 134.0 134.0 188.0 133.0 132.0 159.0 129.0 164.0 133.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.05 22:21:21 INFO - PROCESS | 14586 | Values: 163.0 160.0 160.0 162.0 160.0 162.0 162.0 162.0 162.0 160.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:11.44 (15.1%) stddev-sans-first:11.90 22:21:21 INFO - PROCESS | 14586 | Values: 75.0 77.0 76.0 103.0 78.0 76.0 74.0 75.0 103.0 77.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:86.00 stddev:18.39 (21.4%) stddev-sans-first:19.11 22:21:21 INFO - PROCESS | 14586 | Values: 87.0 86.0 122.0 85.0 86.0 86.0 124.0 86.0 86.0 126.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.88 (0.7%) stddev-sans-first:0.87 22:21:21 INFO - PROCESS | 14586 | Values: 117.0 118.0 118.0 117.0 118.0 118.0 118.0 120.0 117.0 118.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 22:21:21 INFO - PROCESS | 14586 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:21:21 INFO - PROCESS | 14586 | Values: 94.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 22:21:21 INFO - PROCESS | 14586 | Values: 64.0 63.0 64.0 64.0 64.0 65.0 63.0 62.0 63.0 63.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 22:21:21 INFO - PROCESS | 14586 | Values: 64.0 65.0 65.0 65.0 66.0 64.0 66.0 64.0 66.0 64.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:86.00 stddev:11.58 (13.5%) stddev-sans-first:12.19 22:21:21 INFO - PROCESS | 14586 | Values: 85.0 85.0 86.0 84.0 86.0 86.0 86.0 86.0 122.0 85.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:137.50 stddev:3.75 (2.7%) stddev-sans-first:2.86 22:21:21 INFO - PROCESS | 14586 | Values: 144.0 139.0 132.0 140.0 134.0 134.0 134.0 139.0 136.0 134.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:173.50 stddev:1.52 (0.9%) stddev-sans-first:1.62 22:21:21 INFO - PROCESS | 14586 | Values: 173.0 173.0 173.0 172.0 174.0 175.0 175.0 174.0 172.0 170.0 22:21:21 INFO - PROCESS | 14586 | 22:21:21 INFO - PROCESS | 14586 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:69.00 stddev:1.70 (2.5%) stddev-sans-first:1.76 22:21:21 INFO - PROCESS | 14586 | Values: 69.0 69.0 69.0 67.0 67.0 71.0 69.0 67.0 67.0 65.0 22:21:21 INFO - PROCESS | 14586 | -------- Summary: end -------- 22:21:21 INFO - PROCESS | 14586 | 22:21:22 INFO - TEST-INFO | 14586: exit 0 22:21:22 INFO - TEST-OK | kraken | took 63658ms 22:21:22 INFO - SUITE-END | took 1183s 22:21:22 INFO - Completed test suite (00:19:43) 22:21:22 INFO - javascript benchmark 22:21:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2584.7802936750377, 2645.616385624086, 2667.959980600291, 2672.1182009936715, 2680.1423544465765, 4770.666053376265, 4778.952210477894, 4815.951840481594, 4861.708297502152, 4899.020195960808, 4782.473927867932, 4834.9033019339595, 4844.079624871272, 4852.3206751054795, 4908.0920029794515, 4414.381986521881, 4429.77851107445, 4471.843485478009, 4536.818527258905, 4608.700434471765, 4517.661175411858, 4547.5452454754595, 4557.794899229542, 4567.817287308504, 4580.083983203356, 4365.585269399402, 4374.387585737999, 4401.185780630593, 4408.316710909814, 4456.955430445686, 4398.450193725785, 4400.867973960786, 4405.691601587891, 4405.977970110161, 4462.687611867171, 4433.866983990485, 4435.3125265583885, 4479.39528163697, 4487.753173575452, 4488.5960263576435, 4663.300504924255, 4756.286557016475, 4810.518948105197, 4856.4172299323955, 4859.0767754126955, 5169.172932330809, 5300.01949639318, 5300.867478313035, 5303.178007408857, 5306.628536002443, 5299.947000529984, 5314.388845282765, 5318.73406329682, 5333.839984800462, 5342.412334643186, 4768.403949506312, 4773.618110551148, 4828.758562071883, 4905.509449055137, 4948.38145231846, 5306.62853600252, 5309.0, 5341.519263266325, 5353.197020446964, 5367.221752845816, 5082.161443361804, 5124.948750512447, 5134.717590532482, 5136.614753893473, 5142.845714628567, 5041.571466425322, 5147.2279158126585, 5197.142471492161, 5217.713025783618, 5224.738763061831, 5195.376554813447, 5198.116320225571, 5200.427952925175, 5217.895642087213, 5231.346081739783, 5174.974125129425, 5184.948150518447, 5185.5073767992735, 5185.688858668492, 5236.842894713165, 4337.0458499130145, 4396.648268138541, 4410.7353558785935, 4479.3057076153245, 4481.932771008437, 3880.825362858678, 3881.4954055972544, 3887.5723670396233, 3918.8824335269987, 3970.7419017763755, 4457.710248833815, 4473.642108631302, 4474.7762611869275, 4476.015276639134, 4516.209663308908, 5108.846734597968, 5155.819546315861, 5169.68981861082, 5278.630495865329, 5335.626506144533, 4435.290353543418, 4441.622462090694, 4462.799174037176, 4509.706869053567, 4512.435945506812, 4732.905341893143, 4752.334673145762, 4752.7623618818925, 4752.904941901212, 4800.015996720734, 4701.318308845267, 4740.360051393018, 4767.0, 4922.0648076866, 4928.383952005999, 5076.162433198629, 5080.46655101209, 5092.694438333712, 5094.133997220407, 5144.6398752087, 4773.618110551217, 4774.40319960005, 4777.283407488904, 4779.784909679142, 4782.067496838084, 5041.571466425395, 5059.747012649279, 5105.285260063668, 5162.664426812245, 5173.508516690908, 5229.816956406507, 5245.134552798745, 5245.501677340647, 5249.055170069424, 5249.501297376743, 5061.190209566452, 5061.443241243386, 5063.594912407072, 5070.3155074064525, 5087.262346959744, 4938.555530002317, 5045.142325804695, 5068.442471328077, 5084.466131056186, 5101.107306221471, 5006.173981293192, 5042.596592272536, 5099.36257967754, 5106.2595923590125, 5108.259302401206, 4982.925256121233, 5152.690838549699, 5153.896922061613, 5190.766415511386, 5202.453742357149, 4847.80608775645, 4881.145799485147, 4912.238603016538, 4930.408350997904, 4987.251912213197], "value": 4767.209882984759, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.8939994688558, 579.3192998227082, 582.798934367643, 584.3747190506159, 601.6119602856157, 27036.324091897714, 30115.54826677599, 30149.09552713422, 30171.0, 30186.245343866387, 29456.263593410124, 30036.699633003664, 41527.79236103823, 42043.57956420435, 42408.57591424085, 7749.147593764696, 7856.764297071097, 7879.606019699021, 7880.487768295071, 7915.722949696762, 7829.099653539831, 7917.445778795487, 7919.168487308807, 7924.405669574776, 7925.603719814015, 7550.7734767957045, 10149.543270552844, 10368.481575921174, 10369.844452333222, 10735.248532602722, 10253.641122560712, 10309.845352319646, 10310.07209351162, 10317.84523232152, 10319.948400258027, 7533.773986780406, 9756.609735610567, 10102.242331825142, 10182.59269629214, 10192.031756983075, 37371.439428408594, 37777.24445511102, 40349.596504034875, 40358.0, 40400.0, 2774.986125069382, 2976.5683975823385, 2989.7907146499756, 3076.9384612307854, 3370.7303415726683, 29371.0, 29826.0, 29933.550996734848, 30117.0, 30668.8466557668, 3519.0674471265133, 3535.2045789697368, 3537.6992955598803, 3545.4327307630656, 3596.1369271374683, 264182.67908660526, 264526.0, 264944.0, 265451.0, 265757.0, 55399.169012464845, 55884.72057639686, 55915.44084559102, 55976.0, 56002.15996760052, 57485.425145748006, 57492.42507574955, 57721.0, 58092.41907580954, 58171.418285817446, 14422.206778627276, 14871.256437178314, 14884.92557537227, 14886.925565372103, 14887.404503819942, 11029.117670586334, 11071.16966227536, 11074.335539867634, 11075.446227688582, 11075.50160242791, 16088.919555402383, 17485.562860928578, 17833.732494012602, 18313.90843045803, 18352.724709129372, 6614.371634694697, 6624.635645039569, 6633.800985970429, 6728.091707619409, 6741.157355330583, 779.6491578789568, 854.9316054715608, 863.5250612163285, 896.4038914122036, 939.3518472254123, 26757.331066723487, 27157.0, 27497.725022749517, 27694.169174924784, 27810.582841257397, 928.7167413938813, 928.8606708993705, 954.9140577348072, 1040.8074506216374, 1048.1405247696816, 14640.0, 14769.92615036918, 14892.478763243234, 14899.329530171168, 14978.62553436151, 117710.41144794221, 118505.0, 118702.0, 118750.4062479682, 118790.0, 48430.0, 48872.5112748875, 48958.75520622374, 48967.51032489629, 49047.264291035666, 1124.550179928019, 1172.4137931034484, 1177.5172179406402, 1182.0484509969558, 1186.5550418593027, 26675.466490670467, 27542.17373478799, 27745.0, 27820.0, 27940.161795146178, 41715.582844172386, 42093.78953105215, 42462.0, 42473.78763106165, 42481.0, 321020.0, 324205.757942427, 324417.0, 324958.0, 326401.0, 32325.35349292954, 33664.831675841466, 37739.05652358713, 38304.659336923076, 38787.224255514164, 41072.79463602663, 41133.382999255635, 41616.167676645695, 42220.15559688851, 42551.574484254765, 33623.327533448704, 35417.46873796843, 35524.64475355214, 35941.921742347775, 36023.819880900424, 35223.82388088043, 35263.294734105686, 35436.29127417489, 35512.644873551966, 35693.64306357007, 34142.31715365629, 34565.65434345725, 34976.65023349734, 35599.28801424009, 35603.82198088993, 30514.237144071576, 32211.0, 32357.191070223435, 32777.344453111284, 32798.67201328051, 24582.033437325328, 24886.875565622056, 25081.372965676004, 25136.874315628305, 25248.747512525373], "value": 17297.54903438674, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.6297039989611, 368.2689860626656, 374.46077648186593, 381.7881076002817, 383.7678204686163, 15737.291821868015, 16632.0, 17672.116394180302, 17682.0, 17709.0, 16346.918265408685, 16590.75113873293, 17396.913015434937, 17411.21649525771, 17428.564285892826, 5132.8460146195675, 5300.867478313035, 5310.654807437523, 5340.385855626614, 5345.866353341159, 3771.905702357449, 5227.08526007949, 5488.984537860489, 6113.174721412619, 6134.693265336738, 6008.399160083978, 6157.261128664567, 6191.133241346215, 6200.100985357143, 6211.937880621181, 5875.706214689249, 6138.263408390977, 6139.416755408251, 6143.569950103494, 6161.7227224775, 5222.294990176316, 5275.709835959021, 5277.788888444457, 5278.0, 5294.311739473882, 20508.17967281307, 20725.27461538839, 21037.47406314839, 21058.157673693033, 21065.262715804874, 1901.077977181066, 1905.5236190952262, 1944.7277381166648, 1975.683890577515, 1999.9700004499944, 12686.365681715877, 13219.140755850935, 13240.53658121961, 13683.31583420835, 13786.241756703377, 1692.6106995391128, 1797.6224992751538, 1844.0318832612986, 1851.574137948266, 1855.1466325490292, 64651.0, 64731.67634161846, 65276.347236527974, 66463.33536664669, 68295.0, 8046.678132874619, 8573.914260857437, 8777.341699372573, 9084.90915090854, 9140.954295228481, 11763.0, 11794.587189448328, 11836.940815295868, 11882.40587970598, 11883.0, 6631.27056023837, 6885.277045910208, 6932.6533673316135, 6942.51402401827, 6982.2319544850025, 6654.70053847578, 6715.46276297895, 6768.187817461936, 6859.691313890887, 6869.381755641916, 3719.776813391205, 3754.3992961126087, 3763.6236376362144, 3766.9058273542832, 3838.865639702597, 1775.7691500104904, 1794.2553840156188, 1823.1431227323137, 1836.2379612460945, 1865.7947625761155, 733.1495465260273, 802.0255389701541, 815.8001289684065, 878.3236907581127, 893.8748575199347, 15962.840371596136, 17022.82977170237, 17164.141792910305, 17445.82554174442, 17591.12044397775, 826.8553003224412, 836.3142223376773, 836.3142223376894, 854.5171977832505, 854.5470900422786, 9304.95347523258, 9576.664816731382, 9688.273379496566, 9719.222462203006, 9854.16239619626, 384493.0, 387809.0, 391296.0, 395442.0, 395621.0, 76869.2313076862, 76963.0, 77715.0, 77760.61119694478, 77775.0, 885.7387070814125, 896.9551522423852, 914.0767824497173, 918.7289749523907, 934.4626839567276, 15058.849411505746, 15103.697926041637, 15423.228838557803, 15472.303746331218, 15645.921770391074, 25676.743232568184, 26720.732792671824, 33104.66895331112, 37027.444588330654, 37061.073473163386, 39684.0, 49002.26496602483, 50830.237546436096, 51130.48869511406, 51470.48529514657, 7789.1821358756515, 7825.3739700824935, 7916.722914697958, 7935.444518883623, 8084.83830323402, 8610.956945215234, 12123.818142728043, 12286.324252166216, 12311.56909508163, 12550.686232844251, 9373.85939210926, 10387.065164135263, 10434.947825260826, 10545.420001899969, 10568.577256909637, 10633.096186823897, 10728.839067414152, 10751.731206719895, 10752.301100428447, 10824.512896919816, 9353.39202951806, 10629.521671524646, 10661.840072398765, 10879.510422031186, 10968.0, 7589.772306830804, 7641.617919103911, 7719.614019299125, 7749.07011158642, 7786.532808031537, 9858.457784821903, 10270.845937311096, 10346.172306215636, 10465.68602941913, 10497.47512624381], "value": 8206.641802742819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1152.2683096233889, 1318.2024874950653, 1373.2378529915895, 1380.7445622559828, 1663.4178037686804, 6621.536492445524, 7623.771286861396, 7850.097238817538, 7858.1356050834365, 8038.196180381973, 7748.612569371537, 7919.445638805286, 7943.324817390514, 7951.880721789178, 7959.084705258897, 1855.944321670352, 1915.5977244778544, 1918.8273055425009, 2222.2333295013195, 2256.4471704432353, 1951.7853036166011, 2165.2421652421604, 2257.9096836126537, 2259.830512711545, 2266.4900397410534, 2190.2443656938412, 2223.3552269841725, 2229.0972156276653, 2229.6544035674497, 2229.9219527316545, 1791.4894255137353, 2073.8963051847422, 2078.023329035351, 2078.272604588397, 2081.354780018199, 2094.225136699431, 2159.762426133124, 2163.8268938484885, 2175.195177784214, 2176.7823217678106, 7817.921820781777, 7888.80277993049, 7900.841983160362, 7918.841623167505, 7948.443608947376, 4416.955830441687, 4549.545045495424, 4551.772411379451, 4553.203189441868, 4562.771861406916, 2883.3800732842537, 2975.9553606695918, 2992.9551056734167, 3028.787984841062, 3031.257341951214, 889.7820034091689, 892.6875593542273, 914.1726737302752, 915.1443400420628, 920.8066306075732, 10633.893661063368, 10649.307794993378, 10658.04077633009, 10676.039156475954, 10682.412467314294, 4098.1189044355015, 4187.309093999456, 4215.051613387025, 4282.957170428318, 4295.892602684958, 9015.459072455673, 9017.504037278013, 9024.774380640536, 9040.09599040104, 9055.320850936207, 4424.513303536608, 4428.977855110768, 4429.512753597101, 4430.579094986036, 4431.40176076232, 3485.3029394121277, 3811.923761524754, 3975.741576797499, 4101.794910254535, 4111.465509483748, 3658.6158453362546, 3734.066483379155, 3776.9244615107546, 3787.659110680052, 3788.886333410002, 1148.1618418554501, 1168.7370341673056, 1168.7662467506532, 1176.0709039858586, 1180.2564384437749, 751.3650964934621, 782.7025730222592, 799.86402311606, 838.5555655502593, 871.6382701179067, 5159.1487404578575, 5285.788568457218, 5300.045991721528, 5398.433164517669, 5416.51251387377, 390.81631633132383, 403.0166393998634, 403.2801449412843, 403.7456402466427, 415.74016239850096, 3407.778494397906, 3446.3107378524396, 3535.275268569937, 3557.2707594942976, 3561.394562924257, 9394.483303418241, 9739.026097390346, 9780.41317520951, 9794.853077203848, 9815.36200146988, 9599.856002159973, 9674.0, 9691.370060946016, 9697.757556061155, 9732.659356922473, 7142.4286057115305, 7216.458765592602, 7223.2415596361625, 7233.348998590257, 7289.817754556178, 5137.383513978347, 5165.535101840853, 5262.578993680573, 5282.31329927107, 5297.920531192112, 8979.0, 9101.362904596615, 9123.315751318545, 9150.63397464094, 9180.0, 9039.502827344559, 9160.6793762218, 9177.357584969255, 9186.632534698805, 9206.631734730536, 6019.789307374221, 6241.43827055549, 6417.807465776034, 6766.7969960901255, 6845.726170953305, 7728.652210650646, 7768.88346674789, 7770.417218708732, 7852.882206766789, 7869.921300787148, 6668.799936001928, 6850.417714494124, 6892.414144797548, 6902.275261097713, 6909.343612356818, 6710.899336510054, 6783.287754785873, 6923.584584924719, 6940.340667636567, 7025.0, 5812.360640329645, 5816.941830581809, 5952.910706339495, 5988.910166347596, 5989.101634754647, 4520.932186017147, 4577.656675749266, 4649.86050418488, 4696.1546921554445, 4703.153432382204, 4429.114177164515, 4511.120331535319, 4572.741347450458, 4577.908441831211, 4654.16225079489], "value": 4018.81431140008, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.8114405197521, 292.41662882549315, 293.696024614524, 294.3524246657354, 302.47369576936137, 4637.0, 4813.3983252093485, 4895.779689913954, 4952.306677065209, 4961.305417241589, 4650.139724151034, 4685.742284174369, 4745.810167593292, 4760.476347601761, 4763.190257656198, 3761.849526018956, 3766.0, 3781.5273090863643, 3794.924101517968, 3844.3272427325105, 3653.3058718843336, 3680.0, 3803.50554427924, 3822.7706337619693, 3826.9808650956707, 3765.3222419964395, 3768.773873567581, 3777.9811100944457, 3784.205316883444, 3798.791066491342, 3732.869349572752, 3741.8877433677035, 3771.2268984858038, 3782.9621703782886, 3790.3366910790637, 3327.7337812975147, 3332.5334453176574, 3332.866685332584, 3336.916577085568, 3365.7307415406954, 4606.746628935407, 4649.604783593399, 4693.835715749932, 4710.152172608929, 4719.268513380432, 4229.942514371407, 4248.299030659938, 4253.255680255974, 4281.37920001598, 4291.184674911757, 4793.640476964241, 4997.325361076245, 5025.497450254982, 5025.693319736894, 5032.597392208614, 3516.578010638714, 3547.5565554305713, 3547.9822600887087, 3549.804760738158, 3553.4492153716214, 5260.92108618371, 5290.735463226862, 5299.125644268691, 5303.867403314909, 5330.440303768126, 3641.435577485494, 3646.5624125104628, 3648.416253399443, 3687.9262414751556, 3699.1676872703965, 3556.342076715816, 3675.8713445029293, 3677.7057835373107, 3678.834452449646, 3714.257148570243, 4186.592709192227, 4204.222218889515, 4212.49450065994, 4247.51153617338, 4250.489941207075, 4810.831620893252, 5016.0, 5056.646034777604, 5074.391073071255, 5108.744562771846, 1235.5675513570359, 1275.5854347337151, 1279.680079980005, 1286.3246795432474, 1287.8538101090037, 786.7639708087551, 792.5205250823169, 808.8907997420273, 827.6317038917745, 827.9710210142737, 4019.2869812876193, 4020.5175378954714, 4022.8390864365097, 4078.9184216315507, 4105.891409319527, 4703.882402939954, 4725.243960966229, 4728.196206644879, 4730.0, 4744.169770290255, 4048.473698419187, 4057.3102572562734, 4062.532808726975, 4069.694772892045, 4073.551909289976, 2358.433975845819, 2510.8450112947885, 2524.8106392020304, 2528.8240967949623, 2531.810049276837, 4403.7009082320155, 4435.403254828324, 4438.178936896685, 4453.732776033448, 4461.910761784811, 4779.9848127573005, 4793.448753393334, 4794.866284503432, 4805.495422980537, 4821.349117869113, 4427.380166776654, 4436.689431739747, 4576.290674945389, 4993.600511958962, 5018.698878067327, 5277.815276465459, 5285.365756109291, 5288.233206185005, 5288.524032837063, 5289.523942845162, 5188.818391356284, 5202.375714914235, 5202.869928251673, 5227.764750586157, 5228.137357335997, 4753.287006948985, 4981.8329026873425, 5020.974895125501, 5021.297018417352, 5025.522575355335, 3626.437902125175, 3627.945580816237, 3634.63653634645, 3657.9817100914324, 3669.724770642266, 5070.2141168119, 5326.201069839555, 5330.973345133405, 5345.78616855337, 5358.758855851418, 4962.082014827269, 5071.518205770446, 5097.209932460474, 5108.540231379194, 5110.310108135503, 5044.855936227163, 5079.136546786981, 5082.593392528663, 5082.644214905069, 5094.719790411415, 4862.085550096043, 5031.169856973558, 5035.84892453227, 5047.87380315495, 5053.418856831511, 5114.590832733446, 5134.332536770196, 5150.047241260229, 5155.9742201288755, 5164.870878228075, 5021.72380519075, 5039.344885165052, 5052.924206136838, 5063.3417655704525, 5063.924041139459], "value": 3739.8942121646996, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.2392520377178, 696.9895451568224, 718.4109030594911, 719.780466957578, 742.2021327073396, 13222.074454788157, 13686.11040282381, 13695.041347105707, 13724.107932984374, 13749.175049497024, 12503.49986000559, 12668.176568523062, 12840.935795321033, 12904.741905161914, 13329.600111996631, 770.437580566187, 770.8728059870115, 775.0273406874393, 775.9922400776005, 777.8522080804629, 622.6762083716465, 768.3392282636928, 784.309807369514, 799.400449662753, 802.4783890471182, 840.0423517190408, 842.010637500937, 842.9452085614415, 843.2537204573965, 843.3927572148043, 654.9803505894831, 834.0866750907746, 834.0950069174952, 834.1741675740973, 837.4138103327695, 588.1589327262013, 594.485769809112, 594.7620951619343, 609.4910749524153, 612.4029071655145, 13184.274864882427, 13188.274644894527, 13196.0, 13202.603921882359, 13206.735865282737, 11739.47172377245, 12390.520607591881, 12688.74622507554, 13086.14940028895, 13117.86882131176, 10137.087662110445, 11537.0, 11806.114040051998, 12172.330521821295, 12276.202046867014, 3404.097914052778, 3427.0918206675, 3466.2374277658873, 3483.3555792178527, 3525.964740352589, 60480.09279860806, 61058.0, 61984.07023894645, 62046.0, 62315.6884215576, 10418.791624167474, 10451.268411211142, 10494.370337779757, 10535.894641053492, 10577.259591828499, 19013.33453329127, 19031.238750450102, 19039.52401189981, 19040.80959190418, 19644.214231430582, 6694.297098804653, 6737.831554211086, 6790.388865002174, 6826.726930922708, 6849.520533562702, 3579.874704385386, 3635.0, 3670.137517683342, 3683.9263214736093, 3769.3403654360377, 2812.7046660100805, 2924.6636636787043, 2938.30949726814, 2939.9265018374285, 2940.3678209185127, 505.6182582150453, 517.3584754903892, 518.1528201390753, 523.0976565424688, 523.3405908555247, 475.4959742672778, 505.858359659296, 532.2814200828927, 537.3256563013393, 541.5478075806745, 7411.407087432993, 7548.396128309722, 7551.0, 7614.961925190339, 7663.118741344817, 474.3027749208658, 512.4670342843475, 520.3885434614313, 522.4618642797924, 522.6506851701549, 3645.9453108203397, 3694.9815250923575, 3761.586225515191, 3777.9622203777612, 3855.9421608675893, 88744.5562772182, 91528.0, 93211.06788932024, 93485.0, 93693.0, 78711.0, 80761.59619201985, 80922.0, 81445.59277203576, 83006.58496707478, 42318.0, 43086.78456607759, 44363.55636443659, 45442.77278613586, 56577.15134272989, 6992.510524263253, 7104.715811367694, 7159.61447463444, 7210.423166146591, 7234.312740289664, 42775.3583696251, 44293.778531107135, 45378.54621453743, 45395.319070213314, 45731.0, 58683.413165867794, 62235.688821557414, 62517.0, 62876.68561657162, 63067.36932630615, 9490.28822838276, 9590.952045239728, 9594.856077158709, 9694.36986595869, 9745.171660408945, 10480.213983951387, 10535.57857685684, 10567.418791966516, 10676.0, 10780.622678206226, 9373.531323433937, 9398.577064031999, 9498.715038548855, 9523.047695230698, 9708.466034368177, 9456.810863782823, 9710.854337185088, 9715.0, 9800.852987205055, 9813.313068085452, 9262.259019278306, 9334.953325233331, 9379.343445958717, 9385.483798391155, 9511.904880951379, 8473.703420380258, 8485.40602157843, 8525.658973640984, 8795.780105497157, 8830.779230519289, 8383.455075420079, 8597.226249637313, 8676.262517685815, 8734.650613975624, 8828.867566986371], "value": 6185.8220772686855, "unit": "score"}], "name": "dromaeo_css", "value": 6306.715256867796, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 114.0, 114.0, 115.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 134.0, 134.0, 188.0, 133.0, 132.0, 159.0, 129.0, 164.0, 133.0], "value": 146.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 162.0, 160.0, 162.0, 162.0, 162.0, 162.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 103.0, 78.0, 76.0, 74.0, 75.0, 103.0, 77.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 122.0, 85.0, 86.0, 86.0, 124.0, 86.0, 86.0, 126.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 120.0, 117.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 64.0, 65.0, 63.0, 62.0, 63.0, 63.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 65.0, 66.0, 64.0, 66.0, 64.0, 66.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 84.0, 86.0, 86.0, 86.0, 86.0, 122.0, 85.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 139.0, 132.0, 140.0, 134.0, 134.0, 134.0, 139.0, 136.0, 134.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 173.0, 172.0, 174.0, 175.0, 175.0, 174.0, 172.0, 170.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 67.0, 67.0, 71.0, 69.0, 67.0, 67.0, 65.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1553.6999999999998, "alertThreshold": 2.0}]} 22:21:22 INFO - Return code: 0 22:21:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:22 INFO - # TBPL SUCCESS # 22:21:22 INFO - Running post-action listener: _resource_record_post_action 22:21:22 INFO - ##### 22:21:22 INFO - ##### Finished run-tests step (success) 22:21:22 INFO - ##### 22:21:22 INFO - Running post-run listener: _resource_record_post_run 22:21:22 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 534212608; Read time: 12; Write time: 380172 22:21:22 INFO - TinderboxPrint: CPU usage
13.2% 22:21:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:21:22 INFO - TinderboxPrint: I/O write bytes / time
534,212,608 / 380,172 22:21:22 INFO - TinderboxPrint: CPU idle
8,314.6 (86.8%) 22:21:22 INFO - TinderboxPrint: CPU user
1,217.6 (12.7%) 22:21:22 INFO - TinderboxPrint: Swap in / out
0 / 0 22:21:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 245903360; Read time: 0; Write time: 191060 22:21:22 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288309248; Read time: 12; Write time: 189112 22:21:23 INFO - Running post-run listener: _upload_blobber_files 22:21:23 INFO - Blob upload gear active. 22:21:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:21:23 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'] 22:21:23 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 22:21:23 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:24 INFO - (blobuploader) - INFO - Done attempting. 22:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:28 INFO - (blobuploader) - INFO - Done attempting. 22:21:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:29 INFO - (blobuploader) - INFO - Done attempting. 22:21:29 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:29 INFO - Return code: 0 22:21:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1079b7fbcf9a21a2f7d72fcb74aab0329f06ae89478e94fda6bc04e299cc5fc9778f92e6100a20be736644744100f2e72adec7a5cd2188248e1b542bd217d512", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8d1e73b482ae14b58e85039e266188b8c8cb85f79a881d64d4658d6981dad9a8da710d048ed3e955a98ea7cc4a06edd86ab11aa6649ce3a982e598b5464de77f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f264453f0b08af51a4905dc67781d8f5e6cf6368ddf7511690f82c63837263e38754df50972b4baf9f5aed12dd0b47d6d8cd10c68592edc1c8f5258bf146ebc"} 22:21:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:29 INFO - Contents: 22:21:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1079b7fbcf9a21a2f7d72fcb74aab0329f06ae89478e94fda6bc04e299cc5fc9778f92e6100a20be736644744100f2e72adec7a5cd2188248e1b542bd217d512", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8d1e73b482ae14b58e85039e266188b8c8cb85f79a881d64d4658d6981dad9a8da710d048ed3e955a98ea7cc4a06edd86ab11aa6649ce3a982e598b5464de77f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f264453f0b08af51a4905dc67781d8f5e6cf6368ddf7511690f82c63837263e38754df50972b4baf9f5aed12dd0b47d6d8cd10c68592edc1c8f5258bf146ebc"} 22:21:29 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:29 INFO - Copying logs to upload dir... 22:21:29 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.301656 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-06-17 22:21:28.850076) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:28.853992) ========= 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=923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446 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/1079b7fbcf9a21a2f7d72fcb74aab0329f06ae89478e94fda6bc04e299cc5fc9778f92e6100a20be736644744100f2e72adec7a5cd2188248e1b542bd217d512", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8d1e73b482ae14b58e85039e266188b8c8cb85f79a881d64d4658d6981dad9a8da710d048ed3e955a98ea7cc4a06edd86ab11aa6649ce3a982e598b5464de77f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f264453f0b08af51a4905dc67781d8f5e6cf6368ddf7511690f82c63837263e38754df50972b4baf9f5aed12dd0b47d6d8cd10c68592edc1c8f5258bf146ebc"} build_url:https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/el0TtMs7RuC0U8ZBejxYCQ/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/1079b7fbcf9a21a2f7d72fcb74aab0329f06ae89478e94fda6bc04e299cc5fc9778f92e6100a20be736644744100f2e72adec7a5cd2188248e1b542bd217d512", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8d1e73b482ae14b58e85039e266188b8c8cb85f79a881d64d4658d6981dad9a8da710d048ed3e955a98ea7cc4a06edd86ab11aa6649ce3a982e598b5464de77f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f264453f0b08af51a4905dc67781d8f5e6cf6368ddf7511690f82c63837263e38754df50972b4baf9f5aed12dd0b47d6d8cd10c68592edc1c8f5258bf146ebc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:28.879511) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:28.879901) ========= 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=923d835db2ba3686ebe126b700000192-1466205769.746163-1474487446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:28.965964) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 22:21:28.966288) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 22:21:28.966650) ========= ========= Total master_lag: 0.19 =========