builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1466770136.52 results: success (0) buildid: 20160624042927 builduid: 5be081320a944e8c89ff6e897fea31d2 revision: 9dac1358aaadcea90aca05b170fc8bc59e097d52 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.523151) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.523670) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.523986) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.538869) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.539199) ========= 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=836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-24 05:08:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.4M=0s 2016-06-24 05:08:58 (40.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.388758 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.942689) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:56.943066) ========= 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=836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014584 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:57.084013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-24 05:08:57.084451) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9dac1358aaadcea90aca05b170fc8bc59e097d52 --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 9dac1358aaadcea90aca05b170fc8bc59e097d52 --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=836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-24 05:08:58,322 truncating revision to first 12 chars 2016-06-24 05:08:58,322 Setting DEBUG logging. 2016-06-24 05:08:58,322 attempt 1/10 2016-06-24 05:08:58,322 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9dac1358aaad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-24 05:08:59,422 unpacking tar archive at: fx-team-9dac1358aaad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.441915 ========= master_lag: 0.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-24 05:08:58.939436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:58.939791) ========= script_repo_revision: 9dac1358aaadcea90aca05b170fc8bc59e097d52 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:58.943280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:58.943631) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-24 05:08:59.209348) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-24 05:08:59.209708) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:09:00 INFO - MultiFileLogger online at 20160624 05:09:00 in /builds/slave/test 05:09:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:09:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:00 INFO - {'append_to_log': False, 05:09:00 INFO - 'base_work_dir': '/builds/slave/test', 05:09:00 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:09:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:00 INFO - 'branch': 'Fx-Team-Non-PGO', 05:09:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:00 INFO - 'config_files': ('talos/linux_config.py',), 05:09:00 INFO - 'default_actions': ('clobber', 05:09:00 INFO - 'read-buildbot-config', 05:09:00 INFO - 'download-and-extract', 05:09:00 INFO - 'populate-webroot', 05:09:00 INFO - 'create-virtualenv', 05:09:00 INFO - 'install', 05:09:00 INFO - 'run-tests'), 05:09:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:00 INFO - 'download_minidump_stackwalk': True, 05:09:00 INFO - 'download_symbols': 'ondemand', 05:09:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:00 INFO - 'installer_path': 'installer.exe', 05:09:00 INFO - 'log_level': 'info', 05:09:00 INFO - 'log_name': 'talos', 05:09:00 INFO - 'log_to_console': True, 05:09:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:09:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:09:00 INFO - 'opt_config_files': (), 05:09:00 INFO - 'pip_index': False, 05:09:00 INFO - 'sps_profile': False, 05:09:00 INFO - 'sps_profile_interval': 0, 05:09:00 INFO - 'suite': 'dromaeojs', 05:09:00 INFO - 'system_bits': '32', 05:09:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:00 INFO - 'title': 'talos-linux64-ix-028', 05:09:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:00 INFO - 'use_talos_json': True, 05:09:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:00 INFO - 'work_dir': 'build'} 05:09:00 INFO - ##### 05:09:00 INFO - ##### Running clobber step. 05:09:00 INFO - ##### 05:09:00 INFO - Running pre-action listener: _resource_record_pre_action 05:09:00 INFO - Running main action method: clobber 05:09:00 INFO - rmtree: /builds/slave/test/build 05:09:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:01 INFO - Running post-action listener: _resource_record_post_action 05:09:01 INFO - ##### 05:09:01 INFO - ##### Finished clobber step (success) 05:09:01 INFO - ##### 05:09:01 INFO - ##### 05:09:01 INFO - ##### Running read-buildbot-config step. 05:09:01 INFO - ##### 05:09:01 INFO - Running pre-action listener: _resource_record_pre_action 05:09:01 INFO - Running main action method: read_buildbot_config 05:09:01 INFO - Using buildbot properties: 05:09:01 INFO - { 05:09:01 INFO - "project": "", 05:09:01 INFO - "product": "firefox", 05:09:01 INFO - "script_repo_revision": "production", 05:09:01 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:09:01 INFO - "repository": "", 05:09:01 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 05:09:01 INFO - "buildid": "20160624042927", 05:09:01 INFO - "pgo_build": "False", 05:09:01 INFO - "basedir": "/builds/slave/test", 05:09:01 INFO - "buildnumber": 3113, 05:09:01 INFO - "slavename": "talos-linux64-ix-028", 05:09:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:09:01 INFO - "platform": "ubuntu64_hw", 05:09:01 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:09:01 INFO - "branch": "fx-team", 05:09:01 INFO - "repo_path": "integration/fx-team", 05:09:01 INFO - "revision": "9dac1358aaadcea90aca05b170fc8bc59e097d52", 05:09:01 INFO - "stage_platform": "linux64", 05:09:01 INFO - "builduid": "5be081320a944e8c89ff6e897fea31d2", 05:09:01 INFO - "slavebuilddir": "test" 05:09:01 INFO - } 05:09:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:09:01 INFO - Running post-action listener: _resource_record_post_action 05:09:01 INFO - ##### 05:09:01 INFO - ##### Finished read-buildbot-config step (success) 05:09:01 INFO - ##### 05:09:01 INFO - ##### 05:09:01 INFO - ##### Running download-and-extract step. 05:09:01 INFO - ##### 05:09:01 INFO - Running pre-action listener: _resource_record_pre_action 05:09:01 INFO - Running main action method: download_and_extract 05:09:01 INFO - mkdir: /builds/slave/test/build/tests 05:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:01 INFO - https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:09:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:09:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:09:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:02 INFO - Downloaded 1361 bytes. 05:09:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:09:02 INFO - Using the following test package requirements: 05:09:02 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:09:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:09:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'jsshell-linux-x86_64.zip'], 05:09:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:09:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:09:02 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:09:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:09:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:09:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:09: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')]} 05:09:02 INFO - https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:09:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:03 INFO - Downloaded 22710890 bytes. 05:09:03 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'] 05:09:03 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 05:09:04 INFO - Return code: 0 05:09:04 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 05:09:04 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')]} 05:09:04 INFO - https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:09:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:05 INFO - Downloaded 22710890 bytes. 05:09:05 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'] 05:09:05 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 05:09:07 INFO - Return code: 0 05:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:07 INFO - https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:09:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:08 INFO - Downloaded 14405518 bytes. 05:09:08 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'] 05:09:08 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 05:09:08 INFO - Return code: 0 05:09:08 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')]} 05:09:08 INFO - https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:09:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YE9GQSWqQRi7CSf592BOJw/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 05:09:09 INFO - Downloaded 56268924 bytes. 05:09:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:09 INFO - mkdir: /builds/slave/test/properties 05:09:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:09 INFO - Contents: 05:09:09 INFO - build_url:https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:10 INFO - Running post-action listener: _resource_record_post_action 05:09:10 INFO - Running post-action listener: set_extra_try_arguments 05:09:10 INFO - ##### 05:09:10 INFO - ##### Finished download-and-extract step (success) 05:09:10 INFO - ##### 05:09:10 INFO - ##### 05:09:10 INFO - ##### Running populate-webroot step. 05:09:10 INFO - ##### 05:09:10 INFO - Running pre-action listener: _resource_record_pre_action 05:09:10 INFO - Running main action method: populate_webroot 05:09:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:10 INFO - '--tppagecycles', 05:09:10 INFO - '7'], 05:09:10 INFO - 'tests': ['tsvgm']}}, 05:09:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['damp', 'tps']}, 05:09:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'tests': ['damp', 'tps']}, 05:09:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['dromaeo_dom']}, 05:09:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['basic_compositor_video']}, 05:09:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:09:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tsvgx', 05:09:10 INFO - 'tsvgr_opacity', 05:09:10 INFO - 'tart', 05:09:10 INFO - 'tscrollx', 05:09:10 INFO - 'cart']}, 05:09:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:10 INFO - 'tsvgr_opacity', 05:09:10 INFO - 'tart', 05:09:10 INFO - 'tscrollx', 05:09:10 INFO - 'cart']}, 05:09:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tp5o']}, 05:09:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'tests': ['tp5o']}, 05:09:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s', 05:09:10 INFO - '--xperf_path', 05:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:10 INFO - 'tests': ['tp5n']}, 05:09:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--xperf_path', 05:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:10 INFO - 'tests': ['tp5n']}}, 05:09:10 INFO - 'talos.zip': {'path': '', 05:09:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:10 INFO - Running post-action listener: _resource_record_post_action 05:09:10 INFO - ##### 05:09:10 INFO - ##### Finished populate-webroot step (success) 05:09:10 INFO - ##### 05:09:10 INFO - ##### 05:09:10 INFO - ##### Running create-virtualenv step. 05:09:10 INFO - ##### 05:09:10 INFO - Running pre-action listener: _resource_record_pre_action 05:09:10 INFO - Running main action method: create_virtualenv 05:09:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:10 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 05:09:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:10 INFO - Using real prefix '/usr' 05:09:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:10 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:12 INFO - Installing pip.................done. 05:09:12 INFO - Return code: 0 05:09:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:09:12 INFO - Using env: {'DISPLAY': ':0', 05:09:12 INFO - 'HOME': '/home/cltbld', 05:09:12 INFO - 'LANG': 'en_US.UTF-8', 05:09:12 INFO - 'LANGUAGE': 'en_US:en', 05:09:12 INFO - 'LOGNAME': 'cltbld', 05:09:12 INFO - 'MAIL': '/var/mail/cltbld', 05:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:12 INFO - 'MOZ_NO_REMOTE': '1', 05:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:12 INFO - 'NO_EM_RESTART': '1', 05:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:12 INFO - 'PWD': '/builds/slave/test', 05:09:12 INFO - 'SHELL': '/bin/bash', 05:09:12 INFO - 'SHLVL': '1', 05:09:12 INFO - 'TERM': 'linux', 05:09:12 INFO - 'TMOUT': '86400', 05:09:12 INFO - 'USER': 'cltbld', 05:09:12 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:12 INFO - '_': '/tools/buildbot/bin/python'} 05:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:13 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 05:09:13 INFO - Cleaning up... 05:09:13 INFO - Return code: 0 05:09:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:09:13 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')]} 05:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:13 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')]} 05:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:13 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:13 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 05:09:13 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 05:09:13 INFO - Using env: {'DISPLAY': ':0', 05:09:13 INFO - 'HOME': '/home/cltbld', 05:09:13 INFO - 'LANG': 'en_US.UTF-8', 05:09:13 INFO - 'LANGUAGE': 'en_US:en', 05:09:13 INFO - 'LOGNAME': 'cltbld', 05:09:13 INFO - 'MAIL': '/var/mail/cltbld', 05:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:13 INFO - 'MOZ_NO_REMOTE': '1', 05:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:13 INFO - 'NO_EM_RESTART': '1', 05:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:13 INFO - 'PWD': '/builds/slave/test', 05:09:13 INFO - 'SHELL': '/bin/bash', 05:09:13 INFO - 'SHLVL': '1', 05:09:13 INFO - 'TERM': 'linux', 05:09:13 INFO - 'TMOUT': '86400', 05:09:13 INFO - 'USER': 'cltbld', 05:09:13 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:13 INFO - '_': '/tools/buildbot/bin/python'} 05:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:13 INFO - Downloading/unpacking psutil>=3.1.1 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:15 INFO - Installing collected packages: psutil 05:09:15 INFO - Running setup.py install for psutil 05:09:15 INFO - building 'psutil._psutil_linux' extension 05:09:15 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 05:09:15 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 05:09:15 INFO - building 'psutil._psutil_posix' extension 05:09:15 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 05:09:15 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 05:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:15 INFO - Successfully installed psutil 05:09:15 INFO - Cleaning up... 05:09:15 INFO - Return code: 0 05:09:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:09:15 INFO - Using env: {'DISPLAY': ':0', 05:09:15 INFO - 'HOME': '/home/cltbld', 05:09:15 INFO - 'LANG': 'en_US.UTF-8', 05:09:15 INFO - 'LANGUAGE': 'en_US:en', 05:09:15 INFO - 'LOGNAME': 'cltbld', 05:09:15 INFO - 'MAIL': '/var/mail/cltbld', 05:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:15 INFO - 'MOZ_NO_REMOTE': '1', 05:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:15 INFO - 'NO_EM_RESTART': '1', 05:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:15 INFO - 'PWD': '/builds/slave/test', 05:09:15 INFO - 'SHELL': '/bin/bash', 05:09:15 INFO - 'SHLVL': '1', 05:09:15 INFO - 'TERM': 'linux', 05:09:15 INFO - 'TMOUT': '86400', 05:09:15 INFO - 'USER': 'cltbld', 05:09:15 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:15 INFO - '_': '/tools/buildbot/bin/python'} 05:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:09:18 INFO - Installing collected packages: mozsystemmonitor 05:09:18 INFO - Running setup.py install for mozsystemmonitor 05:09:18 INFO - Successfully installed mozsystemmonitor 05:09:18 INFO - Cleaning up... 05:09:18 INFO - Return code: 0 05:09:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:09:18 INFO - Using env: {'DISPLAY': ':0', 05:09:18 INFO - 'HOME': '/home/cltbld', 05:09:18 INFO - 'LANG': 'en_US.UTF-8', 05:09:18 INFO - 'LANGUAGE': 'en_US:en', 05:09:18 INFO - 'LOGNAME': 'cltbld', 05:09:18 INFO - 'MAIL': '/var/mail/cltbld', 05:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:18 INFO - 'MOZ_NO_REMOTE': '1', 05:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:18 INFO - 'NO_EM_RESTART': '1', 05:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:18 INFO - 'PWD': '/builds/slave/test', 05:09:18 INFO - 'SHELL': '/bin/bash', 05:09:18 INFO - 'SHLVL': '1', 05:09:18 INFO - 'TERM': 'linux', 05:09:18 INFO - 'TMOUT': '86400', 05:09:18 INFO - 'USER': 'cltbld', 05:09:18 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:18 INFO - '_': '/tools/buildbot/bin/python'} 05:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:18 INFO - Downloading/unpacking jsonschema==2.5.1 05:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:20 INFO - warning: no files found matching '*.txt' 05:09:20 INFO - no previously-included directories found matching 'build' 05:09:20 INFO - no previously-included directories found matching 'dist' 05:09:20 INFO - no previously-included directories found matching '.git*' 05:09:20 INFO - Installing collected packages: jsonschema, functools32 05:09:20 INFO - Running setup.py install for functools32 05:09:20 INFO - warning: no files found matching '*.txt' 05:09:20 INFO - no previously-included directories found matching 'build' 05:09:20 INFO - no previously-included directories found matching 'dist' 05:09:20 INFO - no previously-included directories found matching '.git*' 05:09:20 INFO - Successfully installed jsonschema functools32 05:09:20 INFO - Cleaning up... 05:09:20 INFO - Return code: 0 05:09:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:20 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')]} 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:20 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')]} 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:20 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:20 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 05:09:20 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 05:09:20 INFO - Using env: {'DISPLAY': ':0', 05:09:20 INFO - 'HOME': '/home/cltbld', 05:09:20 INFO - 'LANG': 'en_US.UTF-8', 05:09:20 INFO - 'LANGUAGE': 'en_US:en', 05:09:20 INFO - 'LOGNAME': 'cltbld', 05:09:20 INFO - 'MAIL': '/var/mail/cltbld', 05:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:20 INFO - 'MOZ_NO_REMOTE': '1', 05:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:20 INFO - 'NO_EM_RESTART': '1', 05:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:20 INFO - 'PWD': '/builds/slave/test', 05:09:20 INFO - 'SHELL': '/bin/bash', 05:09:20 INFO - 'SHLVL': '1', 05:09:20 INFO - 'TERM': 'linux', 05:09:20 INFO - 'TMOUT': '86400', 05:09:20 INFO - 'USER': 'cltbld', 05:09:20 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:20 INFO - '_': '/tools/buildbot/bin/python'} 05:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:20 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:22 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:22 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 05:09:22 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 05:09:22 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 05:09:22 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 05:09:22 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 05:09:22 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 05:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:23 INFO - Downloading docopt-0.6.1.tar.gz 05:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:23 INFO - Running setup.py install for blobuploader 05:09:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:23 INFO - Running setup.py install for requests 05:09:24 INFO - Running setup.py install for docopt 05:09:24 INFO - Successfully installed blobuploader requests docopt 05:09:24 INFO - Cleaning up... 05:09:24 INFO - Return code: 0 05:09:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:24 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')]} 05:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:24 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')]} 05:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:24 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:24 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 05:09:24 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 05:09:24 INFO - Using env: {'DISPLAY': ':0', 05:09:24 INFO - 'HOME': '/home/cltbld', 05:09:24 INFO - 'LANG': 'en_US.UTF-8', 05:09:24 INFO - 'LANGUAGE': 'en_US:en', 05:09:24 INFO - 'LOGNAME': 'cltbld', 05:09:24 INFO - 'MAIL': '/var/mail/cltbld', 05:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:24 INFO - 'MOZ_NO_REMOTE': '1', 05:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:24 INFO - 'NO_EM_RESTART': '1', 05:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:24 INFO - 'PWD': '/builds/slave/test', 05:09:24 INFO - 'SHELL': '/bin/bash', 05:09:24 INFO - 'SHLVL': '1', 05:09:24 INFO - 'TERM': 'linux', 05:09:24 INFO - 'TMOUT': '86400', 05:09:24 INFO - 'USER': 'cltbld', 05:09:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:24 INFO - '_': '/tools/buildbot/bin/python'} 05:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:24 INFO - Running setup.py (path:/tmp/pip-N95ZCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:24 INFO - Running setup.py (path:/tmp/pip-1Z5zC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:24 INFO - Running setup.py (path:/tmp/pip-U1n_1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:24 INFO - Running setup.py (path:/tmp/pip-r3v0dJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:24 INFO - Running setup.py (path:/tmp/pip-FuwMN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:24 INFO - Running setup.py (path:/tmp/pip-38i3kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:24 INFO - Running setup.py (path:/tmp/pip-6vqt7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:24 INFO - Running setup.py (path:/tmp/pip-T0fjyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:25 INFO - Running setup.py (path:/tmp/pip-XiBYa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:25 INFO - Running setup.py (path:/tmp/pip-IlLWEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:25 INFO - Running setup.py (path:/tmp/pip-KL8Ono-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:25 INFO - Running setup.py (path:/tmp/pip-jJiiCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:25 INFO - Running setup.py (path:/tmp/pip-jMRL7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:25 INFO - Running setup.py (path:/tmp/pip-pF4tvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:25 INFO - Running setup.py (path:/tmp/pip-jy73vE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:25 INFO - Running setup.py (path:/tmp/pip-je7RdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:25 INFO - Running setup.py (path:/tmp/pip-FzNPXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:25 INFO - Running setup.py install for manifestparser 05:09:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:25 INFO - Running setup.py install for mozcrash 05:09:25 INFO - Running setup.py install for mozdebug 05:09:25 INFO - Running setup.py install for mozdevice 05:09:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Running setup.py install for mozfile 05:09:26 INFO - Running setup.py install for mozhttpd 05:09:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Running setup.py install for mozinfo 05:09:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Running setup.py install for mozInstall 05:09:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Running setup.py install for mozleak 05:09:26 INFO - Running setup.py install for mozlog 05:09:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Running setup.py install for moznetwork 05:09:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:26 INFO - Running setup.py install for mozprocess 05:09:26 INFO - Running setup.py install for mozprofile 05:09:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:27 INFO - Running setup.py install for mozrunner 05:09:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:27 INFO - Running setup.py install for mozscreenshot 05:09:27 INFO - Running setup.py install for moztest 05:09:27 INFO - Running setup.py install for mozversion 05:09:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:27 INFO - Cleaning up... 05:09:27 INFO - Return code: 0 05:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:27 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:27 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 05:09:27 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 05:09:27 INFO - Using env: {'DISPLAY': ':0', 05:09:27 INFO - 'HOME': '/home/cltbld', 05:09:27 INFO - 'LANG': 'en_US.UTF-8', 05:09:27 INFO - 'LANGUAGE': 'en_US:en', 05:09:27 INFO - 'LOGNAME': 'cltbld', 05:09:27 INFO - 'MAIL': '/var/mail/cltbld', 05:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:27 INFO - 'MOZ_NO_REMOTE': '1', 05:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:27 INFO - 'NO_EM_RESTART': '1', 05:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:27 INFO - 'PWD': '/builds/slave/test', 05:09:27 INFO - 'SHELL': '/bin/bash', 05:09:27 INFO - 'SHLVL': '1', 05:09:27 INFO - 'TERM': 'linux', 05:09:27 INFO - 'TMOUT': '86400', 05:09:27 INFO - 'USER': 'cltbld', 05:09:27 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:27 INFO - '_': '/tools/buildbot/bin/python'} 05:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:27 INFO - Running setup.py (path:/tmp/pip-hvlkgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:27 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)) 05:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:27 INFO - Running setup.py (path:/tmp/pip-SXncOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:27 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)) 05:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:27 INFO - Running setup.py (path:/tmp/pip-m_n2Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:27 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)) 05:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:27 INFO - Running setup.py (path:/tmp/pip-C9E7Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:27 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)) 05:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:27 INFO - Running setup.py (path:/tmp/pip-MPuTcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:28 INFO - Running setup.py (path:/tmp/pip-2IFTeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:28 INFO - Running setup.py (path:/tmp/pip-Kia5QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:28 INFO - Running setup.py (path:/tmp/pip-667tA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:28 INFO - Running setup.py (path:/tmp/pip-4nOEr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:28 INFO - Running setup.py (path:/tmp/pip-lhSBDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:28 INFO - Running setup.py (path:/tmp/pip-7mKYAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:28 INFO - Running setup.py (path:/tmp/pip-vf1O7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:28 INFO - Running setup.py (path:/tmp/pip-nONvdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:28 INFO - Running setup.py (path:/tmp/pip-sux9b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:28 INFO - Running setup.py (path:/tmp/pip-xwzReq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:28 INFO - Running setup.py (path:/tmp/pip-9VrVgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:28 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)) 05:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:28 INFO - Running setup.py (path:/tmp/pip-lqkCn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:28 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 05:09:28 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 05:09:28 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 05:09:28 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 05:09:28 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 05:09:28 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 05:09:30 INFO - Downloading blessings-1.6.tar.gz 05:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:30 INFO - Installing collected packages: blessings 05:09:30 INFO - Running setup.py install for blessings 05:09:30 INFO - Successfully installed blessings 05:09:30 INFO - Cleaning up... 05:09:31 INFO - Return code: 0 05:09:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:31 INFO - Reading from file tmpfile_stdout 05:09:31 INFO - Current package versions: 05:09:31 INFO - argparse == 1.2.1 05:09:31 INFO - blessings == 1.6 05:09:31 INFO - blobuploader == 1.2.4 05:09:31 INFO - docopt == 0.6.1 05:09:31 INFO - functools32 == 3.2.3-2 05:09:31 INFO - jsonschema == 2.5.1 05:09:31 INFO - manifestparser == 1.1 05:09:31 INFO - mozInstall == 1.12 05:09:31 INFO - mozcrash == 0.17 05:09:31 INFO - mozdebug == 0.1 05:09:31 INFO - mozdevice == 0.48 05:09:31 INFO - mozfile == 1.2 05:09:31 INFO - mozhttpd == 0.7 05:09:31 INFO - mozinfo == 0.9 05:09:31 INFO - mozleak == 0.1 05:09:31 INFO - mozlog == 3.2 05:09:31 INFO - moznetwork == 0.27 05:09:31 INFO - mozprocess == 0.22 05:09:31 INFO - mozprofile == 0.28 05:09:31 INFO - mozrunner == 6.11 05:09:31 INFO - mozscreenshot == 0.1 05:09:31 INFO - mozsystemmonitor == 0.3 05:09:31 INFO - moztest == 0.7 05:09:31 INFO - mozversion == 1.4 05:09:31 INFO - psutil == 3.1.1 05:09:31 INFO - requests == 1.2.3 05:09:31 INFO - wsgiref == 0.1.2 05:09:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:31 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 05:09:31 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 05:09:31 INFO - Using env: {'DISPLAY': ':0', 05:09:31 INFO - 'HOME': '/home/cltbld', 05:09:31 INFO - 'LANG': 'en_US.UTF-8', 05:09:31 INFO - 'LANGUAGE': 'en_US:en', 05:09:31 INFO - 'LOGNAME': 'cltbld', 05:09:31 INFO - 'MAIL': '/var/mail/cltbld', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:31 INFO - 'NO_EM_RESTART': '1', 05:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:31 INFO - 'PWD': '/builds/slave/test', 05:09:31 INFO - 'SHELL': '/bin/bash', 05:09:31 INFO - 'SHLVL': '1', 05:09:31 INFO - 'TERM': 'linux', 05:09:31 INFO - 'TMOUT': '86400', 05:09:31 INFO - 'USER': 'cltbld', 05:09:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 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)) 05:09:31 INFO - Cleaning up... 05:09:31 INFO - Return code: 0 05:09:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:31 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 05:09:31 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 05:09:31 INFO - Using env: {'DISPLAY': ':0', 05:09:31 INFO - 'HOME': '/home/cltbld', 05:09:31 INFO - 'LANG': 'en_US.UTF-8', 05:09:31 INFO - 'LANGUAGE': 'en_US:en', 05:09:31 INFO - 'LOGNAME': 'cltbld', 05:09:31 INFO - 'MAIL': '/var/mail/cltbld', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:31 INFO - 'NO_EM_RESTART': '1', 05:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:31 INFO - 'PWD': '/builds/slave/test', 05:09:31 INFO - 'SHELL': '/bin/bash', 05:09:31 INFO - 'SHLVL': '1', 05:09:31 INFO - 'TERM': 'linux', 05:09:31 INFO - 'TMOUT': '86400', 05:09:31 INFO - 'USER': 'cltbld', 05:09:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:31 INFO - Cleaning up... 05:09:31 INFO - Return code: 0 05:09:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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 0x7fddcd4e5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', '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 05:09:31 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 05:09:31 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 05:09:31 INFO - Using env: {'DISPLAY': ':0', 05:09:31 INFO - 'HOME': '/home/cltbld', 05:09:31 INFO - 'LANG': 'en_US.UTF-8', 05:09:31 INFO - 'LANGUAGE': 'en_US:en', 05:09:31 INFO - 'LOGNAME': 'cltbld', 05:09:31 INFO - 'MAIL': '/var/mail/cltbld', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:31 INFO - 'NO_EM_RESTART': '1', 05:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:31 INFO - 'PWD': '/builds/slave/test', 05:09:31 INFO - 'SHELL': '/bin/bash', 05:09:31 INFO - 'SHLVL': '1', 05:09:31 INFO - 'TERM': 'linux', 05:09:31 INFO - 'TMOUT': '86400', 05:09:31 INFO - 'USER': 'cltbld', 05:09:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:31 INFO - Downloading/unpacking flake8 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:34 INFO - no previously-included directories found matching 'docs/_build' 05:09:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:34 INFO - Running setup.py install for pep8 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:34 INFO - no previously-included directories found matching 'docs/_build' 05:09:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:34 INFO - Cleaning up... 05:09:34 INFO - Return code: 0 05:09:34 INFO - Running post-action listener: _resource_record_post_action 05:09:34 INFO - Running post-action listener: _start_resource_monitoring 05:09:34 INFO - Starting resource monitoring. 05:09:34 INFO - ##### 05:09:34 INFO - ##### Finished create-virtualenv step (success) 05:09:34 INFO - ##### 05:09:34 INFO - ##### 05:09:34 INFO - ##### Running install step. 05:09:34 INFO - ##### 05:09:34 INFO - Running pre-action listener: _resource_record_pre_action 05:09:34 INFO - Running main action method: install 05:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:35 INFO - Reading from file tmpfile_stdout 05:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:35 INFO - Reading from file tmpfile_stdout 05:09:35 INFO - Output received: 05:09:35 INFO - Usage: mozinstall [options] installer 05:09:35 INFO - Options: 05:09:35 INFO - -h, --help show this help message and exit 05:09:35 INFO - -d DEST, --destination=DEST 05:09:35 INFO - Directory to install application into. [default: 05:09:35 INFO - "/builds/slave/test"] 05:09:35 INFO - --app=APP Application being installed. [default: firefox] 05:09:35 INFO - mkdir: /builds/slave/test/build/application 05:09:35 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'] 05:09:35 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 05:09:51 INFO - Reading from file tmpfile_stdout 05:09:51 INFO - Output received: 05:09:51 INFO - /builds/slave/test/build/application/firefox/firefox 05:09:51 INFO - Running post-action listener: _resource_record_post_action 05:09:51 INFO - ##### 05:09:51 INFO - ##### Finished install step (success) 05:09:51 INFO - ##### 05:09:51 INFO - ##### 05:09:51 INFO - ##### Running run-tests step. 05:09:51 INFO - ##### 05:09:51 INFO - Running pre-action listener: _resource_record_pre_action 05:09:51 INFO - Running main action method: run_tests 05:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:51 INFO - Python 2.7.3 05:09:51 INFO - Return code: 0 05:09:51 INFO - grabbing minidump binary from tooltool 05:09: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')]} 05:09:51 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 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:51 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 05:09:51 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 05:09:51 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 05:09:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:51 INFO - Return code: 0 05:09:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:09:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:09:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:09:52 INFO - Return code: 0 05:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 05:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 05:09:52 INFO - Using env: {'DISPLAY': ':0', 05:09:52 INFO - 'HOME': '/home/cltbld', 05:09:52 INFO - 'LANG': 'en_US.UTF-8', 05:09:52 INFO - 'LANGUAGE': 'en_US:en', 05:09:52 INFO - 'LOGNAME': 'cltbld', 05:09:52 INFO - 'MAIL': '/var/mail/cltbld', 05:09:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:52 INFO - 'MOZ_NO_REMOTE': '1', 05:09:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:52 INFO - 'NO_EM_RESTART': '1', 05:09:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:52 INFO - 'PWD': '/builds/slave/test', 05:09:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:52 INFO - 'SHELL': '/bin/bash', 05:09:52 INFO - 'SHLVL': '1', 05:09:52 INFO - 'TERM': 'linux', 05:09:52 INFO - 'TMOUT': '86400', 05:09:52 INFO - 'USER': 'cltbld', 05:09:52 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013', 05:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:52 INFO - '_': '/tools/buildbot/bin/python'} 05:09:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 05:09:52 INFO - mozversion application_buildid: 20160624042927 05:09:52 INFO - mozversion application_changeset: 9dac1358aaadcea90aca05b170fc8bc59e097d52 05:09:52 INFO - mozversion application_display_name: Nightly 05:09:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:52 INFO - mozversion application_name: Firefox 05:09:52 INFO - mozversion application_remotingname: firefox 05:09:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:09:52 INFO - mozversion application_vendor: Mozilla 05:09:52 INFO - mozversion application_version: 50.0a1 05:09:52 INFO - mozversion platform_buildid: 20160624042927 05:09:52 INFO - mozversion platform_changeset: 9dac1358aaadcea90aca05b170fc8bc59e097d52 05:09:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:09:52 INFO - mozversion platform_version: 50.0a1 05:09:52 INFO - using testdate: 1466770192 05:09:52 INFO - actual date: 1466770192 05:09:52 INFO - starting webserver on 'localhost:45026' 05:09:52 INFO - SUITE-START | Running 2 tests 05:09:52 INFO - TEST-START | dromaeo_css 05:09:52 INFO - operating with platform_type : linux_ 05:09:52 INFO - Initialising browser for dromaeo_css test... 05:09:52 INFO - TEST-INFO | started process 5046 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnuOfT1/profile http://localhost:45026/getInfo.html) 05:09:57 INFO - PROCESS | 5046 | __metrics Screen width/height:1600/1200 05:09:57 INFO - PROCESS | 5046 | colorDepth:24 05:09:57 INFO - PROCESS | 5046 | Browser inner width/height: 1024/697 05:09:57 INFO - PROCESS | 5046 | __metrics 05:09:58 INFO - PROCESS | 5046 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:09:59 INFO - TEST-INFO | 5046: exit 0 05:09:59 INFO - Browser initialized. 05:09:59 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:59 INFO - TEST-INFO | started process 5128 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnuOfT1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:11 INFO - PROCESS | 5128 | RSS: Main: 175378432 05:10:11 INFO - PROCESS | 5128 | 05:11:02 INFO - PROCESS | 5128 | 1466770262124 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] 05:13:15 INFO - PROCESS | 5128 | Cycle 1(1): loaded http://localhost:45026/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45026/tests/dromaeo/cssquery-ext.html) 05:13:16 INFO - PROCESS | 5128 | RSS: Main: 278552576 05:13:16 INFO - PROCESS | 5128 | 05:16:18 INFO - PROCESS | 5128 | Cycle 1(1): loaded http://localhost:45026/tests/dromaeo/cssquery-ext.html (next: http://localhost:45026/tests/dromaeo/cssquery-jquery.html) 05:16:19 INFO - PROCESS | 5128 | RSS: Main: 259936256 05:16:19 INFO - PROCESS | 5128 | 05:19:21 INFO - PROCESS | 5128 | Cycle 1(1): loaded http://localhost:45026/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45026/tests/dromaeo/cssquery-mootools.html) 05:19:22 INFO - PROCESS | 5128 | RSS: Main: 257363968 05:19:22 INFO - PROCESS | 5128 | 05:22:24 INFO - PROCESS | 5128 | Cycle 1(1): loaded http://localhost:45026/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45026/tests/dromaeo/cssquery-prototype.html) 05:22:25 INFO - PROCESS | 5128 | RSS: Main: 251437056 05:22:25 INFO - PROCESS | 5128 | 05:25:28 INFO - PROCESS | 5128 | Cycle 1(1): loaded http://localhost:45026/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45026/tests/dromaeo/cssquery-yui.html) 05:25:28 INFO - PROCESS | 5128 | RSS: Main: 245264384 05:25:28 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | Cycle 1(1): loaded http://localhost:45026/tests/dromaeo/cssquery-yui.html (next: http://localhost:45026/tests/dromaeo/cssquery-dojo.html) 05:28:31 INFO - PROCESS | 5128 | RSS: Main: 291213312 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | __start_tp_report 05:28:31 INFO - PROCESS | 5128 | _x_x_mozilla_page_load 05:28:31 INFO - PROCESS | 5128 | _x_x_mozilla_page_load_details 05:28:31 INFO - PROCESS | 5128 | |i|pagename|runs| 05:28:31 INFO - PROCESS | 5128 | |0;dojo.html;2644.048142668639;2648.430587423706;2702.729727027297;2716.022231996481;2717;4575.862724118273;4625.5836974672275;4689.249720044793;4789.904201915964;4853.635977301698;4730.550597693222;4765.785539650716;4785.066911952171;4790.065937142259;4810.206315957871;4337.089211265638;4348.695591308609;4391.209582275189;4418.160549495602;4564.726116433008;4416.183006143858;4488.05750792334;4521.977390113061;4529.524399938008;4549.818007279705;4257.76582287974;4337.306031035019;4352.717073390219;4409;4420.580044895762;4352.695311328208;4374.190774706658;4374.8906277343;4408.0522687622315;4447.688661793676;4390.078083602447;4406.25093734066;4411.051623900845;4414.470263568361;4458.4426946631675;4631.953680463186;4748.667593268473;4766.73782941938;4807.519248075165;4812.085703716282;5162.070827251093;5164.845054648329;5181.611379146579;5205.583553315726;5216.504432078947;5199.6360254782185;5218.60860435465;5231.424543300234;5235.240890070956;5238.92141617876;4635.930461043054;4733.408323959505;4811.542903424169;4818.71087734737;4829.468758436569;5256.080185967517;5292.867678308073;5297.099493086184;5341.0653135701095;5351.598630102758;4867.853964381075;5073.239014147834;5073.695578265316;5115.897682046339;5120.385553733501;5025.095482813055;5033.697978121324;5090.007448547498;5151.613628977767;5165.173572228426;5072.898542029138;5075.3148324975655;5078.314427552307;5082.669626474268;5131.846044618667;5011.448740638527;5040.193569029011;5052.166392545262;5056.191009438472;5058.848234552969;4256.553061928453;4297.742135471882;4299.97850010748;4355.956440435555;4383.232934236496;3832.4442955771815;3857.479240302523;3887.78617176058;3897.4348719435525;3918.5689574146822;4333.71830830995;4355.412019377407;4361.847335343247;4370.278903980871;4419.18245124653;4886.438059623118;4990.525900039491;5168.586513078944;5180.585553155738;5227.634065615371;4326.199653064194;4369.12617476506;4376.6498680105515;4378.956210437918;4404.097160082176;4629.722216667011;4646.674732768742;4647.1635105680625;4655.604273636779;4702.741349225757;4611.423572053493;4797.4482934462285;4810.062037902575;4814.566688998007;4815.061063092803;5118.462561431145;5126;5176.922346164735;5198.038362902875;5215.061288968022;4797.112534181188;4815.494373090775;4829.589484893823;4837.419509658864;4883.047805678001;4893.461719210955;5104.719240441811;5114.5652619527755;5115.25828754821;5159.406668233051;4979.228219625964;5054.519820617035;5069.7718602662235;5085.237214417867;5087.186050231945;4994.9750251248515;4999.999999999942;5001.424836143745;5002.749862506933;5043.722595257296;4838.298446725275;4981.800727970841;4982.202847544376;4990.101781679187;5035.546800787946;4858.732769697559;4860.441049279378;4884.511548845229;4969.55274025325;5014.7994080236385;5046.318746969259;5051.368578927634;5051.949480505148;5056.595472362129;5098.643094983316;4757.119932812441;4867.464578896389;4950.529699678525;4956.355673762388;5000.724960127082 05:28:31 INFO - PROCESS | 5128 | |1;ext.html;537.9168735268742;578.6354596604139;586.1793489115238;599.5653151465189;605.2071785960395;26964.730352696468;39280.661143802776;40272.59727402725;40277.3958390624;40303.19393612126;41643.58356416435;41730;41751.79124104375;41839.58160418395;41856.16287674245;7587.203343648893;7887.842243155119;8041.7185398511065;8048.195180481964;8048.517088974651;10112.544935477921;10417.27079104463;10435.32170408921;10726;10755.569777208902;10353.482325883713;10451.581936722521;10461.320014199091;10474.58101675932;10481.318714283585;7609.7336593219525;10156.339837910513;10275.280730348879;10346.999999999962;10391.960803919623;10249.231307651957;10312.690619281433;10333.844992325121;10338.328008679411;10342.327748696385;40283.59716402828;40657.186856262706;40659.7967010166;40662.79668601668;40707.38938915919;3076.292452735861;3188.952165717516;3410.9449775595776;3691.409374500094;3789.4505296732095;28592;30633.387332253453;30711.846440767877;31253.37493250145;31850.203744906332;3588.766730162557;3600.765950213228;3655.798931058791;3656.4149736042104;3717.553893532793;259864;265156.9999999981;265927;266453;267002;55893;56165.7191714047;56384;56548.43451565432;56563.43436565664;33812.830935845166;34751;34796.65203347934;36027.81986090105;36236.275274494365;13953.232572208626;14061.086029408056;14072.366743496566;14077.084989475856;14146.434142634385;9353.719388418358;9479.526023698787;9497.43015419077;9614.663486777928;11366.545338186379;18528.351507697167;18696.719549206773;19182.808171918383;19779.901100494404;19948;6928.960655901655;6973.79078627642;7042.119735033121;7045.788626341218;7070.681819318143;804.2359758229705;908.3596064774318;914.4741773480275;970.9611615535441;972.6498460554195;27596.37834405728;28679.139625810843;28809.604531041205;28958.855205723838;29259.414811703646;946.2808265718104;976.4629453800381;1015.4871789746131;1032.5869652139054;1087.842262871895;15064.397424102914;15168.924155379153;15169.24153792306;15282.694346113238;15409;127176;127242;127503.362483187;127869;128175;33898.49152262718;34410.13974650654;34468;34511.82744086314;34519.309613807585;1090.929089609173;1195.1753290229713;1224.5958833584896;1225.154643296123;1246.401727170945;28358.85820570884;29567.704322956495;29994.550081748355;30117.548236776027;30358.84820575883;44080.559194407644;44391.11217775562;44513.777431112634;44576;44635.553644463136;302318;302513;302558;302749;303403;22270;24026;26450.735492644828;27012.45975080527;27794.861025694743;43357.34963975606;43509.564904350555;43555.34666980061;44398;44504.77747611241;36372.454413183295;37252.62747372492;37681;38104;38259.61740382561;37298.627013729514;37500.81249593844;37724.81137594295;37905.81047094747;37972.43041354327;35426;36493.27013459769;36828.26343473169;36830.63169368272;37121.81439092787;33030.5045424314;34050.16849115706;34217;34515.309693806485;34645.13387165341;25864.741352586232;26222.60666089972;26223.838947578388;26229.606555902057;26411.207663770118 05:28:31 INFO - PROCESS | 5128 | |2;jquery.html;369.58606360875814;370.2520907766311;377.7884384744543;390.5294120584696;391.9764814111152;15806.762898556532;16924.57688557785;17200.3119875205;17426.56433589161;17488.21303041363;15551.844481555181;16388.344466221337;16414;16449.424270150546;17343.219555120017;4580.312953057043;4739.14695354836;4756.1914474539235;4761.714297142165;4763.14263432582;3452.085197422685;3600.71194304455;4708.270218116198;5242.475752424746;5520.558355331584;5564.415736347686;5581.274434323533;5598.600349912522;5602.495775380215;5602.7758889644565;4986.825461108862;5309.230161626581;5315.601329900253;5396.217548455491;5575.414581468928;3370.1069216657606;4969.875753106165;5221.869453263699;5276.815311464119;5326.92009619856;12017.579384721581;12283.877161228364;12528.749425011449;12535.122541422104;12622.495100195982;1919.865609407342;1984.0179111339978;1998.5203551147765;2168.4687251623295;2262.4343914021497;8957.955210223972;9057.275417966614;9065.22945549629;9131.315151363675;9311.953440232823;1675.8407951244612;1689.684476272352;1710.2731339180798;1747.860171186302;1764.3824661368546;67301.66349168272;72480.27519724841;72547.63726181441;72647.27352726458;73247.63376183138;9691.370060946016;10369.688909332732;10397.428141452292;10759.677209683721;11002.834957475645;14257.572272831832;14310.427582896567;14380.209088500023;14399.208043557495;14403.92798036024;7118.181409138016;7448.424756117094;7458.73894413693;7602.923970760332;7617.733379331697;7086.149662040588;7089.042979197846;7237.167725711505;7244.492885497964;7272.527285726516;4349.369341445473;4366.585174408426;4462.754548499799;4526.140033393644;4555.63554915606;1655.4950740024167;1735.2885317020214;1803.729440583923;1814.6642871068896;1857.9840872653053;817.1950628630794;824.2046425199711;830.9958450207711;836.4479443567217;896.6861598440624;16801.747973780402;16931.153442327835;17491.300347985936;17698.203580838894;17767;841.7382642261238;845.2139510255521;857.7469646454311;869.0787764969153;964.4309857184295;9530.142287194185;9813.80372392548;9857;9860.901390986142;10078.09297163244;136794;139975;145156;411461;412675;45216.321755173696;45724.771376142904;68272.3172768266;72207.63896180486;73537;906.4063038709655;911.3666002128457;917.1607978699549;933.7525555727738;937.4703292639637;14964;15189.392424302903;15386.538403847902;15484.612884677974;15809.13049782273;25510.234692959242;27422.72577274202;31190.22024449407;35191.648083518834;35748.28503429969;43763.56236437722;45985;47987;49171.25415871682;49270;7566.470347075652;7634.122075961113;7679.500832445873;7739.961300193463;7810.492317999539;11621.535138594361;11809.23239989398;11962;12226;12330.136890417585;6509.837254068686;9511.383064878848;9887.110160085627;10267.281290309607;10419.843702344622;6940.826479338057;10596.15230781551;10625.840612390975;10647.361158330525;10895.564177432814;9570.56932438028;10317.793644127225;10572.471376431302;10657.626983055556;10743.677689669323;6750.966245168743;7033.402160816183;7929.762107136795;7959.4030447715495;8065.395095367753;9746.951265243628;10379.896201037893;10413.739656508647;10582.735431614272;10798.352098874093 05:28:31 INFO - PROCESS | 5128 | |3;mootools.html;1223.3760782001177;1372.0464277327255;1428.8285405751312;1479.7558402863528;1693.9576510587233;6719.697613607387;7673.424493163007;7965.243301886326;7979.122296547383;8190.54951977641;7909.960450197756;8050.838983220332;8066.2337077977645;8088.029436467617;8092.59537023148;2233.932982010538;2262.671912572676;2264.954700905981;2267.4444761033487;2267.8979445924974;2210.7568167501563;2230.163688616769;2249.201533455624;2252.042881775247;2252.6395776675736;2175.282156888223;2190.310052333518;2193.7367515898113;2196.077647388101;2210.624193887043;2111.0183764549406;2114.7250857388594;2115.2702317700446;2117.3224568138203;2119.798619131188;2081.55246621977;2121.2469573301564;2125.064971412589;2128.7653161166486;2131.4138611881704;7670.884936725954;7914.248146426138;8038.919610803934;8094.392920530925;8100.594970251464;4582.083583283324;4708.199606066977;4725.5510726480925;4725.834595789131;4739.9526004739855;2886.451574200916;2990.312228187507;3043.7717171212025;3051.725344718986;3060.2808340040074;866.4411454611803;878.7100256915203;883.1654086887894;930.5004543265287;975.5756246032928;10792;10912.945435272852;10940.617078402298;10953.83569246454;10980.066694330992;4067.633912947849;4163.7293575917465;4180.24755544005;4219;4221.831126754895;8501;8568.785780355416;8571.742847714579;8574.271186949054;8575.056743758181;4679.9298010529865;4694.7887345068875;4696.624270058387;4698.647601429906;4702.27114797207;3531.7174626029855;3844.5386553613744;3977.5084343371236;4262.062346283813;4302.548232435612;3421.8802341917913;3472.5138480612736;3527.0829584307753;3558.3417067842533;3574.910627234288;1052.3896140238644;1057.4659796802719;1057.6509751781894;1057.8889216632297;1082.577794660083;701.5825583777644;703.6657587645827;792.7146227358146;810.9026916770025;822.1778221778102;4903.852884413402;4980.90038199234;5106.846794596168;5157.664751791197;5194.636375453682;835.0939230934383;856.3448961544424;900.5182227508252;903.3179949138358;908.3323757038571;3372.612149602778;3505.9123521911647;3509.0700964244497;3523.735719821024;3540.46892966052;9563.856542151872;9907.25695572835;9933.304668673123;10054.195664346835;10071.899281007243;9904.504774761233;9947.353422027545;9976.45129517869;9994.550245238981;9998.600055997678;7290.161631412456;7430.96284518574;7432.628368581657;7445.702171913063;7446.329830315298;5162.97418512905;5199.920031987235;5306.8938621228135;5476.397596264328;5492.5331346835965;8537.914620853711;9063.546822658709;9227.907720922705;9308.255339572954;9329;9208.355415120877;9212.078792120734;9365.765855853386;9374.156325930699;9452.669156579486;6498.577592456475;7285.744998925013;7530.510516816389;7538.3215510604305;7713.691452341844;7689.423293252917;7690.692372305269;7749.7675069748;7754;7777.416693748105;6622.337766223339;6650.401463868275;6658.101156344025;6726.8654626908165;6752.966235168793;6639.568428052161;6667.299933506912;6727.596344219362;6729.596224226562;6732.596044237362;6059.3637668045985;6069;6119.112728654409;6130.049842274455;6130.111133885473;4242.236397448489;4252.277112890878;4253.532111467674;4298.333758267474;4327.7186982846015;4552.931706024478;4571.222892108283;4591.196540605448;4605.700629459075;4609.331646911112 05:28:31 INFO - PROCESS | 5128 | |4;prototype.html;300.61671369004523;302.31073153210673;304.73031367239986;305.9908202753918;306.7561288775423;4887.608991280698;4899.16714158593;4911.9263211051775;4920.86820031393;4921.9015619687625;4843.612510999121;4853.757312134388;4877.902441951159;4879.951200487994;4881;3657.9451308230396;3765.472833803256;3767.2088861339016;3852.460655508217;3885.1258466845015;3817.7709337439705;3859.9614003859883;3887.358585833348;3887.650111489965;3898.1229223424784;3687.1888184599347;3846.595992462745;3850.17221297421;3850.9807450962844;3851.383778595425;3760.398336266198;3802.714796390254;3806.9530879008216;3810.6951443884564;3818.427235914607;3389.2713066690535;3391.6608339166132;3402.302527981758;3406.3017081498233;3419.281950790337;4716.646251531149;4741.976290118562;4776.665633405663;4778.832740854054;4802.703270117084;4200.260657987976;4394.8461803837035;4396.318570621559;4398.208322501949;4401.537838526972;5050.368703912011;5056.62075344347;5071.822486213002;5077.49225077493;5100.719460429675;3734.25314937011;3746.1571146492092;3760.9247815043545;3761.6614504694708;3778.2065766189135;5141.948580514184;5298.788048478056;5316.867078323035;5321.813736519203;5329.920051199197;3748.887533374003;3771.4342848572933;3791.2417516496257;3796.9430458543143;3807.428885667172;3800.7529510582276;3809.7333186676665;3837.5586807516934;3840.8093491017876;3840.9039774005873;4348.130373925228;4352.955290730202;4354.455693038371;4355.455568053993;4385.473743150778;4872.8315899424415;5023.296738456619;5051.924221136685;5072.442031376546;5117.232415137759;1330.647378444713;1351.7431687979251;1354.607163922471;1359.7756370199004;1362.781954887233;862.6894318045498;875.8817559629368;876.4303202918027;879.7492714576315;881.3918396306528;4323.718958267703;4343;4360.41134446852;4501.0547784964865;4501.392312037899;5212.201790382312;5238.266642669953;5245.632805703641;5291.444398338119;5295.549878260314;4236.322188449833;4424.977875110668;4429.80065897029;4448.532904045093;4476.485204201558;2761.550186152286;2810.7189281072137;2814.183886672883;2814.3667674773014;2824.5480723084206;4951.375770841388;4952.910359720856;4962.354893863847;4963.131451995886;4964.329815474937;5111.67418824434;5409.648372855751;5410.242566040757;5421.810236641699;5422.1053526168525;4923.409190897115;4941.604671626261;4959.70241785494;4971.28490670718;4984.576311013532;5311.3891902431715;5325.9201111982575;5328.413874473727;5332.9466705334;5347.679139251657;4920.040592084652;5238.266642669953;5250.474952504719;5256.737163141904;5264.657797243167;4919;4919.877003074808;4923.236898280772;4964;4978.651494395358;3718.795466249382;3744.7191460641106;3753.680937120266;3761.7366784324836;3765.566959799658;5152.4847515248175;5276.868078298074;5322.946770532245;5328.547073498796;5378.112611419072;5122.308488354173;5135.87160320995;5144.430948560725;5144.9742751286;5145.897082058263;5124.590032797441;5155.896882062413;5160.922586161136;5190.195519694454;5208.479152084761;4854.296127061627;4949.925751113664;5083.796648134034;5086.593072554261;5092.9745351273;5034.546890779848;5035.59715222774;5040.7479626019285;5073.37651951372;5093.949060509496;4925.24186455586;5024.271480635361;5060.822871199491;5082.364704411949;5086.313347698014 05:28:31 INFO - PROCESS | 5128 | |5;yui.html;648.2933602373412;667.0727688512968;708.6350529477317;745.8545583611198;747.3460721868365;12725.42735576899;13815.654608634777;13837.723245535084;13921.23433211173;13968.580942571727;12402.81395779064;12427.813582796243;13669.248191349472;13695.520656777015;13729.45082196711;622.5704264057808;768.4927947554615;780.4224873593542;790.6797746912481;790.93277071449;784.2157842157842;795.9681612735484;796.1202870827739;796.3151689546985;796.5937371940323;835.5863847395517;838.7232213369604;839.6725277141919;839.689314953468;839.9622017009249;646.5312648329971;810.7162493127418;834.5993922917004;834.5993922917033;835.761807884753;609.6189881324173;609.7500024989733;610.0605068194974;610.1092405088576;610.1701685707434;13952.790708139386;14005.789913151311;14012.089214201147;14013.929930350385;14014.649633759238;13059.869401305961;13131.803022954664;13190.274534900576;13691.178529288274;13877.930610346984;11722.355364534862;11806.027533176237;11891.28652280866;11927.343996080212;11951.223170493977;4223.176480586287;4237.724547904407;4248.702590818644;4263.893402664927;4281.828726850922;57845.661575382015;57988.420115798304;58026.70986645082;58166.418335816525;58444.41555584432;9809.166220871162;9944.105030547285;10003.599856005823;10033.949830250947;10181.796364072678;17713.29146834138;17719.114044297734;17799.377021804175;17822.46532604024;17824.46526604204;6817.9659101705165;6919.37725604698;6947.3747362736585;7036.964815175891;7203.567785932861;3583.8387272572795;3613.1328481164333;3631;3651.507046548735;3706.388445906395;2798.4263226038615;2871.956920646192;2886.148586167086;2888.306806366499;2900.4054168895327;503.0341743851813;510.14295982749337;514.385309555078;516.4474012806336;521.0698902459093;372.261061792342;390.0813584009673;440.67390131303245;448.401384152154;451.82153049545246;6466.547341686037;6538;6554.803355899236;6658.1011563438315;6744.5616034957575;458.9701669391479;466.79461037143545;474.86466357088756;479.53485119434094;505.00513987444674;3801.6388443098413;3839.212961342918;3867.7099217558793;3941.743786653916;4078.7144899857317;61284;82659;107130.46434767776;110198.44900775605;110329;83607.16392836116;94589;95820.52089739506;96816.67508287112;97116;83674;85061.57469212615;87044;87267.56366218255;88267;7777.377809775091;7867.095284042409;7872.976513053266;7874.56689882062;8020.358371330163;46830.76584617055;46840.297395538415;47608.76195619;47798.283025753946;48809.2678609814;64586;67510;72228.63885680538;73114.26885731074;73240.63379683068;8800.515971621622;8861.158189971942;9054.27565794748;9080.364374493722;9696.175825054666;10341.689749307532;11997.16019878635;13066.934665326613;13249.867501325249;13459.990500712682;11486.655400338002;11539.942300288782;11585.594504192313;12028.09789265791;12431.254124752544;11198.496067677137;11252.83120753173;11826.113041521749;12373.13388062863;13224.80481731696;10143.34068285559;10814.621488247874;11180.72048198769;11583.942080289882;11763.705907352043;9564.426134431957;9588.089131532493;9614.471204083842;9743.26925480606;9821.263405244492;9138.518195857696;9326.580303886205;9342.112499312554;9349;9351.48566828831 05:28:31 INFO - PROCESS | 5128 | __end_tp_report 05:28:31 INFO - PROCESS | 5128 | __start_cc_report 05:28:31 INFO - PROCESS | 5128 | _x_x_mozilla_cycle_collect,956 05:28:31 INFO - PROCESS | 5128 | __end_cc_report 05:28:31 INFO - PROCESS | 5128 | __startTimestamp1466771311760__endTimestamp 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | ------- Summary: start ------- 05:28:31 INFO - PROCESS | 5128 | Number of tests: 6 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | [#0] dojo.html Cycles:165 Average:4733.42 Median:4829.59 stddev:496.14 (10.3%) stddev-sans-first:469.81 05:28:31 INFO - PROCESS | 5128 | Values: 2644.0 2648.4 2702.7 2716.0 2717.0 4575.9 4625.6 4689.2 4789.9 4853.6 4730.6 4765.8 4785.1 4790.1 4810.2 4337.1 4348.7 4391.2 4418.2 4564.7 4416.2 4488.1 4522.0 4529.5 4549.8 4257.8 4337.3 4352.7 4409.0 4420.6 4352.7 4374.2 4374.9 4408.1 4447.7 4390.1 4406.3 4411.1 4414.5 4458.4 4632.0 4748.7 4766.7 4807.5 4812.1 5162.1 5164.8 5181.6 5205.6 5216.5 5199.6 5218.6 5231.4 5235.2 5238.9 4635.9 4733.4 4811.5 4818.7 4829.5 5256.1 5292.9 5297.1 5341.1 5351.6 4867.9 5073.2 5073.7 5115.9 5120.4 5025.1 5033.7 5090.0 5151.6 5165.2 5072.9 5075.3 5078.3 5082.7 5131.8 5011.4 5040.2 5052.2 5056.2 5058.8 4256.6 4297.7 4300.0 4356.0 4383.2 3832.4 3857.5 3887.8 3897.4 3918.6 4333.7 4355.4 4361.8 4370.3 4419.2 4886.4 4990.5 5168.6 5180.6 5227.6 4326.2 4369.1 4376.6 4379.0 4404.1 4629.7 4646.7 4647.2 4655.6 4702.7 4611.4 4797.4 4810.1 4814.6 4815.1 5118.5 5126.0 5176.9 5198.0 5215.1 4797.1 4815.5 4829.6 4837.4 4883.0 4893.5 5104.7 5114.6 5115.3 5159.4 4979.2 5054.5 5069.8 5085.2 5087.2 4995.0 5000.0 5001.4 5002.7 5043.7 4838.3 4981.8 4982.2 4990.1 5035.5 4858.7 4860.4 4884.5 4969.6 5014.8 5046.3 5051.4 5051.9 5056.6 5098.6 4757.1 4867.5 4950.5 4956.4 5000.7 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | [#1] ext.html Cycles:180 Average:40017.97 Median:18813.97 stddev:63752.29 (338.9%) stddev-sans-first:63862.22 05:28:31 INFO - PROCESS | 5128 | Values: 537.9 578.6 586.2 599.6 605.2 26964.7 39280.7 40272.6 40277.4 40303.2 41643.6 41730.0 41751.8 41839.6 41856.2 7587.2 7887.8 8041.7 8048.2 8048.5 10112.5 10417.3 10435.3 10726.0 10755.6 10353.5 10451.6 10461.3 10474.6 10481.3 7609.7 10156.3 10275.3 10347.0 10392.0 10249.2 10312.7 10333.8 10338.3 10342.3 40283.6 40657.2 40659.8 40662.8 40707.4 3076.3 3189.0 3410.9 3691.4 3789.5 28592.0 30633.4 30711.8 31253.4 31850.2 3588.8 3600.8 3655.8 3656.4 3717.6 259864.0 265157.0 265927.0 266453.0 267002.0 55893.0 56165.7 56384.0 56548.4 56563.4 33812.8 34751.0 34796.7 36027.8 36236.3 13953.2 14061.1 14072.4 14077.1 14146.4 9353.7 9479.5 9497.4 9614.7 11366.5 18528.4 18696.7 19182.8 19779.9 19948.0 6929.0 6973.8 7042.1 7045.8 7070.7 804.2 908.4 914.5 971.0 972.6 27596.4 28679.1 28809.6 28958.9 29259.4 946.3 976.5 1015.5 1032.6 1087.8 15064.4 15168.9 15169.2 15282.7 15409.0 127176.0 127242.0 127503.4 127869.0 128175.0 33898.5 34410.1 34468.0 34511.8 34519.3 1090.9 1195.2 1224.6 1225.2 1246.4 28358.9 29567.7 29994.6 30117.5 30358.8 44080.6 44391.1 44513.8 44576.0 44635.6 302318.0 302513.0 302558.0 302749.0 303403.0 22270.0 24026.0 26450.7 27012.5 27794.9 43357.3 43509.6 43555.3 44398.0 44504.8 36372.5 37252.6 37681.0 38104.0 38259.6 37298.6 37500.8 37724.8 37905.8 37972.4 35426.0 36493.3 36828.3 36830.6 37121.8 33030.5 34050.2 34217.0 34515.3 34645.1 25864.7 26222.6 26223.8 26229.6 26411.2 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | [#2] jquery.html Cycles:180 Average:19505.36 Median:24839.54 stddev:47569.63 (191.5%) stddev-sans-first:47681.38 05:28:31 INFO - PROCESS | 5128 | Values: 369.6 370.3 377.8 390.5 392.0 15806.8 16924.6 17200.3 17426.6 17488.2 15551.8 16388.3 16414.0 16449.4 17343.2 4580.3 4739.1 4756.2 4761.7 4763.1 3452.1 3600.7 4708.3 5242.5 5520.6 5564.4 5581.3 5598.6 5602.5 5602.8 4986.8 5309.2 5315.6 5396.2 5575.4 3370.1 4969.9 5221.9 5276.8 5326.9 12017.6 12283.9 12528.7 12535.1 12622.5 1919.9 1984.0 1998.5 2168.5 2262.4 8958.0 9057.3 9065.2 9131.3 9312.0 1675.8 1689.7 1710.3 1747.9 1764.4 67301.7 72480.3 72547.6 72647.3 73247.6 9691.4 10369.7 10397.4 10759.7 11002.8 14257.6 14310.4 14380.2 14399.2 14403.9 7118.2 7448.4 7458.7 7602.9 7617.7 7086.1 7089.0 7237.2 7244.5 7272.5 4349.4 4366.6 4462.8 4526.1 4555.6 1655.5 1735.3 1803.7 1814.7 1858.0 817.2 824.2 831.0 836.4 896.7 16801.7 16931.2 17491.3 17698.2 17767.0 841.7 845.2 857.7 869.1 964.4 9530.1 9813.8 9857.0 9860.9 10078.1 136794.0 139975.0 145156.0 411461.0 412675.0 45216.3 45724.8 68272.3 72207.6 73537.0 906.4 911.4 917.2 933.8 937.5 14964.0 15189.4 15386.5 15484.6 15809.1 25510.2 27422.7 31190.2 35191.6 35748.3 43763.6 45985.0 47987.0 49171.3 49270.0 7566.5 7634.1 7679.5 7740.0 7810.5 11621.5 11809.2 11962.0 12226.0 12330.1 6509.8 9511.4 9887.1 10267.3 10419.8 6940.8 10596.2 10625.8 10647.4 10895.6 9570.6 10317.8 10572.5 10657.6 10743.7 6751.0 7033.4 7929.8 7959.4 8065.4 9747.0 10379.9 10413.7 10582.7 10798.4 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | [#3] mootools.html Cycles:180 Average:5172.20 Median:5160.32 stddev:2957.35 (57.3%) stddev-sans-first:2950.75 05:28:31 INFO - PROCESS | 5128 | Values: 1223.4 1372.0 1428.8 1479.8 1694.0 6719.7 7673.4 7965.2 7979.1 8190.5 7910.0 8050.8 8066.2 8088.0 8092.6 2233.9 2262.7 2265.0 2267.4 2267.9 2210.8 2230.2 2249.2 2252.0 2252.6 2175.3 2190.3 2193.7 2196.1 2210.6 2111.0 2114.7 2115.3 2117.3 2119.8 2081.6 2121.2 2125.1 2128.8 2131.4 7670.9 7914.2 8038.9 8094.4 8100.6 4582.1 4708.2 4725.6 4725.8 4740.0 2886.5 2990.3 3043.8 3051.7 3060.3 866.4 878.7 883.2 930.5 975.6 10792.0 10912.9 10940.6 10953.8 10980.1 4067.6 4163.7 4180.2 4219.0 4221.8 8501.0 8568.8 8571.7 8574.3 8575.1 4679.9 4694.8 4696.6 4698.6 4702.3 3531.7 3844.5 3977.5 4262.1 4302.5 3421.9 3472.5 3527.1 3558.3 3574.9 1052.4 1057.5 1057.7 1057.9 1082.6 701.6 703.7 792.7 810.9 822.2 4903.9 4980.9 5106.8 5157.7 5194.6 835.1 856.3 900.5 903.3 908.3 3372.6 3505.9 3509.1 3523.7 3540.5 9563.9 9907.3 9933.3 10054.2 10071.9 9904.5 9947.4 9976.5 9994.6 9998.6 7290.2 7431.0 7432.6 7445.7 7446.3 5163.0 5199.9 5306.9 5476.4 5492.5 8537.9 9063.5 9227.9 9308.3 9329.0 9208.4 9212.1 9365.8 9374.2 9452.7 6498.6 7285.7 7530.5 7538.3 7713.7 7689.4 7690.7 7749.8 7754.0 7777.4 6622.3 6650.4 6658.1 6726.9 6753.0 6639.6 6667.3 6727.6 6729.6 6732.6 6059.4 6069.0 6119.1 6130.0 6130.1 4242.2 4252.3 4253.5 4298.3 4327.7 4552.9 4571.2 4591.2 4605.7 4609.3 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | [#4] prototype.html Cycles:180 Average:4257.17 Median:4880.48 stddev:1225.01 (25.1%) stddev-sans-first:1191.91 05:28:31 INFO - PROCESS | 5128 | Values: 300.6 302.3 304.7 306.0 306.8 4887.6 4899.2 4911.9 4920.9 4921.9 4843.6 4853.8 4877.9 4880.0 4881.0 3657.9 3765.5 3767.2 3852.5 3885.1 3817.8 3860.0 3887.4 3887.7 3898.1 3687.2 3846.6 3850.2 3851.0 3851.4 3760.4 3802.7 3807.0 3810.7 3818.4 3389.3 3391.7 3402.3 3406.3 3419.3 4716.6 4742.0 4776.7 4778.8 4802.7 4200.3 4394.8 4396.3 4398.2 4401.5 5050.4 5056.6 5071.8 5077.5 5100.7 3734.3 3746.2 3760.9 3761.7 3778.2 5141.9 5298.8 5316.9 5321.8 5329.9 3748.9 3771.4 3791.2 3796.9 3807.4 3800.8 3809.7 3837.6 3840.8 3840.9 4348.1 4353.0 4354.5 4355.5 4385.5 4872.8 5023.3 5051.9 5072.4 5117.2 1330.6 1351.7 1354.6 1359.8 1362.8 862.7 875.9 876.4 879.7 881.4 4323.7 4343.0 4360.4 4501.1 4501.4 5212.2 5238.3 5245.6 5291.4 5295.5 4236.3 4425.0 4429.8 4448.5 4476.5 2761.6 2810.7 2814.2 2814.4 2824.5 4951.4 4952.9 4962.4 4963.1 4964.3 5111.7 5409.6 5410.2 5421.8 5422.1 4923.4 4941.6 4959.7 4971.3 4984.6 5311.4 5325.9 5328.4 5332.9 5347.7 4920.0 5238.3 5250.5 5256.7 5264.7 4919.0 4919.9 4923.2 4964.0 4978.7 3718.8 3744.7 3753.7 3761.7 3765.6 5152.5 5276.9 5322.9 5328.5 5378.1 5122.3 5135.9 5144.4 5145.0 5145.9 5124.6 5155.9 5160.9 5190.2 5208.5 4854.3 4949.9 5083.8 5086.6 5093.0 5034.5 5035.6 5040.7 5073.4 5093.9 4925.2 5024.3 5060.8 5082.4 5086.3 05:28:31 INFO - PROCESS | 5128 | 05:28:31 INFO - PROCESS | 5128 | [#5] yui.html Cycles:180 Average:18367.73 Median:504.02 stddev:27084.46 (5373.7%) stddev-sans-first:27127.76 05:28:31 INFO - PROCESS | 5128 | Values: 648.3 667.1 708.6 745.9 747.3 12725.4 13815.7 13837.7 13921.2 13968.6 12402.8 12427.8 13669.2 13695.5 13729.5 622.6 768.5 780.4 790.7 790.9 784.2 796.0 796.1 796.3 796.6 835.6 838.7 839.7 839.7 840.0 646.5 810.7 834.6 834.6 835.8 609.6 609.8 610.1 610.1 610.2 13952.8 14005.8 14012.1 14013.9 14014.6 13059.9 13131.8 13190.3 13691.2 13877.9 11722.4 11806.0 11891.3 11927.3 11951.2 4223.2 4237.7 4248.7 4263.9 4281.8 57845.7 57988.4 58026.7 58166.4 58444.4 9809.2 9944.1 10003.6 10033.9 10181.8 17713.3 17719.1 17799.4 17822.5 17824.5 6818.0 6919.4 6947.4 7037.0 7203.6 3583.8 3613.1 3631.0 3651.5 3706.4 2798.4 2872.0 2886.1 2888.3 2900.4 503.0 510.1 514.4 516.4 521.1 372.3 390.1 440.7 448.4 451.8 6466.5 6538.0 6554.8 6658.1 6744.6 459.0 466.8 474.9 479.5 505.0 3801.6 3839.2 3867.7 3941.7 4078.7 61284.0 82659.0 107130.5 110198.4 110329.0 83607.2 94589.0 95820.5 96816.7 97116.0 83674.0 85061.6 87044.0 87267.6 88267.0 7777.4 7867.1 7873.0 7874.6 8020.4 46830.8 46840.3 47608.8 47798.3 48809.3 64586.0 67510.0 72228.6 73114.3 73240.6 8800.5 8861.2 9054.3 9080.4 9696.2 10341.7 11997.2 13066.9 13249.9 13460.0 11486.7 11539.9 11585.6 12028.1 12431.3 11198.5 11252.8 11826.1 12373.1 13224.8 10143.3 10814.6 11180.7 11583.9 11763.7 9564.4 9588.1 9614.5 9743.3 9821.3 9138.5 9326.6 9342.1 9349.0 9351.5 05:28:31 INFO - PROCESS | 5128 | -------- Summary: end -------- 05:28:31 INFO - PROCESS | 5128 | 05:28:32 INFO - TEST-INFO | 5128: exit 0 05:28:32 INFO - TEST-OK | dromaeo_css | took 1120014ms 05:28:32 INFO - TEST-START | kraken 05:28:32 INFO - operating with platform_type : linux_ 05:28:32 INFO - Initialising browser for kraken test... 05:28:32 INFO - TEST-INFO | started process 5312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsl0oPi/profile http://localhost:45026/getInfo.html) 05:28:37 INFO - PROCESS | 5312 | __metrics Screen width/height:1600/1200 05:28:37 INFO - PROCESS | 5312 | colorDepth:24 05:28:37 INFO - PROCESS | 5312 | Browser inner width/height: 1024/697 05:28:37 INFO - PROCESS | 5312 | __metrics 05:28:38 INFO - PROCESS | 5312 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:39 INFO - TEST-INFO | 5312: exit 0 05:28:39 INFO - Browser initialized. 05:28:39 INFO - Running cycle 1/1 for kraken test... 05:28:39 INFO - TEST-INFO | started process 5394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsl0oPi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:51 INFO - PROCESS | 5394 | RSS: Main: 175800320 05:28:51 INFO - PROCESS | 5394 | 05:28:53 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45026/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:54 INFO - PROCESS | 5394 | RSS: Main: 233496576 05:28:54 INFO - PROCESS | 5394 | 05:28:57 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45026/tests/kraken/driver.html?testName=audio-dft) 05:28:58 INFO - PROCESS | 5394 | RSS: Main: 220549120 05:28:58 INFO - PROCESS | 5394 | 05:29:01 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45026/tests/kraken/driver.html?testName=audio-fft) 05:29:02 INFO - PROCESS | 5394 | RSS: Main: 287924224 05:29:02 INFO - PROCESS | 5394 | 05:29:04 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45026/tests/kraken/driver.html?testName=audio-oscillator) 05:29:04 INFO - PROCESS | 5394 | RSS: Main: 198287360 05:29:04 INFO - PROCESS | 5394 | 05:29:07 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45026/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:07 INFO - PROCESS | 5394 | RSS: Main: 202391552 05:29:07 INFO - PROCESS | 5394 | 05:29:10 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45026/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:11 INFO - PROCESS | 5394 | RSS: Main: 238252032 05:29:11 INFO - PROCESS | 5394 | 05:29:15 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45026/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:15 INFO - PROCESS | 5394 | RSS: Main: 238542848 05:29:15 INFO - PROCESS | 5394 | 05:29:18 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45026/tests/kraken/driver.html?testName=json-parse-financial) 05:29:19 INFO - PROCESS | 5394 | RSS: Main: 238620672 05:29:19 INFO - PROCESS | 5394 | 05:29:20 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45026/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:21 INFO - PROCESS | 5394 | RSS: Main: 226848768 05:29:21 INFO - PROCESS | 5394 | 05:29:23 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:24 INFO - PROCESS | 5394 | RSS: Main: 231813120 05:29:24 INFO - PROCESS | 5394 | 05:29:26 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:26 INFO - PROCESS | 5394 | RSS: Main: 238231552 05:29:26 INFO - PROCESS | 5394 | 05:29:29 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:30 INFO - PROCESS | 5394 | RSS: Main: 241106944 05:29:30 INFO - PROCESS | 5394 | 05:29:33 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:33 INFO - PROCESS | 5394 | RSS: Main: 239190016 05:29:33 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | Cycle 1(1): loaded http://localhost:45026/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45026/tests/kraken/driver.html?testName=ai-astar) 05:29:35 INFO - PROCESS | 5394 | RSS: Main: 281694208 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | __start_tp_report 05:29:35 INFO - PROCESS | 5394 | _x_x_mozilla_page_load 05:29:35 INFO - PROCESS | 5394 | _x_x_mozilla_page_load_details 05:29:35 INFO - PROCESS | 5394 | |i|pagename|runs| 05:29:35 INFO - PROCESS | 5394 | |0;ai-astar;105;114;115;113;116;115;113;115;115;115 05:29:35 INFO - PROCESS | 5394 | |1;audio-beat-detection;162;129;132;174;129;130;167;143;177;130 05:29:35 INFO - PROCESS | 5394 | |2;audio-dft;164;162;162;162;162;162;163;160;162;161 05:29:35 INFO - PROCESS | 5394 | |3;audio-fft;75;76;76;110;76;77;75;75;97;75 05:29:35 INFO - PROCESS | 5394 | |4;audio-oscillator;87;86;119;86;87;86;130;85;85;129 05:29:35 INFO - PROCESS | 5394 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;117;118 05:29:35 INFO - PROCESS | 5394 | |6;imaging-darkroom;145;146;146;148;147;147;146;146;146;146 05:29:35 INFO - PROCESS | 5394 | |7;imaging-desaturate;95;96;96;96;96;96;96;96;95;95 05:29:35 INFO - PROCESS | 5394 | |8;json-parse-financial;64;64;65;65;64;74;65;64;67;64 05:29:35 INFO - PROCESS | 5394 | |9;json-stringify-tinderbox;65;65;65;65;64;65;65;65;65;65 05:29:35 INFO - PROCESS | 5394 | |10;stanford-crypto-aes;89;90;82;83;82;83;82;83;98;82 05:29:35 INFO - PROCESS | 5394 | |11;stanford-crypto-ccm;143;134;130;132;131;131;130;128;140;137 05:29:35 INFO - PROCESS | 5394 | |12;stanford-crypto-pbkdf2;169;180;177;174;168;174;175;167;175;170 05:29:35 INFO - PROCESS | 5394 | |13;stanford-crypto-sha256-iterative;68;67;68;68;67;67;70;67;66;66 05:29:35 INFO - PROCESS | 5394 | __end_tp_report 05:29:35 INFO - PROCESS | 5394 | __start_cc_report 05:29:35 INFO - PROCESS | 5394 | _x_x_mozilla_cycle_collect,1914 05:29:35 INFO - PROCESS | 5394 | __end_cc_report 05:29:35 INFO - PROCESS | 5394 | __startTimestamp1466771375873__endTimestamp 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | ------- Summary: start ------- 05:29:35 INFO - PROCESS | 5394 | Number of tests: 14 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#0] ai-astar Cycles:10 Average:113.60 Median:115.00 stddev:3.17 (2.8%) stddev-sans-first:1.01 05:29:35 INFO - PROCESS | 5394 | Values: 105.0 114.0 115.0 113.0 116.0 115.0 113.0 115.0 115.0 115.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:152.50 stddev:20.33 (13.3%) stddev-sans-first:20.86 05:29:35 INFO - PROCESS | 5394 | Values: 162.0 129.0 132.0 174.0 129.0 130.0 167.0 143.0 177.0 130.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.05 (0.7%) stddev-sans-first:0.83 05:29:35 INFO - PROCESS | 5394 | Values: 164.0 162.0 162.0 162.0 162.0 162.0 163.0 160.0 162.0 161.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:12.16 (16.0%) stddev-sans-first:12.69 05:29:35 INFO - PROCESS | 5394 | Values: 75.0 76.0 76.0 110.0 76.0 77.0 75.0 75.0 97.0 75.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:86.00 stddev:19.54 (22.7%) stddev-sans-first:20.32 05:29:35 INFO - PROCESS | 5394 | Values: 87.0 86.0 119.0 86.0 87.0 86.0 130.0 85.0 85.0 129.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:29:35 INFO - PROCESS | 5394 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 118.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 05:29:35 INFO - PROCESS | 5394 | Values: 145.0 146.0 146.0 148.0 147.0 147.0 146.0 146.0 146.0 146.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 05:29:35 INFO - PROCESS | 5394 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:65.00 stddev:3.10 (4.8%) stddev-sans-first:3.23 05:29:35 INFO - PROCESS | 5394 | Values: 64.0 64.0 65.0 65.0 64.0 74.0 65.0 64.0 67.0 64.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 05:29:35 INFO - PROCESS | 5394 | Values: 65.0 65.0 65.0 65.0 64.0 65.0 65.0 65.0 65.0 65.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:83.00 stddev:5.34 (6.4%) stddev-sans-first:5.50 05:29:35 INFO - PROCESS | 5394 | Values: 89.0 90.0 82.0 83.0 82.0 83.0 82.0 83.0 98.0 82.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:133.00 stddev:4.88 (3.7%) stddev-sans-first:3.81 05:29:35 INFO - PROCESS | 5394 | Values: 143.0 134.0 130.0 132.0 131.0 131.0 130.0 128.0 140.0 137.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:174.50 stddev:4.23 (2.4%) stddev-sans-first:4.24 05:29:35 INFO - PROCESS | 5394 | Values: 169.0 180.0 177.0 174.0 168.0 174.0 175.0 167.0 175.0 170.0 05:29:35 INFO - PROCESS | 5394 | 05:29:35 INFO - PROCESS | 5394 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.17 (1.7%) stddev-sans-first:1.22 05:29:35 INFO - PROCESS | 5394 | Values: 68.0 67.0 68.0 68.0 67.0 67.0 70.0 67.0 66.0 66.0 05:29:35 INFO - PROCESS | 5394 | -------- Summary: end -------- 05:29:35 INFO - PROCESS | 5394 | 05:29:36 INFO - TEST-INFO | 5394: exit 0 05:29:36 INFO - TEST-OK | kraken | took 64340ms 05:29:36 INFO - SUITE-END | took 1184s 05:29:37 INFO - Completed test suite (00:19:44) 05:29:37 INFO - javascript benchmark 05:29:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2644.048142668639, 2648.430587423706, 2702.729727027297, 2716.022231996481, 2717.0, 4575.862724118273, 4625.5836974672275, 4689.249720044793, 4789.904201915964, 4853.635977301698, 4730.550597693222, 4765.785539650716, 4785.066911952171, 4790.065937142259, 4810.206315957871, 4337.089211265638, 4348.695591308609, 4391.209582275189, 4418.160549495602, 4564.726116433008, 4416.183006143858, 4488.05750792334, 4521.977390113061, 4529.524399938008, 4549.818007279705, 4257.76582287974, 4337.306031035019, 4352.717073390219, 4409.0, 4420.580044895762, 4352.695311328208, 4374.190774706658, 4374.8906277343, 4408.0522687622315, 4447.688661793676, 4390.078083602447, 4406.25093734066, 4411.051623900845, 4414.470263568361, 4458.4426946631675, 4631.953680463186, 4748.667593268473, 4766.73782941938, 4807.519248075165, 4812.085703716282, 5162.070827251093, 5164.845054648329, 5181.611379146579, 5205.583553315726, 5216.504432078947, 5199.6360254782185, 5218.60860435465, 5231.424543300234, 5235.240890070956, 5238.92141617876, 4635.930461043054, 4733.408323959505, 4811.542903424169, 4818.71087734737, 4829.468758436569, 5256.080185967517, 5292.867678308073, 5297.099493086184, 5341.0653135701095, 5351.598630102758, 4867.853964381075, 5073.239014147834, 5073.695578265316, 5115.897682046339, 5120.385553733501, 5025.095482813055, 5033.697978121324, 5090.007448547498, 5151.613628977767, 5165.173572228426, 5072.898542029138, 5075.3148324975655, 5078.314427552307, 5082.669626474268, 5131.846044618667, 5011.448740638527, 5040.193569029011, 5052.166392545262, 5056.191009438472, 5058.848234552969, 4256.553061928453, 4297.742135471882, 4299.97850010748, 4355.956440435555, 4383.232934236496, 3832.4442955771815, 3857.479240302523, 3887.78617176058, 3897.4348719435525, 3918.5689574146822, 4333.71830830995, 4355.412019377407, 4361.847335343247, 4370.278903980871, 4419.18245124653, 4886.438059623118, 4990.525900039491, 5168.586513078944, 5180.585553155738, 5227.634065615371, 4326.199653064194, 4369.12617476506, 4376.6498680105515, 4378.956210437918, 4404.097160082176, 4629.722216667011, 4646.674732768742, 4647.1635105680625, 4655.604273636779, 4702.741349225757, 4611.423572053493, 4797.4482934462285, 4810.062037902575, 4814.566688998007, 4815.061063092803, 5118.462561431145, 5126.0, 5176.922346164735, 5198.038362902875, 5215.061288968022, 4797.112534181188, 4815.494373090775, 4829.589484893823, 4837.419509658864, 4883.047805678001, 4893.461719210955, 5104.719240441811, 5114.5652619527755, 5115.25828754821, 5159.406668233051, 4979.228219625964, 5054.519820617035, 5069.7718602662235, 5085.237214417867, 5087.186050231945, 4994.9750251248515, 4999.999999999942, 5001.424836143745, 5002.749862506933, 5043.722595257296, 4838.298446725275, 4981.800727970841, 4982.202847544376, 4990.101781679187, 5035.546800787946, 4858.732769697559, 4860.441049279378, 4884.511548845229, 4969.55274025325, 5014.7994080236385, 5046.318746969259, 5051.368578927634, 5051.949480505148, 5056.595472362129, 5098.643094983316, 4757.119932812441, 4867.464578896389, 4950.529699678525, 4956.355673762388, 5000.724960127082], "value": 4702.082636159721, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.9168735268742, 578.6354596604139, 586.1793489115238, 599.5653151465189, 605.2071785960395, 26964.730352696468, 39280.661143802776, 40272.59727402725, 40277.3958390624, 40303.19393612126, 41643.58356416435, 41730.0, 41751.79124104375, 41839.58160418395, 41856.16287674245, 7587.203343648893, 7887.842243155119, 8041.7185398511065, 8048.195180481964, 8048.517088974651, 10112.544935477921, 10417.27079104463, 10435.32170408921, 10726.0, 10755.569777208902, 10353.482325883713, 10451.581936722521, 10461.320014199091, 10474.58101675932, 10481.318714283585, 7609.7336593219525, 10156.339837910513, 10275.280730348879, 10346.999999999962, 10391.960803919623, 10249.231307651957, 10312.690619281433, 10333.844992325121, 10338.328008679411, 10342.327748696385, 40283.59716402828, 40657.186856262706, 40659.7967010166, 40662.79668601668, 40707.38938915919, 3076.292452735861, 3188.952165717516, 3410.9449775595776, 3691.409374500094, 3789.4505296732095, 28592.0, 30633.387332253453, 30711.846440767877, 31253.37493250145, 31850.203744906332, 3588.766730162557, 3600.765950213228, 3655.798931058791, 3656.4149736042104, 3717.553893532793, 259864.0, 265156.9999999981, 265927.0, 266453.0, 267002.0, 55893.0, 56165.7191714047, 56384.0, 56548.43451565432, 56563.43436565664, 33812.830935845166, 34751.0, 34796.65203347934, 36027.81986090105, 36236.275274494365, 13953.232572208626, 14061.086029408056, 14072.366743496566, 14077.084989475856, 14146.434142634385, 9353.719388418358, 9479.526023698787, 9497.43015419077, 9614.663486777928, 11366.545338186379, 18528.351507697167, 18696.719549206773, 19182.808171918383, 19779.901100494404, 19948.0, 6928.960655901655, 6973.79078627642, 7042.119735033121, 7045.788626341218, 7070.681819318143, 804.2359758229705, 908.3596064774318, 914.4741773480275, 970.9611615535441, 972.6498460554195, 27596.37834405728, 28679.139625810843, 28809.604531041205, 28958.855205723838, 29259.414811703646, 946.2808265718104, 976.4629453800381, 1015.4871789746131, 1032.5869652139054, 1087.842262871895, 15064.397424102914, 15168.924155379153, 15169.24153792306, 15282.694346113238, 15409.0, 127176.0, 127242.0, 127503.362483187, 127869.0, 128175.0, 33898.49152262718, 34410.13974650654, 34468.0, 34511.82744086314, 34519.309613807585, 1090.929089609173, 1195.1753290229713, 1224.5958833584896, 1225.154643296123, 1246.401727170945, 28358.85820570884, 29567.704322956495, 29994.550081748355, 30117.548236776027, 30358.84820575883, 44080.559194407644, 44391.11217775562, 44513.777431112634, 44576.0, 44635.553644463136, 302318.0, 302513.0, 302558.0, 302749.0, 303403.0, 22270.0, 24026.0, 26450.735492644828, 27012.45975080527, 27794.861025694743, 43357.34963975606, 43509.564904350555, 43555.34666980061, 44398.0, 44504.77747611241, 36372.454413183295, 37252.62747372492, 37681.0, 38104.0, 38259.61740382561, 37298.627013729514, 37500.81249593844, 37724.81137594295, 37905.81047094747, 37972.43041354327, 35426.0, 36493.27013459769, 36828.26343473169, 36830.63169368272, 37121.81439092787, 33030.5045424314, 34050.16849115706, 34217.0, 34515.309693806485, 34645.13387165341, 25864.741352586232, 26222.60666089972, 26223.838947578388, 26229.606555902057, 26411.207663770118], "value": 17471.44527491495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.58606360875814, 370.2520907766311, 377.7884384744543, 390.5294120584696, 391.9764814111152, 15806.762898556532, 16924.57688557785, 17200.3119875205, 17426.56433589161, 17488.21303041363, 15551.844481555181, 16388.344466221337, 16414.0, 16449.424270150546, 17343.219555120017, 4580.312953057043, 4739.14695354836, 4756.1914474539235, 4761.714297142165, 4763.14263432582, 3452.085197422685, 3600.71194304455, 4708.270218116198, 5242.475752424746, 5520.558355331584, 5564.415736347686, 5581.274434323533, 5598.600349912522, 5602.495775380215, 5602.7758889644565, 4986.825461108862, 5309.230161626581, 5315.601329900253, 5396.217548455491, 5575.414581468928, 3370.1069216657606, 4969.875753106165, 5221.869453263699, 5276.815311464119, 5326.92009619856, 12017.579384721581, 12283.877161228364, 12528.749425011449, 12535.122541422104, 12622.495100195982, 1919.865609407342, 1984.0179111339978, 1998.5203551147765, 2168.4687251623295, 2262.4343914021497, 8957.955210223972, 9057.275417966614, 9065.22945549629, 9131.315151363675, 9311.953440232823, 1675.8407951244612, 1689.684476272352, 1710.2731339180798, 1747.860171186302, 1764.3824661368546, 67301.66349168272, 72480.27519724841, 72547.63726181441, 72647.27352726458, 73247.63376183138, 9691.370060946016, 10369.688909332732, 10397.428141452292, 10759.677209683721, 11002.834957475645, 14257.572272831832, 14310.427582896567, 14380.209088500023, 14399.208043557495, 14403.92798036024, 7118.181409138016, 7448.424756117094, 7458.73894413693, 7602.923970760332, 7617.733379331697, 7086.149662040588, 7089.042979197846, 7237.167725711505, 7244.492885497964, 7272.527285726516, 4349.369341445473, 4366.585174408426, 4462.754548499799, 4526.140033393644, 4555.63554915606, 1655.4950740024167, 1735.2885317020214, 1803.729440583923, 1814.6642871068896, 1857.9840872653053, 817.1950628630794, 824.2046425199711, 830.9958450207711, 836.4479443567217, 896.6861598440624, 16801.747973780402, 16931.153442327835, 17491.300347985936, 17698.203580838894, 17767.0, 841.7382642261238, 845.2139510255521, 857.7469646454311, 869.0787764969153, 964.4309857184295, 9530.142287194185, 9813.80372392548, 9857.0, 9860.901390986142, 10078.09297163244, 136794.0, 139975.0, 145156.0, 411461.0, 412675.0, 45216.321755173696, 45724.771376142904, 68272.3172768266, 72207.63896180486, 73537.0, 906.4063038709655, 911.3666002128457, 917.1607978699549, 933.7525555727738, 937.4703292639637, 14964.0, 15189.392424302903, 15386.538403847902, 15484.612884677974, 15809.13049782273, 25510.234692959242, 27422.72577274202, 31190.22024449407, 35191.648083518834, 35748.28503429969, 43763.56236437722, 45985.0, 47987.0, 49171.25415871682, 49270.0, 7566.470347075652, 7634.122075961113, 7679.500832445873, 7739.961300193463, 7810.492317999539, 11621.535138594361, 11809.23239989398, 11962.0, 12226.0, 12330.136890417585, 6509.837254068686, 9511.383064878848, 9887.110160085627, 10267.281290309607, 10419.843702344622, 6940.826479338057, 10596.15230781551, 10625.840612390975, 10647.361158330525, 10895.564177432814, 9570.56932438028, 10317.793644127225, 10572.471376431302, 10657.626983055556, 10743.677689669323, 6750.966245168743, 7033.402160816183, 7929.762107136795, 7959.4030447715495, 8065.395095367753, 9746.951265243628, 10379.896201037893, 10413.739656508647, 10582.735431614272, 10798.352098874093], "value": 7847.829867717932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1223.3760782001177, 1372.0464277327255, 1428.8285405751312, 1479.7558402863528, 1693.9576510587233, 6719.697613607387, 7673.424493163007, 7965.243301886326, 7979.122296547383, 8190.54951977641, 7909.960450197756, 8050.838983220332, 8066.2337077977645, 8088.029436467617, 8092.59537023148, 2233.932982010538, 2262.671912572676, 2264.954700905981, 2267.4444761033487, 2267.8979445924974, 2210.7568167501563, 2230.163688616769, 2249.201533455624, 2252.042881775247, 2252.6395776675736, 2175.282156888223, 2190.310052333518, 2193.7367515898113, 2196.077647388101, 2210.624193887043, 2111.0183764549406, 2114.7250857388594, 2115.2702317700446, 2117.3224568138203, 2119.798619131188, 2081.55246621977, 2121.2469573301564, 2125.064971412589, 2128.7653161166486, 2131.4138611881704, 7670.884936725954, 7914.248146426138, 8038.919610803934, 8094.392920530925, 8100.594970251464, 4582.083583283324, 4708.199606066977, 4725.5510726480925, 4725.834595789131, 4739.9526004739855, 2886.451574200916, 2990.312228187507, 3043.7717171212025, 3051.725344718986, 3060.2808340040074, 866.4411454611803, 878.7100256915203, 883.1654086887894, 930.5004543265287, 975.5756246032928, 10792.0, 10912.945435272852, 10940.617078402298, 10953.83569246454, 10980.066694330992, 4067.633912947849, 4163.7293575917465, 4180.24755544005, 4219.0, 4221.831126754895, 8501.0, 8568.785780355416, 8571.742847714579, 8574.271186949054, 8575.056743758181, 4679.9298010529865, 4694.7887345068875, 4696.624270058387, 4698.647601429906, 4702.27114797207, 3531.7174626029855, 3844.5386553613744, 3977.5084343371236, 4262.062346283813, 4302.548232435612, 3421.8802341917913, 3472.5138480612736, 3527.0829584307753, 3558.3417067842533, 3574.910627234288, 1052.3896140238644, 1057.4659796802719, 1057.6509751781894, 1057.8889216632297, 1082.577794660083, 701.5825583777644, 703.6657587645827, 792.7146227358146, 810.9026916770025, 822.1778221778102, 4903.852884413402, 4980.90038199234, 5106.846794596168, 5157.664751791197, 5194.636375453682, 835.0939230934383, 856.3448961544424, 900.5182227508252, 903.3179949138358, 908.3323757038571, 3372.612149602778, 3505.9123521911647, 3509.0700964244497, 3523.735719821024, 3540.46892966052, 9563.856542151872, 9907.25695572835, 9933.304668673123, 10054.195664346835, 10071.899281007243, 9904.504774761233, 9947.353422027545, 9976.45129517869, 9994.550245238981, 9998.600055997678, 7290.161631412456, 7430.96284518574, 7432.628368581657, 7445.702171913063, 7446.329830315298, 5162.97418512905, 5199.920031987235, 5306.8938621228135, 5476.397596264328, 5492.5331346835965, 8537.914620853711, 9063.546822658709, 9227.907720922705, 9308.255339572954, 9329.0, 9208.355415120877, 9212.078792120734, 9365.765855853386, 9374.156325930699, 9452.669156579486, 6498.577592456475, 7285.744998925013, 7530.510516816389, 7538.3215510604305, 7713.691452341844, 7689.423293252917, 7690.692372305269, 7749.7675069748, 7754.0, 7777.416693748105, 6622.337766223339, 6650.401463868275, 6658.101156344025, 6726.8654626908165, 6752.966235168793, 6639.568428052161, 6667.299933506912, 6727.596344219362, 6729.596224226562, 6732.596044237362, 6059.3637668045985, 6069.0, 6119.112728654409, 6130.049842274455, 6130.111133885473, 4242.236397448489, 4252.277112890878, 4253.532111467674, 4298.333758267474, 4327.7186982846015, 4552.931706024478, 4571.222892108283, 4591.196540605448, 4605.700629459075, 4609.331646911112], "value": 4128.263327208797, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.61671369004523, 302.31073153210673, 304.73031367239986, 305.9908202753918, 306.7561288775423, 4887.608991280698, 4899.16714158593, 4911.9263211051775, 4920.86820031393, 4921.9015619687625, 4843.612510999121, 4853.757312134388, 4877.902441951159, 4879.951200487994, 4881.0, 3657.9451308230396, 3765.472833803256, 3767.2088861339016, 3852.460655508217, 3885.1258466845015, 3817.7709337439705, 3859.9614003859883, 3887.358585833348, 3887.650111489965, 3898.1229223424784, 3687.1888184599347, 3846.595992462745, 3850.17221297421, 3850.9807450962844, 3851.383778595425, 3760.398336266198, 3802.714796390254, 3806.9530879008216, 3810.6951443884564, 3818.427235914607, 3389.2713066690535, 3391.6608339166132, 3402.302527981758, 3406.3017081498233, 3419.281950790337, 4716.646251531149, 4741.976290118562, 4776.665633405663, 4778.832740854054, 4802.703270117084, 4200.260657987976, 4394.8461803837035, 4396.318570621559, 4398.208322501949, 4401.537838526972, 5050.368703912011, 5056.62075344347, 5071.822486213002, 5077.49225077493, 5100.719460429675, 3734.25314937011, 3746.1571146492092, 3760.9247815043545, 3761.6614504694708, 3778.2065766189135, 5141.948580514184, 5298.788048478056, 5316.867078323035, 5321.813736519203, 5329.920051199197, 3748.887533374003, 3771.4342848572933, 3791.2417516496257, 3796.9430458543143, 3807.428885667172, 3800.7529510582276, 3809.7333186676665, 3837.5586807516934, 3840.8093491017876, 3840.9039774005873, 4348.130373925228, 4352.955290730202, 4354.455693038371, 4355.455568053993, 4385.473743150778, 4872.8315899424415, 5023.296738456619, 5051.924221136685, 5072.442031376546, 5117.232415137759, 1330.647378444713, 1351.7431687979251, 1354.607163922471, 1359.7756370199004, 1362.781954887233, 862.6894318045498, 875.8817559629368, 876.4303202918027, 879.7492714576315, 881.3918396306528, 4323.718958267703, 4343.0, 4360.41134446852, 4501.0547784964865, 4501.392312037899, 5212.201790382312, 5238.266642669953, 5245.632805703641, 5291.444398338119, 5295.549878260314, 4236.322188449833, 4424.977875110668, 4429.80065897029, 4448.532904045093, 4476.485204201558, 2761.550186152286, 2810.7189281072137, 2814.183886672883, 2814.3667674773014, 2824.5480723084206, 4951.375770841388, 4952.910359720856, 4962.354893863847, 4963.131451995886, 4964.329815474937, 5111.67418824434, 5409.648372855751, 5410.242566040757, 5421.810236641699, 5422.1053526168525, 4923.409190897115, 4941.604671626261, 4959.70241785494, 4971.28490670718, 4984.576311013532, 5311.3891902431715, 5325.9201111982575, 5328.413874473727, 5332.9466705334, 5347.679139251657, 4920.040592084652, 5238.266642669953, 5250.474952504719, 5256.737163141904, 5264.657797243167, 4919.0, 4919.877003074808, 4923.236898280772, 4964.0, 4978.651494395358, 3718.795466249382, 3744.7191460641106, 3753.680937120266, 3761.7366784324836, 3765.566959799658, 5152.4847515248175, 5276.868078298074, 5322.946770532245, 5328.547073498796, 5378.112611419072, 5122.308488354173, 5135.87160320995, 5144.430948560725, 5144.9742751286, 5145.897082058263, 5124.590032797441, 5155.896882062413, 5160.922586161136, 5190.195519694454, 5208.479152084761, 4854.296127061627, 4949.925751113664, 5083.796648134034, 5086.593072554261, 5092.9745351273, 5034.546890779848, 5035.59715222774, 5040.7479626019285, 5073.37651951372, 5093.949060509496, 4925.24186455586, 5024.271480635361, 5060.822871199491, 5082.364704411949, 5086.313347698014], "value": 3869.3027371645026, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.2933602373412, 667.0727688512968, 708.6350529477317, 745.8545583611198, 747.3460721868365, 12725.42735576899, 13815.654608634777, 13837.723245535084, 13921.23433211173, 13968.580942571727, 12402.81395779064, 12427.813582796243, 13669.248191349472, 13695.520656777015, 13729.45082196711, 622.5704264057808, 768.4927947554615, 780.4224873593542, 790.6797746912481, 790.93277071449, 784.2157842157842, 795.9681612735484, 796.1202870827739, 796.3151689546985, 796.5937371940323, 835.5863847395517, 838.7232213369604, 839.6725277141919, 839.689314953468, 839.9622017009249, 646.5312648329971, 810.7162493127418, 834.5993922917004, 834.5993922917033, 835.761807884753, 609.6189881324173, 609.7500024989733, 610.0605068194974, 610.1092405088576, 610.1701685707434, 13952.790708139386, 14005.789913151311, 14012.089214201147, 14013.929930350385, 14014.649633759238, 13059.869401305961, 13131.803022954664, 13190.274534900576, 13691.178529288274, 13877.930610346984, 11722.355364534862, 11806.027533176237, 11891.28652280866, 11927.343996080212, 11951.223170493977, 4223.176480586287, 4237.724547904407, 4248.702590818644, 4263.893402664927, 4281.828726850922, 57845.661575382015, 57988.420115798304, 58026.70986645082, 58166.418335816525, 58444.41555584432, 9809.166220871162, 9944.105030547285, 10003.599856005823, 10033.949830250947, 10181.796364072678, 17713.29146834138, 17719.114044297734, 17799.377021804175, 17822.46532604024, 17824.46526604204, 6817.9659101705165, 6919.37725604698, 6947.3747362736585, 7036.964815175891, 7203.567785932861, 3583.8387272572795, 3613.1328481164333, 3631.0, 3651.507046548735, 3706.388445906395, 2798.4263226038615, 2871.956920646192, 2886.148586167086, 2888.306806366499, 2900.4054168895327, 503.0341743851813, 510.14295982749337, 514.385309555078, 516.4474012806336, 521.0698902459093, 372.261061792342, 390.0813584009673, 440.67390131303245, 448.401384152154, 451.82153049545246, 6466.547341686037, 6538.0, 6554.803355899236, 6658.1011563438315, 6744.5616034957575, 458.9701669391479, 466.79461037143545, 474.86466357088756, 479.53485119434094, 505.00513987444674, 3801.6388443098413, 3839.212961342918, 3867.7099217558793, 3941.743786653916, 4078.7144899857317, 61284.0, 82659.0, 107130.46434767776, 110198.44900775605, 110329.0, 83607.16392836116, 94589.0, 95820.52089739506, 96816.67508287112, 97116.0, 83674.0, 85061.57469212615, 87044.0, 87267.56366218255, 88267.0, 7777.377809775091, 7867.095284042409, 7872.976513053266, 7874.56689882062, 8020.358371330163, 46830.76584617055, 46840.297395538415, 47608.76195619, 47798.283025753946, 48809.2678609814, 64586.0, 67510.0, 72228.63885680538, 73114.26885731074, 73240.63379683068, 8800.515971621622, 8861.158189971942, 9054.27565794748, 9080.364374493722, 9696.175825054666, 10341.689749307532, 11997.16019878635, 13066.934665326613, 13249.867501325249, 13459.990500712682, 11486.655400338002, 11539.942300288782, 11585.594504192313, 12028.09789265791, 12431.254124752544, 11198.496067677137, 11252.83120753173, 11826.113041521749, 12373.13388062863, 13224.80481731696, 10143.34068285559, 10814.621488247874, 11180.72048198769, 11583.942080289882, 11763.705907352043, 9564.426134431957, 9588.089131532493, 9614.471204083842, 9743.26925480606, 9821.263405244492, 9138.518195857696, 9326.580303886205, 9342.112499312554, 9349.0, 9351.48566828831], "value": 6502.345098403022, "unit": "score"}], "name": "dromaeo_css", "value": 6372.567482407621, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 115.0, 113.0, 116.0, 115.0, 113.0, 115.0, 115.0, 115.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 129.0, 132.0, 174.0, 129.0, 130.0, 167.0, 143.0, 177.0, 130.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 162.0, 162.0, 162.0, 163.0, 160.0, 162.0, 161.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 110.0, 76.0, 77.0, 75.0, 75.0, 97.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 119.0, 86.0, 87.0, 86.0, 130.0, 85.0, 85.0, 129.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 148.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 64.0, 74.0, 65.0, 64.0, 67.0, 64.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 82.0, 83.0, 82.0, 83.0, 82.0, 83.0, 98.0, 82.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 134.0, 130.0, 132.0, 131.0, 131.0, 130.0, 128.0, 140.0, 137.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 180.0, 177.0, 174.0, 168.0, 174.0, 175.0, 167.0, 175.0, 170.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 67.0, 67.0, 70.0, 67.0, 66.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1550.8000000000002, "alertThreshold": 2.0}]} 05:29:37 INFO - Return code: 0 05:29:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:37 INFO - # TBPL SUCCESS # 05:29:37 INFO - Running post-action listener: _resource_record_post_action 05:29:37 INFO - ##### 05:29:37 INFO - ##### Finished run-tests step (success) 05:29:37 INFO - ##### 05:29:37 INFO - Running post-run listener: _resource_record_post_run 05:29:37 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 545423360; Read time: 8; Write time: 536592 05:29:37 INFO - TinderboxPrint: CPU usage
13.2% 05:29:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 05:29:37 INFO - TinderboxPrint: I/O write bytes / time
545,423,360 / 536,592 05:29:37 INFO - TinderboxPrint: CPU idle
8,328.6 (86.8%) 05:29:37 INFO - TinderboxPrint: CPU user
1,214.4 (12.7%) 05:29:37 INFO - TinderboxPrint: Swap in / out
0 / 0 05:29:37 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 246124544; Read time: 0; Write time: 316700 05:29:37 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295682048; Read time: 8; Write time: 219808 05:29:37 INFO - Running post-run listener: _upload_blobber_files 05:29:37 INFO - Blob upload gear active. 05:29:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:37 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 05:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:39 INFO - (blobuploader) - INFO - Done attempting. 05:29:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:42 INFO - (blobuploader) - INFO - Done attempting. 05:29:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:43 INFO - (blobuploader) - INFO - Done attempting. 05:29:43 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:43 INFO - Return code: 0 05:29:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1b4c84b418f45b3d0e7c83020699e738ff7be388e5799e1b30a8a9bfaed909cc82329442358798dc127bf3e277c6497b1464d762463769fae6890ef957688c67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/450554218c9a59b12f7fb97ef0870628bdd8c3b6386f7d8eb797d51931fb06d6a1e60f80c2249c9dc47ad1b2f929692aa268ae5680439586bc223894a87a0926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f339d2c5e4a321dd012c83c29c143eab1c971833eb834d92c857d39e7943ff96ca912a9b823d48982edeab792da5797004f8d3ee2dba1319e44714ff057c86b2"} 05:29:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:43 INFO - Contents: 05:29:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1b4c84b418f45b3d0e7c83020699e738ff7be388e5799e1b30a8a9bfaed909cc82329442358798dc127bf3e277c6497b1464d762463769fae6890ef957688c67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/450554218c9a59b12f7fb97ef0870628bdd8c3b6386f7d8eb797d51931fb06d6a1e60f80c2249c9dc47ad1b2f929692aa268ae5680439586bc223894a87a0926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f339d2c5e4a321dd012c83c29c143eab1c971833eb834d92c857d39e7943ff96ca912a9b823d48982edeab792da5797004f8d3ee2dba1319e44714ff057c86b2"} 05:29:43 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:43 INFO - Copying logs to upload dir... 05:29:43 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.210815 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-24 05:29:42.434618) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-24 05:29:42.438505) ========= 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=836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1b4c84b418f45b3d0e7c83020699e738ff7be388e5799e1b30a8a9bfaed909cc82329442358798dc127bf3e277c6497b1464d762463769fae6890ef957688c67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/450554218c9a59b12f7fb97ef0870628bdd8c3b6386f7d8eb797d51931fb06d6a1e60f80c2249c9dc47ad1b2f929692aa268ae5680439586bc223894a87a0926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f339d2c5e4a321dd012c83c29c143eab1c971833eb834d92c857d39e7943ff96ca912a9b823d48982edeab792da5797004f8d3ee2dba1319e44714ff057c86b2"} build_url:https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011623 build_url: 'https://queue.taskcluster.net/v1/task/YE9GQSWqQRi7CSf592BOJw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1b4c84b418f45b3d0e7c83020699e738ff7be388e5799e1b30a8a9bfaed909cc82329442358798dc127bf3e277c6497b1464d762463769fae6890ef957688c67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/450554218c9a59b12f7fb97ef0870628bdd8c3b6386f7d8eb797d51931fb06d6a1e60f80c2249c9dc47ad1b2f929692aa268ae5680439586bc223894a87a0926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f339d2c5e4a321dd012c83c29c143eab1c971833eb834d92c857d39e7943ff96ca912a9b823d48982edeab792da5797004f8d3ee2dba1319e44714ff057c86b2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-24 05:29:42.464609) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:29:42.464946) ========= 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=836cfdc75598d54cb747368e000001a9-1466770136.706310-2044590013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004731 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:29:42.547635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-24 05:29:42.547971) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-24 05:29:42.548319) ========= ========= Total master_lag: 0.66 =========