builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1467061585.71 results: success (0) buildid: 20160627113032 builduid: 63f12c61ae234384a4254170f9959b10 revision: d095e848a601937d9e7daedfd7e4e6f0199706e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.705699) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.706171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.706453) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.727906) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.728190) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 14:06:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 17.6M=0.001s 2016-06-27 14:06:25 (17.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.185873 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.933247) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.933567) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018136 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.984692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:25.985025) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d095e848a601937d9e7daedfd7e4e6f0199706e2 --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 d095e848a601937d9e7daedfd7e4e6f0199706e2 --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=14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 14:06:26,042 truncating revision to first 12 chars 2016-06-27 14:06:26,042 Setting DEBUG logging. 2016-06-27 14:06:26,042 attempt 1/10 2016-06-27 14:06:26,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d095e848a601?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 14:06:26,324 unpacking tar archive at: fx-team-d095e848a601/testing/mozharness/ program finished with exit code 0 elapsedTime=0.602199 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:26.601052) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:26.601385) ========= script_repo_revision: d095e848a601937d9e7daedfd7e4e6f0199706e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:26.601822) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:26.602096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 14:06:26.611515) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-27 14:06:26.611923) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:06:26 INFO - MultiFileLogger online at 20160627 14:06:26 in /builds/slave/test 14:06:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:06:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:06:26 INFO - {'append_to_log': False, 14:06:26 INFO - 'base_work_dir': '/builds/slave/test', 14:06:26 INFO - 'blob_upload_branch': 'Fx-Team', 14:06:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:06:26 INFO - 'branch': 'Fx-Team', 14:06:26 INFO - 'buildbot_json_path': 'buildprops.json', 14:06:26 INFO - 'config_files': ('talos/linux_config.py',), 14:06:26 INFO - 'default_actions': ('clobber', 14:06:26 INFO - 'read-buildbot-config', 14:06:26 INFO - 'download-and-extract', 14:06:26 INFO - 'populate-webroot', 14:06:26 INFO - 'create-virtualenv', 14:06:26 INFO - 'install', 14:06:26 INFO - 'run-tests'), 14:06:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:06:26 INFO - 'download_minidump_stackwalk': True, 14:06:26 INFO - 'download_symbols': 'ondemand', 14:06:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:06:26 INFO - 'tooltool.py': '/tools/tooltool.py', 14:06:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:06:26 INFO - '/tools/misc-python/virtualenv.py')}, 14:06:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:06:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:06:26 INFO - 'installer_path': 'installer.exe', 14:06:26 INFO - 'log_level': 'info', 14:06:26 INFO - 'log_name': 'talos', 14:06:26 INFO - 'log_to_console': True, 14:06:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:06:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:06:26 INFO - 'opt_config_files': (), 14:06:26 INFO - 'pip_index': False, 14:06:26 INFO - 'sps_profile': False, 14:06:26 INFO - 'sps_profile_interval': 0, 14:06:26 INFO - 'suite': 'dromaeojs', 14:06:26 INFO - 'system_bits': '32', 14:06:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:06:26 INFO - 'title': 'talos-linux64-ix-036', 14:06:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:06:26 INFO - 'use_talos_json': True, 14:06:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:06:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:06:26 INFO - 'work_dir': 'build'} 14:06:26 INFO - ##### 14:06:26 INFO - ##### Running clobber step. 14:06:26 INFO - ##### 14:06:26 INFO - Running pre-action listener: _resource_record_pre_action 14:06:26 INFO - Running main action method: clobber 14:06:26 INFO - rmtree: /builds/slave/test/build 14:06:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:06:27 INFO - Running post-action listener: _resource_record_post_action 14:06:27 INFO - ##### 14:06:27 INFO - ##### Finished clobber step (success) 14:06:27 INFO - ##### 14:06:27 INFO - ##### 14:06:27 INFO - ##### Running read-buildbot-config step. 14:06:27 INFO - ##### 14:06:27 INFO - Running pre-action listener: _resource_record_pre_action 14:06:27 INFO - Running main action method: read_buildbot_config 14:06:27 INFO - Using buildbot properties: 14:06:27 INFO - { 14:06:27 INFO - "project": "", 14:06:27 INFO - "product": "firefox", 14:06:27 INFO - "script_repo_revision": "production", 14:06:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:06:27 INFO - "repository": "", 14:06:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 14:06:27 INFO - "buildid": "20160627113032", 14:06:27 INFO - "pgo_build": "True", 14:06:27 INFO - "basedir": "/builds/slave/test", 14:06:27 INFO - "buildnumber": 218, 14:06:27 INFO - "slavename": "talos-linux64-ix-036", 14:06:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:06:27 INFO - "platform": "ubuntu64_hw", 14:06:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:06:27 INFO - "branch": "fx-team", 14:06:27 INFO - "repo_path": "integration/fx-team", 14:06:27 INFO - "revision": "d095e848a601937d9e7daedfd7e4e6f0199706e2", 14:06:27 INFO - "stage_platform": "linux64-pgo", 14:06:27 INFO - "builduid": "63f12c61ae234384a4254170f9959b10", 14:06:27 INFO - "slavebuilddir": "test" 14:06:27 INFO - } 14:06:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 14:06:27 INFO - Running post-action listener: _resource_record_post_action 14:06:27 INFO - ##### 14:06:27 INFO - ##### Finished read-buildbot-config step (success) 14:06:27 INFO - ##### 14:06:27 INFO - ##### 14:06:27 INFO - ##### Running download-and-extract step. 14:06:27 INFO - ##### 14:06:27 INFO - Running pre-action listener: _resource_record_pre_action 14:06:27 INFO - Running main action method: download_and_extract 14:06:27 INFO - mkdir: /builds/slave/test/build/tests 14:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:27 INFO - https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:06:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:06:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:06:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:27 INFO - Downloaded 1361 bytes. 14:06:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:06:27 INFO - Using the following test package requirements: 14:06:27 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:06:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:06:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:06:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'jsshell-linux-x86_64.zip'], 14:06:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:06:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:06:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:06:27 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:06:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:06:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:06:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:27 INFO - https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:06:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:06:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:06:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:29 INFO - Downloaded 24279589 bytes. 14:06:29 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'] 14:06:29 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 14:06:30 INFO - Return code: 0 14:06:30 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 14:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:06:30 INFO - https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:06:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:06:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:06:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:33 INFO - Downloaded 24279589 bytes. 14:06:33 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'] 14:06:33 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 14:06:35 INFO - Return code: 0 14:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:06:35 INFO - https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:06:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:06:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:06:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:37 INFO - Downloaded 14405245 bytes. 14:06:37 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'] 14:06:37 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 14:06:37 INFO - Return code: 0 14:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:37 INFO - https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:06:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:06:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:06:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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 14:06:40 INFO - Downloaded 60190165 bytes. 14:06:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:06:40 INFO - mkdir: /builds/slave/test/properties 14:06:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:06:40 INFO - Writing to file /builds/slave/test/properties/build_url 14:06:40 INFO - Contents: 14:06:40 INFO - build_url:https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:06:41 INFO - Running post-action listener: _resource_record_post_action 14:06:41 INFO - Running post-action listener: set_extra_try_arguments 14:06:41 INFO - ##### 14:06:41 INFO - ##### Finished download-and-extract step (success) 14:06:41 INFO - ##### 14:06:41 INFO - ##### 14:06:41 INFO - ##### Running populate-webroot step. 14:06:41 INFO - ##### 14:06:41 INFO - Running pre-action listener: _resource_record_pre_action 14:06:41 INFO - Running main action method: populate_webroot 14:06:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:06:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:06:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:06:41 INFO - '--tppagecycles', 14:06:41 INFO - '7'], 14:06:41 INFO - 'tests': ['tsvgm']}}, 14:06:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:06:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:06:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:06:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:06:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['damp', 'tps']}, 14:06:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'tests': ['damp', 'tps']}, 14:06:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['dromaeo_dom']}, 14:06:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:06:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['basic_compositor_video']}, 14:06:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:06:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['a11yr', 14:06:41 INFO - 'ts_paint', 14:06:41 INFO - 'tpaint', 14:06:41 INFO - 'sessionrestore', 14:06:41 INFO - 'sessionrestore_no_auto_restore', 14:06:41 INFO - 'tabpaint']}, 14:06:41 INFO - 'other-e10s': {'tests': ['a11yr', 14:06:41 INFO - 'ts_paint', 14:06:41 INFO - 'tpaint', 14:06:41 INFO - 'sessionrestore', 14:06:41 INFO - 'sessionrestore_no_auto_restore', 14:06:41 INFO - 'tabpaint']}, 14:06:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:06:41 INFO - 'ts_paint', 14:06:41 INFO - 'tpaint', 14:06:41 INFO - 'sessionrestore', 14:06:41 INFO - 'sessionrestore_no_auto_restore', 14:06:41 INFO - 'tabpaint']}, 14:06:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:06:41 INFO - 'ts_paint', 14:06:41 INFO - 'tpaint', 14:06:41 INFO - 'sessionrestore', 14:06:41 INFO - 'sessionrestore_no_auto_restore', 14:06:41 INFO - 'tabpaint']}, 14:06:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['a11yr', 14:06:41 INFO - 'ts_paint', 14:06:41 INFO - 'tpaint', 14:06:41 INFO - 'sessionrestore', 14:06:41 INFO - 'sessionrestore_no_auto_restore', 14:06:41 INFO - 'tabpaint']}, 14:06:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['a11yr', 14:06:41 INFO - 'ts_paint', 14:06:41 INFO - 'tpaint', 14:06:41 INFO - 'sessionrestore', 14:06:41 INFO - 'sessionrestore_no_auto_restore', 14:06:41 INFO - 'tabpaint']}, 14:06:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['tsvgx', 14:06:41 INFO - 'tsvgr_opacity', 14:06:41 INFO - 'tart', 14:06:41 INFO - 'tscrollx', 14:06:41 INFO - 'cart']}, 14:06:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:06:41 INFO - 'tsvgr_opacity', 14:06:41 INFO - 'tart', 14:06:41 INFO - 'tscrollx', 14:06:41 INFO - 'cart']}, 14:06:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'talos_options': ['--disable-e10s'], 14:06:41 INFO - 'tests': ['tp5o']}, 14:06:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'tests': ['tp5o']}, 14:06:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'talos_options': ['--disable-e10s', 14:06:41 INFO - '--xperf_path', 14:06:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:41 INFO - 'tests': ['tp5n']}, 14:06:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:41 INFO - 'talos_options': ['--xperf_path', 14:06:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:41 INFO - 'tests': ['tp5n']}}, 14:06:41 INFO - 'talos.zip': {'path': '', 14:06:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:06:41 INFO - Running post-action listener: _resource_record_post_action 14:06:41 INFO - ##### 14:06:41 INFO - ##### Finished populate-webroot step (success) 14:06:41 INFO - ##### 14:06:41 INFO - ##### 14:06:41 INFO - ##### Running create-virtualenv step. 14:06:41 INFO - ##### 14:06:41 INFO - Running pre-action listener: _resource_record_pre_action 14:06:41 INFO - Running main action method: create_virtualenv 14:06:41 INFO - Creating virtualenv /builds/slave/test/build/venv 14:06:41 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 14:06:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:06:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:06:41 INFO - Using real prefix '/usr' 14:06:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:06:41 INFO - Installing distribute.............................................................................................................................................................................................done. 14:06:43 INFO - Installing pip.................done. 14:06:43 INFO - Return code: 0 14:06:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:43 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:43 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 14:06:43 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 14:06:43 INFO - Using env: {'DISPLAY': ':0', 14:06:43 INFO - 'HOME': '/home/cltbld', 14:06:43 INFO - 'LANG': 'en_US.UTF-8', 14:06:43 INFO - 'LANGUAGE': 'en_US:en', 14:06:43 INFO - 'LOGNAME': 'cltbld', 14:06:43 INFO - 'MAIL': '/var/mail/cltbld', 14:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:43 INFO - 'MOZ_NO_REMOTE': '1', 14:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:43 INFO - 'NO_EM_RESTART': '1', 14:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:43 INFO - 'PWD': '/builds/slave/test', 14:06:43 INFO - 'SHELL': '/bin/bash', 14:06:43 INFO - 'SHLVL': '1', 14:06:43 INFO - 'TERM': 'linux', 14:06:43 INFO - 'TMOUT': '86400', 14:06:43 INFO - 'USER': 'cltbld', 14:06:43 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:43 INFO - '_': '/tools/buildbot/bin/python'} 14:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:43 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 14:06:43 INFO - Cleaning up... 14:06:43 INFO - Return code: 0 14:06:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:43 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:43 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 14:06:43 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 14:06:43 INFO - Using env: {'DISPLAY': ':0', 14:06:43 INFO - 'HOME': '/home/cltbld', 14:06:43 INFO - 'LANG': 'en_US.UTF-8', 14:06:43 INFO - 'LANGUAGE': 'en_US:en', 14:06:43 INFO - 'LOGNAME': 'cltbld', 14:06:43 INFO - 'MAIL': '/var/mail/cltbld', 14:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:43 INFO - 'MOZ_NO_REMOTE': '1', 14:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:43 INFO - 'NO_EM_RESTART': '1', 14:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:43 INFO - 'PWD': '/builds/slave/test', 14:06:43 INFO - 'SHELL': '/bin/bash', 14:06:43 INFO - 'SHLVL': '1', 14:06:43 INFO - 'TERM': 'linux', 14:06:43 INFO - 'TMOUT': '86400', 14:06:43 INFO - 'USER': 'cltbld', 14:06:43 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:43 INFO - '_': '/tools/buildbot/bin/python'} 14:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:44 INFO - Downloading/unpacking psutil>=3.1.1 14:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:06:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:46 INFO - Installing collected packages: psutil 14:06:46 INFO - Running setup.py install for psutil 14:06:46 INFO - building 'psutil._psutil_linux' extension 14:06:46 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 14:06:46 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 14:06:46 INFO - building 'psutil._psutil_posix' extension 14:06:46 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 14:06:46 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 14:06:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:46 INFO - Successfully installed psutil 14:06:46 INFO - Cleaning up... 14:06:46 INFO - Return code: 0 14:06:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:06:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:06:46 INFO - Using env: {'DISPLAY': ':0', 14:06:46 INFO - 'HOME': '/home/cltbld', 14:06:46 INFO - 'LANG': 'en_US.UTF-8', 14:06:46 INFO - 'LANGUAGE': 'en_US:en', 14:06:46 INFO - 'LOGNAME': 'cltbld', 14:06:46 INFO - 'MAIL': '/var/mail/cltbld', 14:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:46 INFO - 'MOZ_NO_REMOTE': '1', 14:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:46 INFO - 'NO_EM_RESTART': '1', 14:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:46 INFO - 'PWD': '/builds/slave/test', 14:06:46 INFO - 'SHELL': '/bin/bash', 14:06:46 INFO - 'SHLVL': '1', 14:06:46 INFO - 'TERM': 'linux', 14:06:46 INFO - 'TMOUT': '86400', 14:06:46 INFO - 'USER': 'cltbld', 14:06:46 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:46 INFO - '_': '/tools/buildbot/bin/python'} 14:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:06:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:06:48 INFO - Installing collected packages: mozsystemmonitor 14:06:48 INFO - Running setup.py install for mozsystemmonitor 14:06:48 INFO - Successfully installed mozsystemmonitor 14:06:48 INFO - Cleaning up... 14:06:48 INFO - Return code: 0 14:06:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:06:48 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')]} 14:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:48 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')]} 14:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:48 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:48 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 14:06:48 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 14:06:48 INFO - Using env: {'DISPLAY': ':0', 14:06:48 INFO - 'HOME': '/home/cltbld', 14:06:48 INFO - 'LANG': 'en_US.UTF-8', 14:06:48 INFO - 'LANGUAGE': 'en_US:en', 14:06:48 INFO - 'LOGNAME': 'cltbld', 14:06:48 INFO - 'MAIL': '/var/mail/cltbld', 14:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:48 INFO - 'MOZ_NO_REMOTE': '1', 14:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:48 INFO - 'NO_EM_RESTART': '1', 14:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:48 INFO - 'PWD': '/builds/slave/test', 14:06:48 INFO - 'SHELL': '/bin/bash', 14:06:48 INFO - 'SHLVL': '1', 14:06:48 INFO - 'TERM': 'linux', 14:06:48 INFO - 'TMOUT': '86400', 14:06:48 INFO - 'USER': 'cltbld', 14:06:48 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:48 INFO - '_': '/tools/buildbot/bin/python'} 14:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:49 INFO - Downloading/unpacking jsonschema==2.5.1 14:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:06:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:51 INFO - Downloading functools32-3.2.3-2.tar.gz 14:06:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:06:51 INFO - warning: no files found matching '*.txt' 14:06:51 INFO - no previously-included directories found matching 'build' 14:06:51 INFO - no previously-included directories found matching 'dist' 14:06:51 INFO - no previously-included directories found matching '.git*' 14:06:51 INFO - Installing collected packages: jsonschema, functools32 14:06:51 INFO - Running setup.py install for functools32 14:06:51 INFO - warning: no files found matching '*.txt' 14:06:51 INFO - no previously-included directories found matching 'build' 14:06:51 INFO - no previously-included directories found matching 'dist' 14:06:51 INFO - no previously-included directories found matching '.git*' 14:06:51 INFO - Successfully installed jsonschema functools32 14:06:51 INFO - Cleaning up... 14:06:51 INFO - Return code: 0 14:06:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:06:51 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')]} 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:51 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')]} 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:51 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:51 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 14:06:51 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 14:06:51 INFO - Using env: {'DISPLAY': ':0', 14:06:51 INFO - 'HOME': '/home/cltbld', 14:06:51 INFO - 'LANG': 'en_US.UTF-8', 14:06:51 INFO - 'LANGUAGE': 'en_US:en', 14:06:51 INFO - 'LOGNAME': 'cltbld', 14:06:51 INFO - 'MAIL': '/var/mail/cltbld', 14:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:51 INFO - 'MOZ_NO_REMOTE': '1', 14:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:51 INFO - 'NO_EM_RESTART': '1', 14:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:51 INFO - 'PWD': '/builds/slave/test', 14:06:51 INFO - 'SHELL': '/bin/bash', 14:06:51 INFO - 'SHLVL': '1', 14:06:51 INFO - 'TERM': 'linux', 14:06:51 INFO - 'TMOUT': '86400', 14:06:51 INFO - 'USER': 'cltbld', 14:06:51 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:51 INFO - '_': '/tools/buildbot/bin/python'} 14:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:51 INFO - Downloading/unpacking blobuploader==1.2.4 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:06:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:06:53 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 14:06:53 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 14:06:53 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 14:06:53 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 14:06:53 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 14:06:53 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 14:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:06:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:06:54 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 14:06:54 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 14:06:54 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 14:06:54 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 14:06:54 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 14:06:54 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 14:06:54 INFO - Downloading docopt-0.6.1.tar.gz 14:06:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:06:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:06:54 INFO - Running setup.py install for blobuploader 14:06:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:06:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:06:54 INFO - Running setup.py install for requests 14:06:54 INFO - Running setup.py install for docopt 14:06:54 INFO - Successfully installed blobuploader requests docopt 14:06:54 INFO - Cleaning up... 14:06:54 INFO - Return code: 0 14:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06: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')]} 14:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06: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')]} 14:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:54 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:54 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 14:06:54 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 14:06:54 INFO - Using env: {'DISPLAY': ':0', 14:06:54 INFO - 'HOME': '/home/cltbld', 14:06:54 INFO - 'LANG': 'en_US.UTF-8', 14:06:54 INFO - 'LANGUAGE': 'en_US:en', 14:06:54 INFO - 'LOGNAME': 'cltbld', 14:06:54 INFO - 'MAIL': '/var/mail/cltbld', 14:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:54 INFO - 'MOZ_NO_REMOTE': '1', 14:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:54 INFO - 'NO_EM_RESTART': '1', 14:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:54 INFO - 'PWD': '/builds/slave/test', 14:06:54 INFO - 'SHELL': '/bin/bash', 14:06:54 INFO - 'SHLVL': '1', 14:06:54 INFO - 'TERM': 'linux', 14:06:54 INFO - 'TMOUT': '86400', 14:06:54 INFO - 'USER': 'cltbld', 14:06:54 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:54 INFO - '_': '/tools/buildbot/bin/python'} 14:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:06:55 INFO - Running setup.py (path:/tmp/pip-JV80WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:06:55 INFO - Running setup.py (path:/tmp/pip-gAHJ9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:06:55 INFO - Running setup.py (path:/tmp/pip-5czNPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:06:55 INFO - Running setup.py (path:/tmp/pip-wGNLj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:06:55 INFO - Running setup.py (path:/tmp/pip-YVNylD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:06:55 INFO - Running setup.py (path:/tmp/pip-NLP30o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:06:55 INFO - Running setup.py (path:/tmp/pip-4cqUmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:06:55 INFO - Running setup.py (path:/tmp/pip-Qu82mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:06:55 INFO - Running setup.py (path:/tmp/pip-EEenzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:06:55 INFO - Running setup.py (path:/tmp/pip-qjPUCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:06:55 INFO - Running setup.py (path:/tmp/pip-Hau6lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:06:56 INFO - Running setup.py (path:/tmp/pip-sZrtmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:06:56 INFO - Running setup.py (path:/tmp/pip-A4KGYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:06:56 INFO - Running setup.py (path:/tmp/pip-yisVKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:56 INFO - Running setup.py (path:/tmp/pip-PKzTKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:06:56 INFO - Running setup.py (path:/tmp/pip-gXZm1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:56 INFO - Running setup.py (path:/tmp/pip-MisZRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:06:56 INFO - Running setup.py install for manifestparser 14:06:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:06:56 INFO - Running setup.py install for mozcrash 14:06:56 INFO - Running setup.py install for mozdebug 14:06:56 INFO - Running setup.py install for mozdevice 14:06:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:06:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:06:56 INFO - Running setup.py install for mozfile 14:06:56 INFO - Running setup.py install for mozhttpd 14:06:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for mozinfo 14:06:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for mozInstall 14:06:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for mozleak 14:06:57 INFO - Running setup.py install for mozlog 14:06:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for moznetwork 14:06:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for mozprocess 14:06:57 INFO - Running setup.py install for mozprofile 14:06:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for mozrunner 14:06:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:06:57 INFO - Running setup.py install for mozscreenshot 14:06:58 INFO - Running setup.py install for moztest 14:06:58 INFO - Running setup.py install for mozversion 14:06:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:06:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:06:58 INFO - Cleaning up... 14:06:58 INFO - Return code: 0 14:06:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:58 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:06:58 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 14:06:58 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 14:06:58 INFO - Using env: {'DISPLAY': ':0', 14:06:58 INFO - 'HOME': '/home/cltbld', 14:06:58 INFO - 'LANG': 'en_US.UTF-8', 14:06:58 INFO - 'LANGUAGE': 'en_US:en', 14:06:58 INFO - 'LOGNAME': 'cltbld', 14:06:58 INFO - 'MAIL': '/var/mail/cltbld', 14:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:58 INFO - 'MOZ_NO_REMOTE': '1', 14:06:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:58 INFO - 'NO_EM_RESTART': '1', 14:06:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:58 INFO - 'PWD': '/builds/slave/test', 14:06:58 INFO - 'SHELL': '/bin/bash', 14:06:58 INFO - 'SHLVL': '1', 14:06:58 INFO - 'TERM': 'linux', 14:06:58 INFO - 'TMOUT': '86400', 14:06:58 INFO - 'USER': 'cltbld', 14:06:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:58 INFO - '_': '/tools/buildbot/bin/python'} 14:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:06:58 INFO - Running setup.py (path:/tmp/pip-vZSSaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:06:58 INFO - Running setup.py (path:/tmp/pip-099I2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:06:58 INFO - Running setup.py (path:/tmp/pip-ObJAzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:06:58 INFO - Running setup.py (path:/tmp/pip-OBIQgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:06:58 INFO - Running setup.py (path:/tmp/pip-CLLNZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:06:58 INFO - Running setup.py (path:/tmp/pip-R6BYD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:06:58 INFO - Running setup.py (path:/tmp/pip-5OA2K9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:06:58 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)) 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:06:58 INFO - Running setup.py (path:/tmp/pip-sKvx98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:06:59 INFO - Running setup.py (path:/tmp/pip-8YhlKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:06:59 INFO - Running setup.py (path:/tmp/pip-DXXhpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:06:59 INFO - Running setup.py (path:/tmp/pip-oW0Udh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:06:59 INFO - Running setup.py (path:/tmp/pip-ChG7cJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:06:59 INFO - Running setup.py (path:/tmp/pip-H1sE4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:06:59 INFO - Running setup.py (path:/tmp/pip-oXajaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:59 INFO - Running setup.py (path:/tmp/pip-8a_ONi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:06:59 INFO - Running setup.py (path:/tmp/pip-AD6YSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:59 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)) 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:59 INFO - Running setup.py (path:/tmp/pip-ObZFud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:59 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)) 14:06:59 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)) 14:06:59 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)) 14:06:59 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)) 14:06:59 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)) 14:06:59 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)) 14:06:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:06:59 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 14:06:59 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 14:06:59 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 14:06:59 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 14:06:59 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 14:06:59 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 14:07:01 INFO - Downloading blessings-1.6.tar.gz 14:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:07:01 INFO - Installing collected packages: blessings 14:07:01 INFO - Running setup.py install for blessings 14:07:01 INFO - Successfully installed blessings 14:07:01 INFO - Cleaning up... 14:07:01 INFO - Return code: 0 14:07:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:07:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:02 INFO - Reading from file tmpfile_stdout 14:07:02 INFO - Current package versions: 14:07:02 INFO - argparse == 1.2.1 14:07:02 INFO - blessings == 1.6 14:07:02 INFO - blobuploader == 1.2.4 14:07:02 INFO - docopt == 0.6.1 14:07:02 INFO - functools32 == 3.2.3-2 14:07:02 INFO - jsonschema == 2.5.1 14:07:02 INFO - manifestparser == 1.1 14:07:02 INFO - mozInstall == 1.12 14:07:02 INFO - mozcrash == 0.17 14:07:02 INFO - mozdebug == 0.1 14:07:02 INFO - mozdevice == 0.48 14:07:02 INFO - mozfile == 1.2 14:07:02 INFO - mozhttpd == 0.7 14:07:02 INFO - mozinfo == 0.9 14:07:02 INFO - mozleak == 0.1 14:07:02 INFO - mozlog == 3.2 14:07:02 INFO - moznetwork == 0.27 14:07:02 INFO - mozprocess == 0.22 14:07:02 INFO - mozprofile == 0.28 14:07:02 INFO - mozrunner == 6.11 14:07:02 INFO - mozscreenshot == 0.1 14:07:02 INFO - mozsystemmonitor == 0.3 14:07:02 INFO - moztest == 0.7 14:07:02 INFO - mozversion == 1.4 14:07:02 INFO - psutil == 3.1.1 14:07:02 INFO - requests == 1.2.3 14:07:02 INFO - wsgiref == 0.1.2 14:07:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07: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')]} 14:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07: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')]} 14:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:02 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 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:07:02 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 14:07:02 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 14:07:02 INFO - Using env: {'DISPLAY': ':0', 14:07:02 INFO - 'HOME': '/home/cltbld', 14:07:02 INFO - 'LANG': 'en_US.UTF-8', 14:07:02 INFO - 'LANGUAGE': 'en_US:en', 14:07:02 INFO - 'LOGNAME': 'cltbld', 14:07:02 INFO - 'MAIL': '/var/mail/cltbld', 14:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:02 INFO - 'MOZ_NO_REMOTE': '1', 14:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:02 INFO - 'NO_EM_RESTART': '1', 14:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:02 INFO - 'PWD': '/builds/slave/test', 14:07:02 INFO - 'SHELL': '/bin/bash', 14:07:02 INFO - 'SHLVL': '1', 14:07:02 INFO - 'TERM': 'linux', 14:07:02 INFO - 'TMOUT': '86400', 14:07:02 INFO - 'USER': 'cltbld', 14:07:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:02 INFO - '_': '/tools/buildbot/bin/python'} 14:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 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)) 14:07:02 INFO - Cleaning up... 14:07:02 INFO - Return code: 0 14:07:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:07: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')]} 14:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07: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')]} 14:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:07: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', 'jsonschema'] in /builds/slave/test/build 14:07: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 jsonschema 14:07:02 INFO - Using env: {'DISPLAY': ':0', 14:07:02 INFO - 'HOME': '/home/cltbld', 14:07:02 INFO - 'LANG': 'en_US.UTF-8', 14:07:02 INFO - 'LANGUAGE': 'en_US:en', 14:07:02 INFO - 'LOGNAME': 'cltbld', 14:07:02 INFO - 'MAIL': '/var/mail/cltbld', 14:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:02 INFO - 'MOZ_NO_REMOTE': '1', 14:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:02 INFO - 'NO_EM_RESTART': '1', 14:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:02 INFO - 'PWD': '/builds/slave/test', 14:07:02 INFO - 'SHELL': '/bin/bash', 14:07:02 INFO - 'SHLVL': '1', 14:07:02 INFO - 'TERM': 'linux', 14:07:02 INFO - 'TMOUT': '86400', 14:07:02 INFO - 'USER': 'cltbld', 14:07:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:02 INFO - '_': '/tools/buildbot/bin/python'} 14:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:07:02 INFO - Cleaning up... 14:07:02 INFO - Return code: 0 14:07:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:07: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')]} 14:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07: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')]} 14:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9224f26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', '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 14:07: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', 'flake8'] in /builds/slave/test/build 14:07: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 flake8 14:07:02 INFO - Using env: {'DISPLAY': ':0', 14:07:02 INFO - 'HOME': '/home/cltbld', 14:07:02 INFO - 'LANG': 'en_US.UTF-8', 14:07:02 INFO - 'LANGUAGE': 'en_US:en', 14:07:02 INFO - 'LOGNAME': 'cltbld', 14:07:02 INFO - 'MAIL': '/var/mail/cltbld', 14:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:02 INFO - 'MOZ_NO_REMOTE': '1', 14:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:02 INFO - 'NO_EM_RESTART': '1', 14:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:02 INFO - 'PWD': '/builds/slave/test', 14:07:02 INFO - 'SHELL': '/bin/bash', 14:07:02 INFO - 'SHLVL': '1', 14:07:02 INFO - 'TERM': 'linux', 14:07:02 INFO - 'TMOUT': '86400', 14:07:02 INFO - 'USER': 'cltbld', 14:07:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:02 INFO - '_': '/tools/buildbot/bin/python'} 14:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:02 INFO - Downloading/unpacking flake8 14:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:07:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:07:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:07:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:07:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:07:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:07:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:07:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:07:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:07:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:07:05 INFO - no previously-included directories found matching 'docs/_build' 14:07:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:07:05 INFO - Running setup.py install for pep8 14:07:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:07:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:07:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:07:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:07:05 INFO - no previously-included directories found matching 'docs/_build' 14:07:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:07:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:07:05 INFO - Cleaning up... 14:07:05 INFO - Return code: 0 14:07:05 INFO - Running post-action listener: _resource_record_post_action 14:07:05 INFO - Running post-action listener: _start_resource_monitoring 14:07:05 INFO - Starting resource monitoring. 14:07:05 INFO - ##### 14:07:05 INFO - ##### Finished create-virtualenv step (success) 14:07:05 INFO - ##### 14:07:05 INFO - ##### 14:07:05 INFO - ##### Running install step. 14:07:05 INFO - ##### 14:07:05 INFO - Running pre-action listener: _resource_record_pre_action 14:07:05 INFO - Running main action method: install 14:07:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:05 INFO - Reading from file tmpfile_stdout 14:07:05 INFO - Detecting whether we're running mozinstall >=1.0... 14:07:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:07:05 INFO - Reading from file tmpfile_stdout 14:07:05 INFO - Output received: 14:07:05 INFO - Usage: mozinstall [options] installer 14:07:05 INFO - Options: 14:07:05 INFO - -h, --help show this help message and exit 14:07:05 INFO - -d DEST, --destination=DEST 14:07:05 INFO - Directory to install application into. [default: 14:07:05 INFO - "/builds/slave/test"] 14:07:05 INFO - --app=APP Application being installed. [default: firefox] 14:07:05 INFO - mkdir: /builds/slave/test/build/application 14:07:05 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'] 14:07:05 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 14:07:22 INFO - Reading from file tmpfile_stdout 14:07:22 INFO - Output received: 14:07:22 INFO - /builds/slave/test/build/application/firefox/firefox 14:07:22 INFO - Running post-action listener: _resource_record_post_action 14:07:22 INFO - ##### 14:07:22 INFO - ##### Finished install step (success) 14:07:22 INFO - ##### 14:07:22 INFO - ##### 14:07:22 INFO - ##### Running run-tests step. 14:07:22 INFO - ##### 14:07:22 INFO - Running pre-action listener: _resource_record_pre_action 14:07:22 INFO - Running main action method: run_tests 14:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:07:22 INFO - Python 2.7.3 14:07:22 INFO - Return code: 0 14:07:22 INFO - grabbing minidump binary from tooltool 14:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:07:22 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 0x161b6f0>, '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 0x161c5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161caa0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:07:22 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 14:07:22 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 14:07:22 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 14:07:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:07:23 INFO - Return code: 0 14:07:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:07:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:07:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:07:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:07:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:07:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:07:23 INFO - Return code: 0 14:07:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 14:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 14:07:23 INFO - Using env: {'DISPLAY': ':0', 14:07:23 INFO - 'HOME': '/home/cltbld', 14:07:23 INFO - 'LANG': 'en_US.UTF-8', 14:07:23 INFO - 'LANGUAGE': 'en_US:en', 14:07:23 INFO - 'LOGNAME': 'cltbld', 14:07:23 INFO - 'MAIL': '/var/mail/cltbld', 14:07:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:07:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:23 INFO - 'MOZ_NO_REMOTE': '1', 14:07:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:23 INFO - 'NO_EM_RESTART': '1', 14:07:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:23 INFO - 'PWD': '/builds/slave/test', 14:07:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:07:23 INFO - 'SHELL': '/bin/bash', 14:07:23 INFO - 'SHLVL': '1', 14:07:23 INFO - 'TERM': 'linux', 14:07:23 INFO - 'TMOUT': '86400', 14:07:23 INFO - 'USER': 'cltbld', 14:07:23 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964', 14:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:23 INFO - '_': '/tools/buildbot/bin/python'} 14:07:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 14:07:24 INFO - mozversion application_buildid: 20160627113032 14:07:24 INFO - mozversion application_changeset: d095e848a601937d9e7daedfd7e4e6f0199706e2 14:07:24 INFO - mozversion application_display_name: Nightly 14:07:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:07:24 INFO - mozversion application_name: Firefox 14:07:24 INFO - mozversion application_remotingname: firefox 14:07:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:07:24 INFO - mozversion application_vendor: Mozilla 14:07:24 INFO - mozversion application_version: 50.0a1 14:07:24 INFO - mozversion platform_buildid: 20160627113032 14:07:24 INFO - mozversion platform_changeset: d095e848a601937d9e7daedfd7e4e6f0199706e2 14:07:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:07:24 INFO - mozversion platform_version: 50.0a1 14:07:24 INFO - using testdate: 1467061644 14:07:24 INFO - actual date: 1467061644 14:07:24 INFO - starting webserver on 'localhost:36091' 14:07:24 INFO - SUITE-START | Running 2 tests 14:07:24 INFO - TEST-START | dromaeo_css 14:07:24 INFO - operating with platform_type : linux_ 14:07:24 INFO - Initialising browser for dromaeo_css test... 14:07:24 INFO - TEST-INFO | started process 1300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIU8ErA/profile http://localhost:36091/getInfo.html) 14:07:28 INFO - PROCESS | 1300 | __metrics Screen width/height:1600/1200 14:07:28 INFO - PROCESS | 1300 | colorDepth:24 14:07:28 INFO - PROCESS | 1300 | Browser inner width/height: 1024/697 14:07:28 INFO - PROCESS | 1300 | __metrics 14:07:29 INFO - PROCESS | 1300 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:07:30 INFO - TEST-INFO | 1300: exit 0 14:07:30 INFO - Browser initialized. 14:07:30 INFO - Running cycle 1/1 for dromaeo_css test... 14:07:30 INFO - TEST-INFO | started process 1383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIU8ErA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:07:42 INFO - PROCESS | 1383 | RSS: Main: 173907968 14:07:42 INFO - PROCESS | 1383 | 14:08:33 INFO - PROCESS | 1383 | 1467061713592 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] 14:10:42 INFO - PROCESS | 1383 | Cycle 1(1): loaded http://localhost:36091/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36091/tests/dromaeo/cssquery-ext.html) 14:10:43 INFO - PROCESS | 1383 | RSS: Main: 280559616 14:10:43 INFO - PROCESS | 1383 | 14:13:45 INFO - PROCESS | 1383 | Cycle 1(1): loaded http://localhost:36091/tests/dromaeo/cssquery-ext.html (next: http://localhost:36091/tests/dromaeo/cssquery-jquery.html) 14:13:46 INFO - PROCESS | 1383 | RSS: Main: 255442944 14:13:46 INFO - PROCESS | 1383 | 14:16:48 INFO - PROCESS | 1383 | Cycle 1(1): loaded http://localhost:36091/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36091/tests/dromaeo/cssquery-mootools.html) 14:16:48 INFO - PROCESS | 1383 | RSS: Main: 259584000 14:16:48 INFO - PROCESS | 1383 | 14:19:50 INFO - PROCESS | 1383 | Cycle 1(1): loaded http://localhost:36091/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36091/tests/dromaeo/cssquery-prototype.html) 14:19:51 INFO - PROCESS | 1383 | RSS: Main: 257101824 14:19:51 INFO - PROCESS | 1383 | 14:22:53 INFO - PROCESS | 1383 | Cycle 1(1): loaded http://localhost:36091/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36091/tests/dromaeo/cssquery-yui.html) 14:22:54 INFO - PROCESS | 1383 | RSS: Main: 242917376 14:22:54 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | Cycle 1(1): loaded http://localhost:36091/tests/dromaeo/cssquery-yui.html (next: http://localhost:36091/tests/dromaeo/cssquery-dojo.html) 14:25:57 INFO - PROCESS | 1383 | RSS: Main: 286957568 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | __start_tp_report 14:25:57 INFO - PROCESS | 1383 | _x_x_mozilla_page_load 14:25:57 INFO - PROCESS | 1383 | _x_x_mozilla_page_load_details 14:25:57 INFO - PROCESS | 1383 | |i|pagename|runs| 14:25:57 INFO - PROCESS | 1383 | |0;dojo.html;3064.6322441307047;3085.95371069434;3113.2216945763557;3145.4809956357203;3145.952810707838;5284.101702710539;5409.864753381157;5440.646357986728;5527.806526771564;5575.247341608877;5381.838544843661;5447.264619276397;5452.073147564914;5488.067028605136;5543.916841247385;4794.352762377078;4798.160321943664;4806.134895718769;4881.389826271716;4923.852284431455;4784.688995215317;4988.850334489953;4991.500849914997;5000.424951130612;5034.169362055275;4856.660033797635;4887.14474966883;4895.412550493964;4964.826231081913;4984.22744474607;4964.05682920244;4965.975170124162;4971.676841005323;4976.129177393942;4979.850604481871;4884.853454396374;4890.266460030988;4937.407511098655;4939.481354457766;4948.38145231846;5485.204645326446;5485.725713714338;5547.223388725582;5551.056320425538;5561.860953476155;5596.104623260299;5866.384029676908;5879.353271140171;5883.323417806964;5885.588008839383;5742.0238559445;5828.2131912191735;5838.2702162229725;5910.1430292607765;5930.1401296812155;5423.647462914937;5429.56563474925;5468.343798744176;5471.398146203915;5471.835844924659;5828;5915.290165180119;5994.700264986734;6007.309159446632;6008.579399442085;5750.971245143747;5769.769209231584;5781.739821708034;5784;5785.479306862402;5817.185594016841;5819.94180058194;5822.33043200029;5863.296404431496;5872.618279811799;5576.442355764391;5722.198892155102;5769.884602308014;5779.479846813807;5788.18965344852;5765.682887441231;5835.241418615553;5876.265466816648;5879.735411906475;5880.852978675482;4404.185225733249;4411.22803509391;4414.1171765647;4414.977925110354;4449.9777501112285;3891.7664940103687;3925.2542017016676;3940.5468371137113;3958.7228893977144;3992.4210989406374;5413.621046526784;5449;5493.7802487900035;5510.448955104457;5525.475079867406;5812.88374232513;5852.678102704393;5855.882882342329;5858.150568167592;5897.793577224862;5351.277577526984;5384.380796208407;5467.917981230285;5508.641938274;5520.66875987434;4889.315495830587;4945.184044632667;4953.6037117030555;4957.603391728653;4998.225275082368;6038.607490513103;6066.302375226817;6071.908921366183;6078.6048906820915;6084.543659225487;5651.406602306699;5753.942460575341;5820.388859169896;5851.268591426072;5859.414058594277;6055.969720151547;6059;6078.057901025348;6079.726412311368;6113;5696.259486266758;5740.827775166752;5744.454276843693;5752.194692742935;5753.338366087786;5635.211070450058;5733.655980641175;5768.3078030634915;5812.505936995233;5844.707764611838;5700.087985922232;5706.172604972338;5717.885642287048;5735.7705691773535;5736.68448235351;5677.77288908439;5692.800751973661;5694.373618901835;5694.829155125353;5695.117256825199;5317.149256119157;5488.945110549003;5557.221988921635;5588.692621905834;5608.579356548194;5726.255586773693;5743.712814359349;5769.913451298318;5773.1051686988585;5778.8844223114465;5447.754851031792;5512.641678290898;5594.41258667834;5604.6917419542315;5606.299212598426 14:25:57 INFO - PROCESS | 1383 | |1;ext.html;586.8591538030871;612.672220362106;620.813755873238;629.6253729031225;640.6348381422589;42167.36748948768;43769;43845.78077109618;43854.12291754163;47893.760531197295;45086.774566127206;45287.77356113223;45361;45393.7730311348;45421.54578454215;8910.46537207768;9130.817383652324;9131.13254240846;9140.908590914072;9152.359334846564;8975.057618950013;9047.773805654846;9069.818603627924;9070.092990700943;9072.909270907272;9138.314626403013;9181.173694367506;9188.724338269829;9189.218916392114;9191.862122068174;9081.318901082412;9104.408213466104;9117.726468205965;9124.498152601604;9124.817503649956;9019.684311049083;9086.909130908673;9089.363744537884;9092.63629454821;9096.044915283866;43262.35106473405;43625;43708.78145609283;43718;43718.56281437177;3471.8437670304897;3612.4039533476944;3621.311950729353;3633.2733453309443;3703.574088979775;32437;32626.67373326284;33439.6656033439;33467.330653386794;33622;4408.7354758714255;4443.333499974998;4461.21928662486;4479.417675702138;4509.210888094571;373268;376565;376968;376974;377404;58968.41031589629;59064;59340;59412;59439.10841337383;51366.74316628393;52707.736461318214;52849.471505284455;54311;62581;14181;14381.28093595316;14428.639284017983;14429.855701442852;14446.855531444551;10076.244281678903;11019.283746556448;11097.667069987914;11107.111431085494;11109;16936.491905242863;16994.150292485574;17556.648867022588;17805.910970445064;17892.642147156985;6030.969845150834;6035.66803825785;6052.425019623129;6185.103160041769;6303.590266632746;901.364538000708;952.5570609666399;1098.050186588611;1098.5001824169983;1192.5825960913785;32026.519602205986;32045;32124.518132228037;32374.514382284284;32517.67482325194;1106.8948449897407;1112.249231768553;1119.2668801934747;1132.6318946342308;1138.3113216504062;17029;17130.828691713174;17287.65424691499;17323.653526929644;17367.13164341778;152557.23721381323;152837;153848;153941;154662;57307.13464326767;58439.70780146157;58490;58567;58724.41275587275;1185.6976471000012;1230.0036970054284;1277.2464246094662;1308.7971364438527;1319.346923272986;30239.848800755855;32677;32722.672773271963;32886.50670239996;33192.83403582966;49860.75069624629;59452.40547594642;61328;61821;66473.33526664865;358987;360748;362757;510427;510976;38677;45702.77148614368;45958.77020614876;46236.57514199885;47055;49425.505744943526;50399.49600503948;50988.74505627448;51396;51619.741901290254;42589.78705106579;42790.35814462848;43182.784086080625;43501.56498434975;44143.55856441523;41276.38085428661;41814.372784408864;42411;42575.5742442584;43404;42370.15259694727;42717.57282427136;42856.14287714166;43403.13193736044;43618.56381436145;36606.267874642894;37773.811130945265;37912.81043594764;38796.61203388043;38800.80599596984;28392.290192745346;28621.71378286274;28876.855615721786;29058;29067.273318167216 14:25:57 INFO - PROCESS | 1383 | |2;jquery.html;372.17563097738514;381.8682554518692;394.3257030477883;399.11595815269175;399.1418450331788;18727.625447491042;18768.155433005515;19831.801681983176;20421.591568168627;20439.386818395436;11994.640160795168;12596.937015314934;12685.429155687993;12715;18694.906525467355;5186.0665080285435;5313.38896026958;5344.706041167734;5348;5368.3021207243;3817.656410923016;4097.774622395782;5304.389995150549;6500.024996250553;6614.305497922721;6535.313792051814;6540.182477190351;6548.607083574977;6572.934270657304;6578.506612004095;6270.937290627081;6438.6780660967;6453.677316134174;6573.901391479108;6580;3768.679662228714;5353.9196912046355;5686.0617998030275;5690.687012214326;5748.338941021794;13544.729105417935;13978.301084945711;14054.648633784238;14108.647283817883;14157.150570965776;2223.788740069691;2328.1385887221836;2422.1038215860067;2732.6584176977876;2944.07261712562;10004.549795259158;10229.335093218991;10262;10388.844167337496;10935.617253396173;1930.1989674285296;1958.1677786940493;1972.8816271023784;1993.3123072540095;2028.3712049264775;83435;84864;85010.57494712487;85474.5726271371;86329.56835215783;10400.791984160425;10691;10935.781284374269;11069.612563560398;11151.442427878574;15172.393104275927;15180.240987950558;15252.313645885963;15256.923715381352;15257.237138143048;7177.6770045348085;7255.927440725631;7326.926730732731;7406.592637404887;7416;6708.19501659804;6759.256481787;6818.18181818185;6913.930860691329;6936.687849046805;4575.542445755398;4692.7653617319;4732.787024583902;4870.853874383774;4872.293517439952;1865.7761068671575;1926.088474390299;1938.505681051351;1958.9902050489657;1960.2942940541393;872.9301655867515;958.3004406783087;989.6437282578266;1002.3711468863097;1077.9245452818384;18143.546411339823;18206.18072186755;18560.81439185618;19242.014291519143;19429.47416835802;982.9557669904872;990.3364745620452;1029.6190409548515;1067.813132701774;1101.2566517600587;10117.949410252902;10159.238057145743;10555.419451930064;10693.83959240612;10715.678529644123;465673;471616;475261;475398;476942;92543.07456925344;93865;94638;95589;96242.51878740561;1001.2990906365574;1014.1734486393566;1037.3827572594446;1042.9843552346722;1043.2853495355555;16996.745048824032;17499.56251093733;17730.5567360817;17880.28478860831;17955.461336159937;30206.697933021267;37948.62051379561;38399;38752;43160.78419607882;58858.41141588701;59014.70492647509;60600;60603;60807;8508.829823403621;8559.400841941253;8690.608922598625;8699;8937.329700272636;12164.75670486603;12797.29614871191;12839.807402889151;13182.406791694588;13886.166829990232;11766.352850593299;11772.293662380285;11772.52909883595;11792.76414471723;12271.938640306742;10966.670999870017;11646.126540509325;11974.64076077719;12013.879861201276;12280.631581052583;11860.644180674248;11956.282623042645;12091.39543022863;12193.390330483619;12197.329146897006;8580.65677372898;8731.563421828756;8815.955920220358;8827.24968377695;8927.553622318988;8888.777780555538;11674.76650467003;11797.935361311907;11941;11984 14:25:57 INFO - PROCESS | 1383 | |3;mootools.html;1187.6555798818338;1287.427094942751;1294.8316718826552;1430.8998370114093;1448.0659974316566;8611.354148438868;9001;9012.053734357889;9317.673881414152;9322.766930826732;9030.548472576362;9211.263098952068;9213.078692130784;9228.215601673866;9248.26013918885;2084.5309805293764;2320.872352020638;2359.6578496118095;2407.0492155598527;2410.517896420714;2431.8419302745265;2436.9390765230833;2439.780419762221;2441.2920253126663;2443.035001174535;2387.331547166796;2401.0635852017726;2411.638254261857;2414.468816860288;2416.4200591858003;2017.5359667276534;2271.7501074881748;2352.2943117064983;2361.929142125739;2362.29131260622;2331.2889568681544;2335.9065637374483;2353;2358.7877091061887;2360.7285162206394;9061.41100828444;9201.447913125166;9209.263258939336;9210.907890921071;9214.815703685888;5309.601779866525;5462.289902312674;5470.015397228497;5476.616636835423;5478.205660179292;3353.6143343515564;3453.913652158691;3517.7889326640484;3528.8059156746367;3542.468629705539;1141.3836528274721;1160.5299853559322;1192.6004788395899;1207.6075275535486;1216.1122380662166;13066.803997940038;13074.542391016364;13081.607551773463;13088.607341779762;13104.803427948493;4751.52484751529;4751.641078766896;4873.439554451284;4884;4884.560389564886;10676.786464270672;10705.090067344207;10740.033396994151;10757.031867131996;10758.300710453796;5437.564994800407;5501.202325662756;5512.5589952803675;5515.338159420815;5520.309961254843;4368.235558777201;4924.113659541317;5068.847934561969;5071.46749591295;5154.92267615986;4041.373587094005;4061.194730728321;4099.200655872136;4106.774127422961;4125.814338354781;1259.3640211693037;1290.6708789258678;1301.7201301720177;1309.9476020959055;1327.621627836062;961.3126614470686;974.8196583632051;981.8183636027358;989.7278248481725;995.1392045880261;6276.529260305495;6280.434760871543;6286.591371560834;6321.462675672619;6336.89104406727;467.73339196657577;468.42149944818124;478.42349968288823;480.49787971569793;487.16937621355504;4213.1994920965535;4266.679999000087;4268.786560672016;4302.806373713191;4317.805698743564;10800.729981750363;10957.685077790717;10965.83551246732;11025.779484410266;11129.220954533266;10759.193060520493;10897.291676041032;10897.455127243606;11012.944935275433;11035.558577656804;7971.322437592869;8260.8347833043;8335.291500222549;8339.499630022217;8351.162744196266;6102.328743838086;6338.334474880071;6353.650549219838;6389.329120442287;6468.547201696025;10459.790804183722;10540.631077912236;10631.681049568526;10649.148068154374;10654.57381704723;10327.845082323622;10895.836562451728;10901.127909767358;10906.454677265947;10918.071963882841;7389.667464963982;7754.8449031017935;7981;8001.719939802079;8111.351091912751;9015.729528114167;9023;9110.271178305587;9110.772230694285;9117.08829117083;7856.45004849655;8094.433389662902;8124.796880078045;8188.14024527416;8236.50580965144;7716.382689384724;7833.1775163607;7858.921410786048;7966.442349035744;7987.920120798718;7287.744928927462;7318.926810732038;7391.78035624116;7400.185979542353;7413.66638501258;5204;5267.525922666979;5283.973580132075;5339.893202136013;5434.429384914685;5039.395272567315;5085.110105731408;5199;5243.89512209746;5245.6852588844795 14:25:57 INFO - PROCESS | 1383 | |4;prototype.html;317.07572426377095;322.74986885164;329.06381345073265;332.23917229544344;334.3847320929489;5159.277701121837;5178.974105129469;5294.523492885644;5373;5400.217928083738;5220.347456567929;5241.344831896013;5245.580353571715;5264.552513036387;5268.710220937847;4125.855595054174;4139.661091483317;4141.798878325282;4158.313878210091;4166.812493437803;3878.7672739635573;3962.5641179470235;4101.241270364992;4111.301078816608;4120.567340429257;4084.4077608746716;4126.649234815044;4127.504699436072;4127.938080928789;4143.854965076223;4095.7133000689964;4108.506979162505;4123.484564429446;4132.442120313779;4138.193052354792;3433.399155147864;3678.098865777875;3679.889603311905;3680.613535578779;3690.5571331440133;5143.871403214949;5156.355455568054;5176.171812510018;5179.870503237449;5183.766730497099;4650.697704649187;4672.13565490382;4672.322513235562;4675.579197872174;4680.859574212779;5320.17537281722;5446.2375267462585;5501.367342755554;5502.147167189092;5518.448155184489;3905.1408690088133;3916.1776027034357;3918.1380096378753;3923.7057220708275;3924.666403355718;5631.2960879890015;5651.802186923438;5660.405657405995;5689.065717656017;5704.344000439961;3968.0476685595827;4020.2562525932794;4031.9798401007806;4034.65705415043;4072.266991941479;4070.633642972147;4076.408920706481;4088.4276201331836;4093.7543747375253;4114.897127571835;4631.212693842055;4638.884027899262;4648.6745927785405;4665.9766701166955;4677.906441871143;5472.425395333433;5503.367112782082;5525.530329922001;5528.889422211533;5540.335159780771;1316.5128902306208;1389.9513517026855;1404.9578512644637;1412.639776856895;1416.6600015996087;855.5722138930535;878.8838175517185;889.822035592884;896.7085697148453;897.3090720145582;4683.929741053887;4694.568156712236;4734.337192792943;4776.188048031843;4789.185838407479;4954.801807927714;4975.4527002029745;5003.699778013258;5015;5026.667932997759;4378.8029538670835;4702.882427939329;4721.607125897868;4722.362481065081;4768.260919557474;2661.427793024509;2670.799690023256;2689.072270066824;2693;2704.8241864278757;5128.8461346159675;5141.665791723526;5156.4070131935305;5186.896262074737;5203.62104042429;4865.683730557503;5027.4721154279005;5050.823221187241;5058.64589478733;5062.734316420895;5120.257562653469;5127.4872512748425;5160.110338210614;5164.225366195025;5171.379434467888;4837.733924634038;4870.634702397264;4949.183384741477;4961.8511444656715;5036.244563315532;4741.861953131155;4946.727929963887;4959.735267506762;5068.721220332917;5078.441371449064;5146.76839542214;5177.637565370388;5218.6868787872845;5250.343707036621;5257.6056795739705;3716.1638631307737;3739.2334571412252;3745.307118183036;3746.6253374663406;3750.1937083527173;4922.556969872729;4975.253711943237;5093.719845408538;5104.770285337243;5117.464760571888;4872.488388719134;4875.6099512038245;4877.585405240452;4929.471863722114;4985.4266759323145;4845.6608037437045;4889.2910527972535;4893.388326459192;4920.237363208708;4956.77694503741;4640.559146881041;4662.906741865211;4769.594584460359;4791.113643975876;4826.541478559671;4924.507549245047;4934.753262336941;4936.333594964633;4947.925781113358;4962.627802914723;4803.831865884677;4852.611791056777;4896.755162241802;4904.043711476228;4917.655764096479 14:25:57 INFO - PROCESS | 1383 | |5;yui.html;746.7423738810111;769.6459628570857;783.2480818414322;784.5410434895585;795.8140182639322;13547.322633868303;14409.351579178936;14458.710825783479;14460.5661830145;14514.201718905457;13864.098833575836;14527.128372297642;14560.927195364035;14587.708245835078;14592.197429141419;658.772723410425;789.376392649806;791.8653828849109;795.9442839001272;796.016919104905;777.7588947426287;798.2097723253966;802.3541049455187;804.0431886055604;805.0741647105816;829.4235506323108;831.563429199672;834.7287131682198;835.3192148399036;835.757630287216;694.4687314204638;808.5472135604022;829.7096016394244;830.1698301698272;830.6552780596045;608.9634621922698;616.7964571691331;616.9938300616981;617.1853153836937;617.4443001298866;13610.183388996691;13643.590692279247;13680.52118175869;13686.52097176594;13688.726225475435;13138.343082845819;13451.394687239;13642.795358069638;13877.236751978637;13988.720225595533;11984.340861252627;12127.454264558117;12304.81542776859;12360.134790564663;12475.376231188495;3762.1159027628487;3765.1151979284846;3814.923701525982;3837.76973381598;3919.960800391988;77346.61326693387;79097.60451197822;79212;79731;80352.79470807943;9946.701598952042;10018.599256029824;11356.716082098013;11473.655790326304;11542.19204655666;17516.29934802619;17688.469345919642;17762;17888.28446862111;17896.194671239824;8186.304164145995;8622.568871556397;8636.866025980959;8730.519821409884;8755.518446485377;4707.83522576715;4791.281307803857;4842.612590992712;4875.098106850244;4960.404751429779;3552.609212986569;3658.579263384692;3668.981655091761;3787.034306251888;3799.5820459749407;702.9753958611526;717.4439809147952;720.5640587444625;720.5892641194469;729.2962291388835;638.4796390941368;640.0079876191977;731.4112139727464;762.9318953465082;763.1300317637897;9431.198348140348;9693.757656058655;9725.270604704534;9912.900870991341;10098.596056157818;650.768977013163;705.8835292176733;745.4669911013543;752.7478294771204;775.8991331126917;4695.272232803921;4896.5103489651465;4935.728534930613;4962.875928101754;4971.4282857471135;91556.54221728849;94846;96007;109663.45168274107;111841;59169;59269;65214.673926630065;79517.20482795214;95042;55768.88462230733;75312.62343688356;82645.58677206576;83992;85811.57094214488;9651.324407291422;9776.26677999167;9795.118439340493;9815.411075335503;9953.850692239477;51374;51621.22568161556;52558.47441525536;52636.21045684241;53616.73191634017;71282.28717712757;78086.6095669518;78139.18608139141;79330;79770;12553.435095420911;12606.49574017029;12917.741645166856;13001.674958125745;13748.93125534366;13630;13671.658208544866;13993.16041037541;14356.928215359274;15237.085774853544;13422.530211442552;13764;14110.039434604962;14116;14310.015414469748;12652.304123273309;12891.16207446513;12936.805947910978;13098.017648676197;13542.255175965023;13506.324683765968;13768.032717304015;13915.791263131263;13955.23246221427;14221.786673199704;11319.717007074889;12202.816957745463;12262.693432663898;12423.192492487959;12591.307478088793;11599.8260026098;12055.638330850088;12246.877531224574;12431.407861417718;12432.191907525981 14:25:57 INFO - PROCESS | 1383 | __end_tp_report 14:25:57 INFO - PROCESS | 1383 | __start_cc_report 14:25:57 INFO - PROCESS | 1383 | _x_x_mozilla_cycle_collect,881 14:25:57 INFO - PROCESS | 1383 | __end_cc_report 14:25:57 INFO - PROCESS | 1383 | __startTimestamp1467062757353__endTimestamp 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | ------- Summary: start ------- 14:25:57 INFO - PROCESS | 1383 | Number of tests: 6 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | [#0] dojo.html Cycles:165 Average:5399.21 Median:5594.41 stddev:629.47 (11.3%) stddev-sans-first:604.18 14:25:57 INFO - PROCESS | 1383 | Values: 3064.6 3086.0 3113.2 3145.5 3146.0 5284.1 5409.9 5440.6 5527.8 5575.2 5381.8 5447.3 5452.1 5488.1 5543.9 4794.4 4798.2 4806.1 4881.4 4923.9 4784.7 4988.9 4991.5 5000.4 5034.2 4856.7 4887.1 4895.4 4964.8 4984.2 4964.1 4966.0 4971.7 4976.1 4979.9 4884.9 4890.3 4937.4 4939.5 4948.4 5485.2 5485.7 5547.2 5551.1 5561.9 5596.1 5866.4 5879.4 5883.3 5885.6 5742.0 5828.2 5838.3 5910.1 5930.1 5423.6 5429.6 5468.3 5471.4 5471.8 5828.0 5915.3 5994.7 6007.3 6008.6 5751.0 5769.8 5781.7 5784.0 5785.5 5817.2 5819.9 5822.3 5863.3 5872.6 5576.4 5722.2 5769.9 5779.5 5788.2 5765.7 5835.2 5876.3 5879.7 5880.9 4404.2 4411.2 4414.1 4415.0 4450.0 3891.8 3925.3 3940.5 3958.7 3992.4 5413.6 5449.0 5493.8 5510.4 5525.5 5812.9 5852.7 5855.9 5858.2 5897.8 5351.3 5384.4 5467.9 5508.6 5520.7 4889.3 4945.2 4953.6 4957.6 4998.2 6038.6 6066.3 6071.9 6078.6 6084.5 5651.4 5753.9 5820.4 5851.3 5859.4 6056.0 6059.0 6078.1 6079.7 6113.0 5696.3 5740.8 5744.5 5752.2 5753.3 5635.2 5733.7 5768.3 5812.5 5844.7 5700.1 5706.2 5717.9 5735.8 5736.7 5677.8 5692.8 5694.4 5694.8 5695.1 5317.1 5488.9 5557.2 5588.7 5608.6 5726.3 5743.7 5769.9 5773.1 5778.9 5447.8 5512.6 5594.4 5604.7 5606.3 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | [#1] ext.html Cycles:180 Average:50779.46 Median:43452.78 stddev:90185.34 (207.5%) stddev-sans-first:90359.59 14:25:57 INFO - PROCESS | 1383 | Values: 586.9 612.7 620.8 629.6 640.6 42167.4 43769.0 43845.8 43854.1 47893.8 45086.8 45287.8 45361.0 45393.8 45421.5 8910.5 9130.8 9131.1 9140.9 9152.4 8975.1 9047.8 9069.8 9070.1 9072.9 9138.3 9181.2 9188.7 9189.2 9191.9 9081.3 9104.4 9117.7 9124.5 9124.8 9019.7 9086.9 9089.4 9092.6 9096.0 43262.4 43625.0 43708.8 43718.0 43718.6 3471.8 3612.4 3621.3 3633.3 3703.6 32437.0 32626.7 33439.7 33467.3 33622.0 4408.7 4443.3 4461.2 4479.4 4509.2 373268.0 376565.0 376968.0 376974.0 377404.0 58968.4 59064.0 59340.0 59412.0 59439.1 51366.7 52707.7 52849.5 54311.0 62581.0 14181.0 14381.3 14428.6 14429.9 14446.9 10076.2 11019.3 11097.7 11107.1 11109.0 16936.5 16994.2 17556.6 17805.9 17892.6 6031.0 6035.7 6052.4 6185.1 6303.6 901.4 952.6 1098.1 1098.5 1192.6 32026.5 32045.0 32124.5 32374.5 32517.7 1106.9 1112.2 1119.3 1132.6 1138.3 17029.0 17130.8 17287.7 17323.7 17367.1 152557.2 152837.0 153848.0 153941.0 154662.0 57307.1 58439.7 58490.0 58567.0 58724.4 1185.7 1230.0 1277.2 1308.8 1319.3 30239.8 32677.0 32722.7 32886.5 33192.8 49860.8 59452.4 61328.0 61821.0 66473.3 358987.0 360748.0 362757.0 510427.0 510976.0 38677.0 45702.8 45958.8 46236.6 47055.0 49425.5 50399.5 50988.7 51396.0 51619.7 42589.8 42790.4 43182.8 43501.6 44143.6 41276.4 41814.4 42411.0 42575.6 43404.0 42370.2 42717.6 42856.1 43403.1 43618.6 36606.3 37773.8 37912.8 38796.6 38800.8 28392.3 28621.7 28876.9 29058.0 29067.3 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | [#2] jquery.html Cycles:180 Average:28216.99 Median:2375.12 stddev:78295.75 (3296.5%) stddev-sans-first:78487.48 14:25:57 INFO - PROCESS | 1383 | Values: 372.2 381.9 394.3 399.1 399.1 18727.6 18768.2 19831.8 20421.6 20439.4 11994.6 12596.9 12685.4 12715.0 18694.9 5186.1 5313.4 5344.7 5348.0 5368.3 3817.7 4097.8 5304.4 6500.0 6614.3 6535.3 6540.2 6548.6 6572.9 6578.5 6270.9 6438.7 6453.7 6573.9 6580.0 3768.7 5353.9 5686.1 5690.7 5748.3 13544.7 13978.3 14054.6 14108.6 14157.2 2223.8 2328.1 2422.1 2732.7 2944.1 10004.5 10229.3 10262.0 10388.8 10935.6 1930.2 1958.2 1972.9 1993.3 2028.4 83435.0 84864.0 85010.6 85474.6 86329.6 10400.8 10691.0 10935.8 11069.6 11151.4 15172.4 15180.2 15252.3 15256.9 15257.2 7177.7 7255.9 7326.9 7406.6 7416.0 6708.2 6759.3 6818.2 6913.9 6936.7 4575.5 4692.8 4732.8 4870.9 4872.3 1865.8 1926.1 1938.5 1959.0 1960.3 872.9 958.3 989.6 1002.4 1077.9 18143.5 18206.2 18560.8 19242.0 19429.5 983.0 990.3 1029.6 1067.8 1101.3 10117.9 10159.2 10555.4 10693.8 10715.7 465673.0 471616.0 475261.0 475398.0 476942.0 92543.1 93865.0 94638.0 95589.0 96242.5 1001.3 1014.2 1037.4 1043.0 1043.3 16996.7 17499.6 17730.6 17880.3 17955.5 30206.7 37948.6 38399.0 38752.0 43160.8 58858.4 59014.7 60600.0 60603.0 60807.0 8508.8 8559.4 8690.6 8699.0 8937.3 12164.8 12797.3 12839.8 13182.4 13886.2 11766.4 11772.3 11772.5 11792.8 12271.9 10966.7 11646.1 11974.6 12013.9 12280.6 11860.6 11956.3 12091.4 12193.4 12197.3 8580.7 8731.6 8816.0 8827.2 8927.6 8888.8 11674.8 11797.9 11941.0 11984.0 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | [#3] mootools.html Cycles:180 Average:5991.28 Median:479.46 stddev:3465.40 (722.8%) stddev-sans-first:3456.31 14:25:57 INFO - PROCESS | 1383 | Values: 1187.7 1287.4 1294.8 1430.9 1448.1 8611.4 9001.0 9012.1 9317.7 9322.8 9030.5 9211.3 9213.1 9228.2 9248.3 2084.5 2320.9 2359.7 2407.0 2410.5 2431.8 2436.9 2439.8 2441.3 2443.0 2387.3 2401.1 2411.6 2414.5 2416.4 2017.5 2271.8 2352.3 2361.9 2362.3 2331.3 2335.9 2353.0 2358.8 2360.7 9061.4 9201.4 9209.3 9210.9 9214.8 5309.6 5462.3 5470.0 5476.6 5478.2 3353.6 3453.9 3517.8 3528.8 3542.5 1141.4 1160.5 1192.6 1207.6 1216.1 13066.8 13074.5 13081.6 13088.6 13104.8 4751.5 4751.6 4873.4 4884.0 4884.6 10676.8 10705.1 10740.0 10757.0 10758.3 5437.6 5501.2 5512.6 5515.3 5520.3 4368.2 4924.1 5068.8 5071.5 5154.9 4041.4 4061.2 4099.2 4106.8 4125.8 1259.4 1290.7 1301.7 1309.9 1327.6 961.3 974.8 981.8 989.7 995.1 6276.5 6280.4 6286.6 6321.5 6336.9 467.7 468.4 478.4 480.5 487.2 4213.2 4266.7 4268.8 4302.8 4317.8 10800.7 10957.7 10965.8 11025.8 11129.2 10759.2 10897.3 10897.5 11012.9 11035.6 7971.3 8260.8 8335.3 8339.5 8351.2 6102.3 6338.3 6353.7 6389.3 6468.5 10459.8 10540.6 10631.7 10649.1 10654.6 10327.8 10895.8 10901.1 10906.5 10918.1 7389.7 7754.8 7981.0 8001.7 8111.4 9015.7 9023.0 9110.3 9110.8 9117.1 7856.5 8094.4 8124.8 8188.1 8236.5 7716.4 7833.2 7858.9 7966.4 7987.9 7287.7 7318.9 7391.8 7400.2 7413.7 5204.0 5267.5 5284.0 5339.9 5434.4 5039.4 5085.1 5199.0 5243.9 5245.7 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | [#4] prototype.html Cycles:180 Average:4360.85 Median:4868.16 stddev:1227.25 (25.2%) stddev-sans-first:1192.57 14:25:57 INFO - PROCESS | 1383 | Values: 317.1 322.7 329.1 332.2 334.4 5159.3 5179.0 5294.5 5373.0 5400.2 5220.3 5241.3 5245.6 5264.6 5268.7 4125.9 4139.7 4141.8 4158.3 4166.8 3878.8 3962.6 4101.2 4111.3 4120.6 4084.4 4126.6 4127.5 4127.9 4143.9 4095.7 4108.5 4123.5 4132.4 4138.2 3433.4 3678.1 3679.9 3680.6 3690.6 5143.9 5156.4 5176.2 5179.9 5183.8 4650.7 4672.1 4672.3 4675.6 4680.9 5320.2 5446.2 5501.4 5502.1 5518.4 3905.1 3916.2 3918.1 3923.7 3924.7 5631.3 5651.8 5660.4 5689.1 5704.3 3968.0 4020.3 4032.0 4034.7 4072.3 4070.6 4076.4 4088.4 4093.8 4114.9 4631.2 4638.9 4648.7 4666.0 4677.9 5472.4 5503.4 5525.5 5528.9 5540.3 1316.5 1390.0 1405.0 1412.6 1416.7 855.6 878.9 889.8 896.7 897.3 4683.9 4694.6 4734.3 4776.2 4789.2 4954.8 4975.5 5003.7 5015.0 5026.7 4378.8 4702.9 4721.6 4722.4 4768.3 2661.4 2670.8 2689.1 2693.0 2704.8 5128.8 5141.7 5156.4 5186.9 5203.6 4865.7 5027.5 5050.8 5058.6 5062.7 5120.3 5127.5 5160.1 5164.2 5171.4 4837.7 4870.6 4949.2 4961.9 5036.2 4741.9 4946.7 4959.7 5068.7 5078.4 5146.8 5177.6 5218.7 5250.3 5257.6 3716.2 3739.2 3745.3 3746.6 3750.2 4922.6 4975.3 5093.7 5104.8 5117.5 4872.5 4875.6 4877.6 4929.5 4985.4 4845.7 4889.3 4893.4 4920.2 4956.8 4640.6 4662.9 4769.6 4791.1 4826.5 4924.5 4934.8 4936.3 4947.9 4962.6 4803.8 4852.6 4896.8 4904.0 4917.7 14:25:57 INFO - PROCESS | 1383 | 14:25:57 INFO - PROCESS | 1383 | [#5] yui.html Cycles:180 Average:19423.68 Median:4967.15 stddev:26901.01 (541.6%) stddev-sans-first:26939.92 14:25:57 INFO - PROCESS | 1383 | Values: 746.7 769.6 783.2 784.5 795.8 13547.3 14409.4 14458.7 14460.6 14514.2 13864.1 14527.1 14560.9 14587.7 14592.2 658.8 789.4 791.9 795.9 796.0 777.8 798.2 802.4 804.0 805.1 829.4 831.6 834.7 835.3 835.8 694.5 808.5 829.7 830.2 830.7 609.0 616.8 617.0 617.2 617.4 13610.2 13643.6 13680.5 13686.5 13688.7 13138.3 13451.4 13642.8 13877.2 13988.7 11984.3 12127.5 12304.8 12360.1 12475.4 3762.1 3765.1 3814.9 3837.8 3920.0 77346.6 79097.6 79212.0 79731.0 80352.8 9946.7 10018.6 11356.7 11473.7 11542.2 17516.3 17688.5 17762.0 17888.3 17896.2 8186.3 8622.6 8636.9 8730.5 8755.5 4707.8 4791.3 4842.6 4875.1 4960.4 3552.6 3658.6 3669.0 3787.0 3799.6 703.0 717.4 720.6 720.6 729.3 638.5 640.0 731.4 762.9 763.1 9431.2 9693.8 9725.3 9912.9 10098.6 650.8 705.9 745.5 752.7 775.9 4695.3 4896.5 4935.7 4962.9 4971.4 91556.5 94846.0 96007.0 109663.5 111841.0 59169.0 59269.0 65214.7 79517.2 95042.0 55768.9 75312.6 82645.6 83992.0 85811.6 9651.3 9776.3 9795.1 9815.4 9953.9 51374.0 51621.2 52558.5 52636.2 53616.7 71282.3 78086.6 78139.2 79330.0 79770.0 12553.4 12606.5 12917.7 13001.7 13748.9 13630.0 13671.7 13993.2 14356.9 15237.1 13422.5 13764.0 14110.0 14116.0 14310.0 12652.3 12891.2 12936.8 13098.0 13542.3 13506.3 13768.0 13915.8 13955.2 14221.8 11319.7 12202.8 12262.7 12423.2 12591.3 11599.8 12055.6 12246.9 12431.4 12432.2 14:25:57 INFO - PROCESS | 1383 | -------- Summary: end -------- 14:25:57 INFO - PROCESS | 1383 | 14:25:58 INFO - TEST-INFO | 1383: exit 0 14:25:58 INFO - TEST-OK | dromaeo_css | took 1113952ms 14:25:58 INFO - TEST-START | kraken 14:25:58 INFO - operating with platform_type : linux_ 14:25:58 INFO - Initialising browser for kraken test... 14:25:58 INFO - TEST-INFO | started process 1574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZ1kBg/profile http://localhost:36091/getInfo.html) 14:26:02 INFO - PROCESS | 1574 | __metrics Screen width/height:1600/1200 14:26:02 INFO - PROCESS | 1574 | colorDepth:24 14:26:02 INFO - PROCESS | 1574 | Browser inner width/height: 1024/697 14:26:02 INFO - PROCESS | 1574 | __metrics 14:26:03 INFO - PROCESS | 1574 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:26:04 INFO - TEST-INFO | 1574: exit 0 14:26:04 INFO - Browser initialized. 14:26:04 INFO - Running cycle 1/1 for kraken test... 14:26:04 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZ1kBg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:16 INFO - PROCESS | 1655 | RSS: Main: 174555136 14:26:16 INFO - PROCESS | 1655 | 14:26:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36091/tests/kraken/driver.html?testName=audio-beat-detection) 14:26:19 INFO - PROCESS | 1655 | RSS: Main: 232198144 14:26:19 INFO - PROCESS | 1655 | 14:26:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36091/tests/kraken/driver.html?testName=audio-dft) 14:26:22 INFO - PROCESS | 1655 | RSS: Main: 219975680 14:26:22 INFO - PROCESS | 1655 | 14:26:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36091/tests/kraken/driver.html?testName=audio-fft) 14:26:26 INFO - PROCESS | 1655 | RSS: Main: 286629888 14:26:26 INFO - PROCESS | 1655 | 14:26:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36091/tests/kraken/driver.html?testName=audio-oscillator) 14:26:29 INFO - PROCESS | 1655 | RSS: Main: 197238784 14:26:29 INFO - PROCESS | 1655 | 14:26:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36091/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:26:32 INFO - PROCESS | 1655 | RSS: Main: 201641984 14:26:32 INFO - PROCESS | 1655 | 14:26:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36091/tests/kraken/driver.html?testName=imaging-darkroom) 14:26:35 INFO - PROCESS | 1655 | RSS: Main: 237543424 14:26:35 INFO - PROCESS | 1655 | 14:26:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36091/tests/kraken/driver.html?testName=imaging-desaturate) 14:26:39 INFO - PROCESS | 1655 | RSS: Main: 237494272 14:26:39 INFO - PROCESS | 1655 | 14:26:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36091/tests/kraken/driver.html?testName=json-parse-financial) 14:26:43 INFO - PROCESS | 1655 | RSS: Main: 237670400 14:26:43 INFO - PROCESS | 1655 | 14:26:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36091/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:26:45 INFO - PROCESS | 1655 | RSS: Main: 224489472 14:26:45 INFO - PROCESS | 1655 | 14:26:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:26:47 INFO - PROCESS | 1655 | RSS: Main: 227868672 14:26:47 INFO - PROCESS | 1655 | 14:26:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:26:50 INFO - PROCESS | 1655 | RSS: Main: 236408832 14:26:50 INFO - PROCESS | 1655 | 14:26:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:26:53 INFO - PROCESS | 1655 | RSS: Main: 239120384 14:26:53 INFO - PROCESS | 1655 | 14:26:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:26:56 INFO - PROCESS | 1655 | RSS: Main: 239321088 14:26:56 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:36091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36091/tests/kraken/driver.html?testName=ai-astar) 14:26:58 INFO - PROCESS | 1655 | RSS: Main: 281964544 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | __start_tp_report 14:26:58 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 14:26:58 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 14:26:58 INFO - PROCESS | 1655 | |i|pagename|runs| 14:26:58 INFO - PROCESS | 1655 | |0;ai-astar;100;110;110;110;112;111;111;109;111;109 14:26:58 INFO - PROCESS | 1655 | |1;audio-beat-detection;153;125;126;194;126;125;162;125;178;133 14:26:58 INFO - PROCESS | 1655 | |2;audio-dft;160;162;160;158;159;162;161;160;162;162 14:26:58 INFO - PROCESS | 1655 | |3;audio-fft;74;74;75;129;75;77;75;76;101;75 14:26:58 INFO - PROCESS | 1655 | |4;audio-oscillator;84;84;117;84;84;85;120;85;84;133 14:26:58 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;117;116;116;116;117;116;116;117;117;116 14:26:58 INFO - PROCESS | 1655 | |6;imaging-darkroom;146;146;146;145;146;146;147;148;146;146 14:26:58 INFO - PROCESS | 1655 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 14:26:58 INFO - PROCESS | 1655 | |8;json-parse-financial;53;53;54;53;54;54;58;54;54;53 14:26:58 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;63;63;63;63;64;63;62;63;63;64 14:26:58 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;79;79;85;79;80;86;80;81;101;83 14:26:58 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;131;123;124;121;131;132;129;119;142;127 14:26:58 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;164;165;158;157;157;163;164;159;158;158 14:26:58 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;64;63;63;63;62;63;63;65;64;62 14:26:58 INFO - PROCESS | 1655 | __end_tp_report 14:26:58 INFO - PROCESS | 1655 | __start_cc_report 14:26:58 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1607 14:26:58 INFO - PROCESS | 1655 | __end_cc_report 14:26:58 INFO - PROCESS | 1655 | __startTimestamp1467062818748__endTimestamp 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | ------- Summary: start ------- 14:26:58 INFO - PROCESS | 1655 | Number of tests: 14 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.50 stddev:3.40 (3.1%) stddev-sans-first:1.00 14:26:58 INFO - PROCESS | 1655 | Values: 100.0 110.0 110.0 110.0 112.0 111.0 111.0 109.0 111.0 109.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:143.00 stddev:25.62 (17.9%) stddev-sans-first:27.00 14:26:58 INFO - PROCESS | 1655 | Values: 153.0 125.0 126.0 194.0 126.0 125.0 162.0 125.0 178.0 133.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.50 stddev:1.43 (0.9%) stddev-sans-first:1.50 14:26:58 INFO - PROCESS | 1655 | Values: 160.0 162.0 160.0 158.0 159.0 162.0 161.0 160.0 162.0 162.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.00 stddev:18.08 (24.1%) stddev-sans-first:18.88 14:26:58 INFO - PROCESS | 1655 | Values: 74.0 74.0 75.0 129.0 75.0 77.0 75.0 76.0 101.0 75.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:84.00 stddev:19.29 (23.0%) stddev-sans-first:19.96 14:26:58 INFO - PROCESS | 1655 | Values: 84.0 84.0 117.0 84.0 84.0 85.0 120.0 85.0 84.0 133.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 14:26:58 INFO - PROCESS | 1655 | Values: 117.0 116.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 116.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 14:26:58 INFO - PROCESS | 1655 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 147.0 148.0 146.0 146.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 14:26:58 INFO - PROCESS | 1655 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.49 (2.8%) stddev-sans-first:1.54 14:26:58 INFO - PROCESS | 1655 | Values: 53.0 53.0 54.0 53.0 54.0 54.0 58.0 54.0 54.0 53.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 14:26:58 INFO - PROCESS | 1655 | Values: 63.0 63.0 63.0 63.0 64.0 63.0 62.0 63.0 63.0 64.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:80.50 stddev:6.72 (8.3%) stddev-sans-first:6.94 14:26:58 INFO - PROCESS | 1655 | Values: 79.0 79.0 85.0 79.0 80.0 86.0 80.0 81.0 101.0 83.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:130.00 stddev:6.69 (5.1%) stddev-sans-first:7.00 14:26:58 INFO - PROCESS | 1655 | Values: 131.0 123.0 124.0 121.0 131.0 132.0 129.0 119.0 142.0 127.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:161.00 stddev:3.27 (2.0%) stddev-sans-first:3.18 14:26:58 INFO - PROCESS | 1655 | Values: 164.0 165.0 158.0 157.0 157.0 163.0 164.0 159.0 158.0 158.0 14:26:58 INFO - PROCESS | 1655 | 14:26:58 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 14:26:58 INFO - PROCESS | 1655 | Values: 64.0 63.0 63.0 63.0 62.0 63.0 63.0 65.0 64.0 62.0 14:26:58 INFO - PROCESS | 1655 | -------- Summary: end -------- 14:26:58 INFO - PROCESS | 1655 | 14:26:59 INFO - TEST-INFO | 1655: exit 0 14:26:59 INFO - TEST-OK | kraken | took 61652ms 14:26:59 INFO - SUITE-END | took 1175s 14:26:59 INFO - Completed test suite (00:19:35) 14:26:59 INFO - javascript benchmark 14:26:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3064.6322441307047, 3085.95371069434, 3113.2216945763557, 3145.4809956357203, 3145.952810707838, 5284.101702710539, 5409.864753381157, 5440.646357986728, 5527.806526771564, 5575.247341608877, 5381.838544843661, 5447.264619276397, 5452.073147564914, 5488.067028605136, 5543.916841247385, 4794.352762377078, 4798.160321943664, 4806.134895718769, 4881.389826271716, 4923.852284431455, 4784.688995215317, 4988.850334489953, 4991.500849914997, 5000.424951130612, 5034.169362055275, 4856.660033797635, 4887.14474966883, 4895.412550493964, 4964.826231081913, 4984.22744474607, 4964.05682920244, 4965.975170124162, 4971.676841005323, 4976.129177393942, 4979.850604481871, 4884.853454396374, 4890.266460030988, 4937.407511098655, 4939.481354457766, 4948.38145231846, 5485.204645326446, 5485.725713714338, 5547.223388725582, 5551.056320425538, 5561.860953476155, 5596.104623260299, 5866.384029676908, 5879.353271140171, 5883.323417806964, 5885.588008839383, 5742.0238559445, 5828.2131912191735, 5838.2702162229725, 5910.1430292607765, 5930.1401296812155, 5423.647462914937, 5429.56563474925, 5468.343798744176, 5471.398146203915, 5471.835844924659, 5828.0, 5915.290165180119, 5994.700264986734, 6007.309159446632, 6008.579399442085, 5750.971245143747, 5769.769209231584, 5781.739821708034, 5784.0, 5785.479306862402, 5817.185594016841, 5819.94180058194, 5822.33043200029, 5863.296404431496, 5872.618279811799, 5576.442355764391, 5722.198892155102, 5769.884602308014, 5779.479846813807, 5788.18965344852, 5765.682887441231, 5835.241418615553, 5876.265466816648, 5879.735411906475, 5880.852978675482, 4404.185225733249, 4411.22803509391, 4414.1171765647, 4414.977925110354, 4449.9777501112285, 3891.7664940103687, 3925.2542017016676, 3940.5468371137113, 3958.7228893977144, 3992.4210989406374, 5413.621046526784, 5449.0, 5493.7802487900035, 5510.448955104457, 5525.475079867406, 5812.88374232513, 5852.678102704393, 5855.882882342329, 5858.150568167592, 5897.793577224862, 5351.277577526984, 5384.380796208407, 5467.917981230285, 5508.641938274, 5520.66875987434, 4889.315495830587, 4945.184044632667, 4953.6037117030555, 4957.603391728653, 4998.225275082368, 6038.607490513103, 6066.302375226817, 6071.908921366183, 6078.6048906820915, 6084.543659225487, 5651.406602306699, 5753.942460575341, 5820.388859169896, 5851.268591426072, 5859.414058594277, 6055.969720151547, 6059.0, 6078.057901025348, 6079.726412311368, 6113.0, 5696.259486266758, 5740.827775166752, 5744.454276843693, 5752.194692742935, 5753.338366087786, 5635.211070450058, 5733.655980641175, 5768.3078030634915, 5812.505936995233, 5844.707764611838, 5700.087985922232, 5706.172604972338, 5717.885642287048, 5735.7705691773535, 5736.68448235351, 5677.77288908439, 5692.800751973661, 5694.373618901835, 5694.829155125353, 5695.117256825199, 5317.149256119157, 5488.945110549003, 5557.221988921635, 5588.692621905834, 5608.579356548194, 5726.255586773693, 5743.712814359349, 5769.913451298318, 5773.1051686988585, 5778.8844223114465, 5447.754851031792, 5512.641678290898, 5594.41258667834, 5604.6917419542315, 5606.299212598426], "value": 5355.567424640666, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.8591538030871, 612.672220362106, 620.813755873238, 629.6253729031225, 640.6348381422589, 42167.36748948768, 43769.0, 43845.78077109618, 43854.12291754163, 47893.760531197295, 45086.774566127206, 45287.77356113223, 45361.0, 45393.7730311348, 45421.54578454215, 8910.46537207768, 9130.817383652324, 9131.13254240846, 9140.908590914072, 9152.359334846564, 8975.057618950013, 9047.773805654846, 9069.818603627924, 9070.092990700943, 9072.909270907272, 9138.314626403013, 9181.173694367506, 9188.724338269829, 9189.218916392114, 9191.862122068174, 9081.318901082412, 9104.408213466104, 9117.726468205965, 9124.498152601604, 9124.817503649956, 9019.684311049083, 9086.909130908673, 9089.363744537884, 9092.63629454821, 9096.044915283866, 43262.35106473405, 43625.0, 43708.78145609283, 43718.0, 43718.56281437177, 3471.8437670304897, 3612.4039533476944, 3621.311950729353, 3633.2733453309443, 3703.574088979775, 32437.0, 32626.67373326284, 33439.6656033439, 33467.330653386794, 33622.0, 4408.7354758714255, 4443.333499974998, 4461.21928662486, 4479.417675702138, 4509.210888094571, 373268.0, 376565.0, 376968.0, 376974.0, 377404.0, 58968.41031589629, 59064.0, 59340.0, 59412.0, 59439.10841337383, 51366.74316628393, 52707.736461318214, 52849.471505284455, 54311.0, 62581.0, 14181.0, 14381.28093595316, 14428.639284017983, 14429.855701442852, 14446.855531444551, 10076.244281678903, 11019.283746556448, 11097.667069987914, 11107.111431085494, 11109.0, 16936.491905242863, 16994.150292485574, 17556.648867022588, 17805.910970445064, 17892.642147156985, 6030.969845150834, 6035.66803825785, 6052.425019623129, 6185.103160041769, 6303.590266632746, 901.364538000708, 952.5570609666399, 1098.050186588611, 1098.5001824169983, 1192.5825960913785, 32026.519602205986, 32045.0, 32124.518132228037, 32374.514382284284, 32517.67482325194, 1106.8948449897407, 1112.249231768553, 1119.2668801934747, 1132.6318946342308, 1138.3113216504062, 17029.0, 17130.828691713174, 17287.65424691499, 17323.653526929644, 17367.13164341778, 152557.23721381323, 152837.0, 153848.0, 153941.0, 154662.0, 57307.13464326767, 58439.70780146157, 58490.0, 58567.0, 58724.41275587275, 1185.6976471000012, 1230.0036970054284, 1277.2464246094662, 1308.7971364438527, 1319.346923272986, 30239.848800755855, 32677.0, 32722.672773271963, 32886.50670239996, 33192.83403582966, 49860.75069624629, 59452.40547594642, 61328.0, 61821.0, 66473.33526664865, 358987.0, 360748.0, 362757.0, 510427.0, 510976.0, 38677.0, 45702.77148614368, 45958.77020614876, 46236.57514199885, 47055.0, 49425.505744943526, 50399.49600503948, 50988.74505627448, 51396.0, 51619.741901290254, 42589.78705106579, 42790.35814462848, 43182.784086080625, 43501.56498434975, 44143.55856441523, 41276.38085428661, 41814.372784408864, 42411.0, 42575.5742442584, 43404.0, 42370.15259694727, 42717.57282427136, 42856.14287714166, 43403.13193736044, 43618.56381436145, 36606.267874642894, 37773.811130945265, 37912.81043594764, 38796.61203388043, 38800.80599596984, 28392.290192745346, 28621.71378286274, 28876.855615721786, 29058.0, 29067.273318167216], "value": 19745.00334467861, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.17563097738514, 381.8682554518692, 394.3257030477883, 399.11595815269175, 399.1418450331788, 18727.625447491042, 18768.155433005515, 19831.801681983176, 20421.591568168627, 20439.386818395436, 11994.640160795168, 12596.937015314934, 12685.429155687993, 12715.0, 18694.906525467355, 5186.0665080285435, 5313.38896026958, 5344.706041167734, 5348.0, 5368.3021207243, 3817.656410923016, 4097.774622395782, 5304.389995150549, 6500.024996250553, 6614.305497922721, 6535.313792051814, 6540.182477190351, 6548.607083574977, 6572.934270657304, 6578.506612004095, 6270.937290627081, 6438.6780660967, 6453.677316134174, 6573.901391479108, 6580.0, 3768.679662228714, 5353.9196912046355, 5686.0617998030275, 5690.687012214326, 5748.338941021794, 13544.729105417935, 13978.301084945711, 14054.648633784238, 14108.647283817883, 14157.150570965776, 2223.788740069691, 2328.1385887221836, 2422.1038215860067, 2732.6584176977876, 2944.07261712562, 10004.549795259158, 10229.335093218991, 10262.0, 10388.844167337496, 10935.617253396173, 1930.1989674285296, 1958.1677786940493, 1972.8816271023784, 1993.3123072540095, 2028.3712049264775, 83435.0, 84864.0, 85010.57494712487, 85474.5726271371, 86329.56835215783, 10400.791984160425, 10691.0, 10935.781284374269, 11069.612563560398, 11151.442427878574, 15172.393104275927, 15180.240987950558, 15252.313645885963, 15256.923715381352, 15257.237138143048, 7177.6770045348085, 7255.927440725631, 7326.926730732731, 7406.592637404887, 7416.0, 6708.19501659804, 6759.256481787, 6818.18181818185, 6913.930860691329, 6936.687849046805, 4575.542445755398, 4692.7653617319, 4732.787024583902, 4870.853874383774, 4872.293517439952, 1865.7761068671575, 1926.088474390299, 1938.505681051351, 1958.9902050489657, 1960.2942940541393, 872.9301655867515, 958.3004406783087, 989.6437282578266, 1002.3711468863097, 1077.9245452818384, 18143.546411339823, 18206.18072186755, 18560.81439185618, 19242.014291519143, 19429.47416835802, 982.9557669904872, 990.3364745620452, 1029.6190409548515, 1067.813132701774, 1101.2566517600587, 10117.949410252902, 10159.238057145743, 10555.419451930064, 10693.83959240612, 10715.678529644123, 465673.0, 471616.0, 475261.0, 475398.0, 476942.0, 92543.07456925344, 93865.0, 94638.0, 95589.0, 96242.51878740561, 1001.2990906365574, 1014.1734486393566, 1037.3827572594446, 1042.9843552346722, 1043.2853495355555, 16996.745048824032, 17499.56251093733, 17730.5567360817, 17880.28478860831, 17955.461336159937, 30206.697933021267, 37948.62051379561, 38399.0, 38752.0, 43160.78419607882, 58858.41141588701, 59014.70492647509, 60600.0, 60603.0, 60807.0, 8508.829823403621, 8559.400841941253, 8690.608922598625, 8699.0, 8937.329700272636, 12164.75670486603, 12797.29614871191, 12839.807402889151, 13182.406791694588, 13886.166829990232, 11766.352850593299, 11772.293662380285, 11772.52909883595, 11792.76414471723, 12271.938640306742, 10966.670999870017, 11646.126540509325, 11974.64076077719, 12013.879861201276, 12280.631581052583, 11860.644180674248, 11956.282623042645, 12091.39543022863, 12193.390330483619, 12197.329146897006, 8580.65677372898, 8731.563421828756, 8815.955920220358, 8827.24968377695, 8927.553622318988, 8888.777780555538, 11674.76650467003, 11797.935361311907, 11941.0, 11984.0], "value": 8980.525626135743, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1187.6555798818338, 1287.427094942751, 1294.8316718826552, 1430.8998370114093, 1448.0659974316566, 8611.354148438868, 9001.0, 9012.053734357889, 9317.673881414152, 9322.766930826732, 9030.548472576362, 9211.263098952068, 9213.078692130784, 9228.215601673866, 9248.26013918885, 2084.5309805293764, 2320.872352020638, 2359.6578496118095, 2407.0492155598527, 2410.517896420714, 2431.8419302745265, 2436.9390765230833, 2439.780419762221, 2441.2920253126663, 2443.035001174535, 2387.331547166796, 2401.0635852017726, 2411.638254261857, 2414.468816860288, 2416.4200591858003, 2017.5359667276534, 2271.7501074881748, 2352.2943117064983, 2361.929142125739, 2362.29131260622, 2331.2889568681544, 2335.9065637374483, 2353.0, 2358.7877091061887, 2360.7285162206394, 9061.41100828444, 9201.447913125166, 9209.263258939336, 9210.907890921071, 9214.815703685888, 5309.601779866525, 5462.289902312674, 5470.015397228497, 5476.616636835423, 5478.205660179292, 3353.6143343515564, 3453.913652158691, 3517.7889326640484, 3528.8059156746367, 3542.468629705539, 1141.3836528274721, 1160.5299853559322, 1192.6004788395899, 1207.6075275535486, 1216.1122380662166, 13066.803997940038, 13074.542391016364, 13081.607551773463, 13088.607341779762, 13104.803427948493, 4751.52484751529, 4751.641078766896, 4873.439554451284, 4884.0, 4884.560389564886, 10676.786464270672, 10705.090067344207, 10740.033396994151, 10757.031867131996, 10758.300710453796, 5437.564994800407, 5501.202325662756, 5512.5589952803675, 5515.338159420815, 5520.309961254843, 4368.235558777201, 4924.113659541317, 5068.847934561969, 5071.46749591295, 5154.92267615986, 4041.373587094005, 4061.194730728321, 4099.200655872136, 4106.774127422961, 4125.814338354781, 1259.3640211693037, 1290.6708789258678, 1301.7201301720177, 1309.9476020959055, 1327.621627836062, 961.3126614470686, 974.8196583632051, 981.8183636027358, 989.7278248481725, 995.1392045880261, 6276.529260305495, 6280.434760871543, 6286.591371560834, 6321.462675672619, 6336.89104406727, 467.73339196657577, 468.42149944818124, 478.42349968288823, 480.49787971569793, 487.16937621355504, 4213.1994920965535, 4266.679999000087, 4268.786560672016, 4302.806373713191, 4317.805698743564, 10800.729981750363, 10957.685077790717, 10965.83551246732, 11025.779484410266, 11129.220954533266, 10759.193060520493, 10897.291676041032, 10897.455127243606, 11012.944935275433, 11035.558577656804, 7971.322437592869, 8260.8347833043, 8335.291500222549, 8339.499630022217, 8351.162744196266, 6102.328743838086, 6338.334474880071, 6353.650549219838, 6389.329120442287, 6468.547201696025, 10459.790804183722, 10540.631077912236, 10631.681049568526, 10649.148068154374, 10654.57381704723, 10327.845082323622, 10895.836562451728, 10901.127909767358, 10906.454677265947, 10918.071963882841, 7389.667464963982, 7754.8449031017935, 7981.0, 8001.719939802079, 8111.351091912751, 9015.729528114167, 9023.0, 9110.271178305587, 9110.772230694285, 9117.08829117083, 7856.45004849655, 8094.433389662902, 8124.796880078045, 8188.14024527416, 8236.50580965144, 7716.382689384724, 7833.1775163607, 7858.921410786048, 7966.442349035744, 7987.920120798718, 7287.744928927462, 7318.926810732038, 7391.78035624116, 7400.185979542353, 7413.66638501258, 5204.0, 5267.525922666979, 5283.973580132075, 5339.893202136013, 5434.429384914685, 5039.395272567315, 5085.110105731408, 5199.0, 5243.89512209746, 5245.6852588844795], "value": 4678.142322924102, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.07572426377095, 322.74986885164, 329.06381345073265, 332.23917229544344, 334.3847320929489, 5159.277701121837, 5178.974105129469, 5294.523492885644, 5373.0, 5400.217928083738, 5220.347456567929, 5241.344831896013, 5245.580353571715, 5264.552513036387, 5268.710220937847, 4125.855595054174, 4139.661091483317, 4141.798878325282, 4158.313878210091, 4166.812493437803, 3878.7672739635573, 3962.5641179470235, 4101.241270364992, 4111.301078816608, 4120.567340429257, 4084.4077608746716, 4126.649234815044, 4127.504699436072, 4127.938080928789, 4143.854965076223, 4095.7133000689964, 4108.506979162505, 4123.484564429446, 4132.442120313779, 4138.193052354792, 3433.399155147864, 3678.098865777875, 3679.889603311905, 3680.613535578779, 3690.5571331440133, 5143.871403214949, 5156.355455568054, 5176.171812510018, 5179.870503237449, 5183.766730497099, 4650.697704649187, 4672.13565490382, 4672.322513235562, 4675.579197872174, 4680.859574212779, 5320.17537281722, 5446.2375267462585, 5501.367342755554, 5502.147167189092, 5518.448155184489, 3905.1408690088133, 3916.1776027034357, 3918.1380096378753, 3923.7057220708275, 3924.666403355718, 5631.2960879890015, 5651.802186923438, 5660.405657405995, 5689.065717656017, 5704.344000439961, 3968.0476685595827, 4020.2562525932794, 4031.9798401007806, 4034.65705415043, 4072.266991941479, 4070.633642972147, 4076.408920706481, 4088.4276201331836, 4093.7543747375253, 4114.897127571835, 4631.212693842055, 4638.884027899262, 4648.6745927785405, 4665.9766701166955, 4677.906441871143, 5472.425395333433, 5503.367112782082, 5525.530329922001, 5528.889422211533, 5540.335159780771, 1316.5128902306208, 1389.9513517026855, 1404.9578512644637, 1412.639776856895, 1416.6600015996087, 855.5722138930535, 878.8838175517185, 889.822035592884, 896.7085697148453, 897.3090720145582, 4683.929741053887, 4694.568156712236, 4734.337192792943, 4776.188048031843, 4789.185838407479, 4954.801807927714, 4975.4527002029745, 5003.699778013258, 5015.0, 5026.667932997759, 4378.8029538670835, 4702.882427939329, 4721.607125897868, 4722.362481065081, 4768.260919557474, 2661.427793024509, 2670.799690023256, 2689.072270066824, 2693.0, 2704.8241864278757, 5128.8461346159675, 5141.665791723526, 5156.4070131935305, 5186.896262074737, 5203.62104042429, 4865.683730557503, 5027.4721154279005, 5050.823221187241, 5058.64589478733, 5062.734316420895, 5120.257562653469, 5127.4872512748425, 5160.110338210614, 5164.225366195025, 5171.379434467888, 4837.733924634038, 4870.634702397264, 4949.183384741477, 4961.8511444656715, 5036.244563315532, 4741.861953131155, 4946.727929963887, 4959.735267506762, 5068.721220332917, 5078.441371449064, 5146.76839542214, 5177.637565370388, 5218.6868787872845, 5250.343707036621, 5257.6056795739705, 3716.1638631307737, 3739.2334571412252, 3745.307118183036, 3746.6253374663406, 3750.1937083527173, 4922.556969872729, 4975.253711943237, 5093.719845408538, 5104.770285337243, 5117.464760571888, 4872.488388719134, 4875.6099512038245, 4877.585405240452, 4929.471863722114, 4985.4266759323145, 4845.6608037437045, 4889.2910527972535, 4893.388326459192, 4920.237363208708, 4956.77694503741, 4640.559146881041, 4662.906741865211, 4769.594584460359, 4791.113643975876, 4826.541478559671, 4924.507549245047, 4934.753262336941, 4936.333594964633, 4947.925781113358, 4962.627802914723, 4803.831865884677, 4852.611791056777, 4896.755162241802, 4904.043711476228, 4917.655764096479], "value": 3972.886048707426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.7423738810111, 769.6459628570857, 783.2480818414322, 784.5410434895585, 795.8140182639322, 13547.322633868303, 14409.351579178936, 14458.710825783479, 14460.5661830145, 14514.201718905457, 13864.098833575836, 14527.128372297642, 14560.927195364035, 14587.708245835078, 14592.197429141419, 658.772723410425, 789.376392649806, 791.8653828849109, 795.9442839001272, 796.016919104905, 777.7588947426287, 798.2097723253966, 802.3541049455187, 804.0431886055604, 805.0741647105816, 829.4235506323108, 831.563429199672, 834.7287131682198, 835.3192148399036, 835.757630287216, 694.4687314204638, 808.5472135604022, 829.7096016394244, 830.1698301698272, 830.6552780596045, 608.9634621922698, 616.7964571691331, 616.9938300616981, 617.1853153836937, 617.4443001298866, 13610.183388996691, 13643.590692279247, 13680.52118175869, 13686.52097176594, 13688.726225475435, 13138.343082845819, 13451.394687239, 13642.795358069638, 13877.236751978637, 13988.720225595533, 11984.340861252627, 12127.454264558117, 12304.81542776859, 12360.134790564663, 12475.376231188495, 3762.1159027628487, 3765.1151979284846, 3814.923701525982, 3837.76973381598, 3919.960800391988, 77346.61326693387, 79097.60451197822, 79212.0, 79731.0, 80352.79470807943, 9946.701598952042, 10018.599256029824, 11356.716082098013, 11473.655790326304, 11542.19204655666, 17516.29934802619, 17688.469345919642, 17762.0, 17888.28446862111, 17896.194671239824, 8186.304164145995, 8622.568871556397, 8636.866025980959, 8730.519821409884, 8755.518446485377, 4707.83522576715, 4791.281307803857, 4842.612590992712, 4875.098106850244, 4960.404751429779, 3552.609212986569, 3658.579263384692, 3668.981655091761, 3787.034306251888, 3799.5820459749407, 702.9753958611526, 717.4439809147952, 720.5640587444625, 720.5892641194469, 729.2962291388835, 638.4796390941368, 640.0079876191977, 731.4112139727464, 762.9318953465082, 763.1300317637897, 9431.198348140348, 9693.757656058655, 9725.270604704534, 9912.900870991341, 10098.596056157818, 650.768977013163, 705.8835292176733, 745.4669911013543, 752.7478294771204, 775.8991331126917, 4695.272232803921, 4896.5103489651465, 4935.728534930613, 4962.875928101754, 4971.4282857471135, 91556.54221728849, 94846.0, 96007.0, 109663.45168274107, 111841.0, 59169.0, 59269.0, 65214.673926630065, 79517.20482795214, 95042.0, 55768.88462230733, 75312.62343688356, 82645.58677206576, 83992.0, 85811.57094214488, 9651.324407291422, 9776.26677999167, 9795.118439340493, 9815.411075335503, 9953.850692239477, 51374.0, 51621.22568161556, 52558.47441525536, 52636.21045684241, 53616.73191634017, 71282.28717712757, 78086.6095669518, 78139.18608139141, 79330.0, 79770.0, 12553.435095420911, 12606.49574017029, 12917.741645166856, 13001.674958125745, 13748.93125534366, 13630.0, 13671.658208544866, 13993.16041037541, 14356.928215359274, 15237.085774853544, 13422.530211442552, 13764.0, 14110.039434604962, 14116.0, 14310.015414469748, 12652.304123273309, 12891.16207446513, 12936.805947910978, 13098.017648676197, 13542.255175965023, 13506.324683765968, 13768.032717304015, 13915.791263131263, 13955.23246221427, 14221.786673199704, 11319.717007074889, 12202.816957745463, 12262.693432663898, 12423.192492487959, 12591.307478088793, 11599.8260026098, 12055.638330850088, 12246.877531224574, 12431.407861417718, 12432.191907525981], "value": 7422.280603876139, "unit": "score"}], "name": "dromaeo_css", "value": 7126.691695446806, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 110.0, 110.0, 112.0, 111.0, 111.0, 109.0, 111.0, 109.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 126.0, 194.0, 126.0, 125.0, 162.0, 125.0, 178.0, 133.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 158.0, 159.0, 162.0, 161.0, 160.0, 162.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 129.0, 75.0, 77.0, 75.0, 76.0, 101.0, 75.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 117.0, 84.0, 84.0, 85.0, 120.0, 85.0, 84.0, 133.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 148.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 53.0, 54.0, 54.0, 58.0, 54.0, 54.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 62.0, 63.0, 63.0, 64.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 85.0, 79.0, 80.0, 86.0, 80.0, 81.0, 101.0, 83.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 123.0, 124.0, 121.0, 131.0, 132.0, 129.0, 119.0, 142.0, 127.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 158.0, 157.0, 157.0, 163.0, 164.0, 159.0, 158.0, 158.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0, 65.0, 64.0, 62.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1503.1, "alertThreshold": 2.0}]} 14:27:00 INFO - Return code: 0 14:27:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:27:00 INFO - # TBPL SUCCESS # 14:27:00 INFO - Running post-action listener: _resource_record_post_action 14:27:00 INFO - ##### 14:27:00 INFO - ##### Finished run-tests step (success) 14:27:00 INFO - ##### 14:27:00 INFO - Running post-run listener: _resource_record_post_run 14:27:00 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 560734208; Read time: 20; Write time: 490432 14:27:00 INFO - TinderboxPrint: CPU usage
13.3% 14:27:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 14:27:00 INFO - TinderboxPrint: I/O write bytes / time
560,734,208 / 490,432 14:27:00 INFO - TinderboxPrint: CPU idle
8,261.2 (86.7%) 14:27:00 INFO - TinderboxPrint: CPU user
1,215.2 (12.8%) 14:27:00 INFO - TinderboxPrint: Swap in / out
0 / 0 14:27:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 164241408; Read time: 0; Write time: 211300 14:27:00 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294203392; Read time: 20; Write time: 197184 14:27:00 INFO - Running post-run listener: _upload_blobber_files 14:27:00 INFO - Blob upload gear active. 14:27:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:27:00 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 14:27:00 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:27:00 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:27:01 INFO - (blobuploader) - INFO - Open directory for files ... 14:27:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:27:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:27:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:01 INFO - (blobuploader) - INFO - Done attempting. 14:27:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:27:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:27:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:02 INFO - (blobuploader) - INFO - Done attempting. 14:27:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:27:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:06 INFO - (blobuploader) - INFO - Done attempting. 14:27:06 INFO - (blobuploader) - INFO - Iteration through files over. 14:27:06 INFO - Return code: 0 14:27:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:27:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:27:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/51b334189278bfea0eed9048dfa0947e39f19366231f782d6408f5195fd42fd959197fdc5d950e7ed021db62073519982c2f08ed551046cec358b4c34ca1c25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a934b9b5585e6cdd738717fa6128c25a875173c12c4f36dd639d50237eb426dc8c87522a4671cd4f99dff02a8b61e7fbb593a48c2ebac68e1c7b7d9d04281dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65e6e149de42720d385fdb4e1d8d3873579ea71a1353e9cc905f369126ad88da09d3ccec946faa1e57e3755d91836662a94f87cace9201ad9777042051efee12"} 14:27:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:27:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:27:06 INFO - Contents: 14:27:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/51b334189278bfea0eed9048dfa0947e39f19366231f782d6408f5195fd42fd959197fdc5d950e7ed021db62073519982c2f08ed551046cec358b4c34ca1c25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a934b9b5585e6cdd738717fa6128c25a875173c12c4f36dd639d50237eb426dc8c87522a4671cd4f99dff02a8b61e7fbb593a48c2ebac68e1c7b7d9d04281dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65e6e149de42720d385fdb4e1d8d3873579ea71a1353e9cc905f369126ad88da09d3ccec946faa1e57e3755d91836662a94f87cace9201ad9777042051efee12"} 14:27:06 INFO - Running post-run listener: copy_logs_to_upload_dir 14:27:06 INFO - Copying logs to upload dir... 14:27:06 INFO - mkdir: /builds/slave/test/build/upload/logs 14:27:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.763245 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-27 14:27:06.410393) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 14:27:06.414307) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964 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/sha512/51b334189278bfea0eed9048dfa0947e39f19366231f782d6408f5195fd42fd959197fdc5d950e7ed021db62073519982c2f08ed551046cec358b4c34ca1c25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a934b9b5585e6cdd738717fa6128c25a875173c12c4f36dd639d50237eb426dc8c87522a4671cd4f99dff02a8b61e7fbb593a48c2ebac68e1c7b7d9d04281dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65e6e149de42720d385fdb4e1d8d3873579ea71a1353e9cc905f369126ad88da09d3ccec946faa1e57e3755d91836662a94f87cace9201ad9777042051efee12"} build_url:https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/EyiHBvSZT3eg0TxIyuN4ow/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/sha512/51b334189278bfea0eed9048dfa0947e39f19366231f782d6408f5195fd42fd959197fdc5d950e7ed021db62073519982c2f08ed551046cec358b4c34ca1c25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a934b9b5585e6cdd738717fa6128c25a875173c12c4f36dd639d50237eb426dc8c87522a4671cd4f99dff02a8b61e7fbb593a48c2ebac68e1c7b7d9d04281dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65e6e149de42720d385fdb4e1d8d3873579ea71a1353e9cc905f369126ad88da09d3ccec946faa1e57e3755d91836662a94f87cace9201ad9777042051efee12"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 14:27:06.443827) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:27:06.444184) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467061065.719044-2004470964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 14:27:06.528763) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 14:27:06.529089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 14:27:06.529413) ========= ========= Total master_lag: 0.20 =========