builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1465474102.04 results: success (0) buildid: 20160609043328 builduid: d8866705666f4b9287bc69ac21565cdb revision: 1c86ba5d7a5b9cb16ef3ad77ff740688a923108a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.035608) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.036021) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.036348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.402973) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.403265) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-09 05:08:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-06-09 05:08:22 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109409 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.529976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.533295) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017413 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.583768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:22.584098) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1c86ba5d7a5b9cb16ef3ad77ff740688a923108a --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 1c86ba5d7a5b9cb16ef3ad77ff740688a923108a --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=c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-09 05:08:23,017 truncating revision to first 12 chars 2016-06-09 05:08:23,018 Setting DEBUG logging. 2016-06-09 05:08:23,018 attempt 1/10 2016-06-09 05:08:23,018 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1c86ba5d7a5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-09 05:08:23,601 unpacking tar archive at: fx-team-1c86ba5d7a5b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924411 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:23.521952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:23.522284) ========= script_repo_revision: 1c86ba5d7a5b9cb16ef3ad77ff740688a923108a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:23.522698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:23.522977) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 05:08:23.532228) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-06-09 05:08:23.532566) ========= /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=c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:08:24 INFO - MultiFileLogger online at 20160609 05:08:24 in /builds/slave/test 05:08:24 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:08:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:24 INFO - {'append_to_log': False, 05:08:24 INFO - 'base_work_dir': '/builds/slave/test', 05:08:24 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:08:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:24 INFO - 'branch': 'Fx-Team-Non-PGO', 05:08:24 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:24 INFO - 'config_files': ('talos/linux_config.py',), 05:08:24 INFO - 'default_actions': ('clobber', 05:08:24 INFO - 'read-buildbot-config', 05:08:24 INFO - 'download-and-extract', 05:08:24 INFO - 'populate-webroot', 05:08:24 INFO - 'create-virtualenv', 05:08:24 INFO - 'install', 05:08:24 INFO - 'run-tests'), 05:08:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:24 INFO - 'download_minidump_stackwalk': True, 05:08:24 INFO - 'download_symbols': 'ondemand', 05:08:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:24 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:24 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:24 INFO - 'installer_path': 'installer.exe', 05:08:24 INFO - 'log_level': 'info', 05:08:24 INFO - 'log_name': 'talos', 05:08:24 INFO - 'log_to_console': True, 05:08:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:08:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:08:24 INFO - 'opt_config_files': (), 05:08:24 INFO - 'pip_index': False, 05:08:24 INFO - 'sps_profile': False, 05:08:24 INFO - 'sps_profile_interval': 0, 05:08:24 INFO - 'suite': 'dromaeojs', 05:08:24 INFO - 'system_bits': '32', 05:08:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:24 INFO - 'title': 'talos-linux64-ix-004', 05:08:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:24 INFO - 'use_talos_json': True, 05:08:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:24 INFO - 'work_dir': 'build'} 05:08:24 INFO - ##### 05:08:24 INFO - ##### Running clobber step. 05:08:24 INFO - ##### 05:08:24 INFO - Running pre-action listener: _resource_record_pre_action 05:08:24 INFO - Running main action method: clobber 05:08:24 INFO - rmtree: /builds/slave/test/build 05:08:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:24 INFO - Running post-action listener: _resource_record_post_action 05:08:24 INFO - ##### 05:08:24 INFO - ##### Finished clobber step (success) 05:08:24 INFO - ##### 05:08:24 INFO - ##### 05:08:24 INFO - ##### Running read-buildbot-config step. 05:08:24 INFO - ##### 05:08:24 INFO - Running pre-action listener: _resource_record_pre_action 05:08:24 INFO - Running main action method: read_buildbot_config 05:08:24 INFO - Using buildbot properties: 05:08:24 INFO - { 05:08:24 INFO - "project": "", 05:08:24 INFO - "product": "firefox", 05:08:24 INFO - "script_repo_revision": "production", 05:08:24 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:08:24 INFO - "repository": "", 05:08:24 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 05:08:24 INFO - "buildid": "20160609043328", 05:08:24 INFO - "pgo_build": "False", 05:08:24 INFO - "basedir": "/builds/slave/test", 05:08:24 INFO - "buildnumber": 1758, 05:08:24 INFO - "slavename": "talos-linux64-ix-004", 05:08:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:08:24 INFO - "platform": "ubuntu64_hw", 05:08:24 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:08:24 INFO - "branch": "fx-team", 05:08:24 INFO - "repo_path": "integration/fx-team", 05:08:24 INFO - "revision": "1c86ba5d7a5b9cb16ef3ad77ff740688a923108a", 05:08:24 INFO - "stage_platform": "linux64", 05:08:24 INFO - "builduid": "d8866705666f4b9287bc69ac21565cdb", 05:08:24 INFO - "slavebuilddir": "test" 05:08:24 INFO - } 05:08:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:08:24 INFO - Running post-action listener: _resource_record_post_action 05:08:24 INFO - ##### 05:08:24 INFO - ##### Finished read-buildbot-config step (success) 05:08:24 INFO - ##### 05:08:24 INFO - ##### 05:08:24 INFO - ##### Running download-and-extract step. 05:08:24 INFO - ##### 05:08:24 INFO - Running pre-action listener: _resource_record_pre_action 05:08:24 INFO - Running main action method: download_and_extract 05:08:24 INFO - mkdir: /builds/slave/test/build/tests 05:08: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:08:24 INFO - https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:08:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:08:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:08:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:26 INFO - Downloaded 1361 bytes. 05:08:26 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:08:26 INFO - Using the following test package requirements: 05:08:26 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:26 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:08:26 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:08:26 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'jsshell-linux-x86_64.zip'], 05:08:26 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:08:26 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:26 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:08:26 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:08:26 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:08:26 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:26 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:08:26 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:08:26 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:08:26 INFO - https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:08:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:08:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:29 INFO - Downloaded 22879604 bytes. 05:08:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:08:31 INFO - Return code: 0 05:08:31 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:08: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:08:31 INFO - https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:08:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:08:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:42 INFO - Downloaded 22879604 bytes. 05:08:42 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:08:42 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:08:44 INFO - Return code: 0 05:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:44 INFO - https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:08:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:46 INFO - Downloaded 10833377 bytes. 05:08:46 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:08:46 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:08:46 INFO - Return code: 0 05:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:46 INFO - https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VihdZ2z7RSyseir6WMAZEA/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:08:48 INFO - Downloaded 55447367 bytes. 05:08:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:08:48 INFO - mkdir: /builds/slave/test/properties 05:08:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:48 INFO - Contents: 05:08:48 INFO - build_url:https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:08:49 INFO - Running post-action listener: _resource_record_post_action 05:08:49 INFO - Running post-action listener: set_extra_try_arguments 05:08:49 INFO - ##### 05:08:49 INFO - ##### Finished download-and-extract step (success) 05:08:49 INFO - ##### 05:08:49 INFO - ##### 05:08:49 INFO - ##### Running populate-webroot step. 05:08:49 INFO - ##### 05:08:49 INFO - Running pre-action listener: _resource_record_pre_action 05:08:49 INFO - Running main action method: populate_webroot 05:08:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:49 INFO - '--tppagecycles', 05:08:49 INFO - '7'], 05:08:49 INFO - 'tests': ['tsvgm']}}, 05:08:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:08:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:08:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['damp', 'tps']}, 05:08:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'tests': ['damp', 'tps']}, 05:08:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['dromaeo_dom']}, 05:08:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['a11yr', 05:08:49 INFO - 'ts_paint', 05:08:49 INFO - 'tpaint', 05:08:49 INFO - 'sessionrestore', 05:08:49 INFO - 'sessionrestore_no_auto_restore', 05:08:49 INFO - 'tabpaint']}, 05:08:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:08:49 INFO - 'ts_paint', 05:08:49 INFO - 'tpaint', 05:08:49 INFO - 'sessionrestore', 05:08:49 INFO - 'sessionrestore_no_auto_restore', 05:08:49 INFO - 'tabpaint']}, 05:08:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:08:49 INFO - 'ts_paint', 05:08:49 INFO - 'tpaint', 05:08:49 INFO - 'sessionrestore', 05:08:49 INFO - 'sessionrestore_no_auto_restore', 05:08:49 INFO - 'tabpaint']}, 05:08:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:08:49 INFO - 'ts_paint', 05:08:49 INFO - 'tpaint', 05:08:49 INFO - 'sessionrestore', 05:08:49 INFO - 'sessionrestore_no_auto_restore', 05:08:49 INFO - 'tabpaint']}, 05:08:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['a11yr', 05:08:49 INFO - 'ts_paint', 05:08:49 INFO - 'tpaint', 05:08:49 INFO - 'sessionrestore', 05:08:49 INFO - 'sessionrestore_no_auto_restore', 05:08:49 INFO - 'tabpaint']}, 05:08:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['a11yr', 05:08:49 INFO - 'ts_paint', 05:08:49 INFO - 'tpaint', 05:08:49 INFO - 'sessionrestore', 05:08:49 INFO - 'sessionrestore_no_auto_restore', 05:08:49 INFO - 'tabpaint']}, 05:08:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['tsvgx', 05:08:49 INFO - 'tsvgr_opacity', 05:08:49 INFO - 'tart', 05:08:49 INFO - 'tscrollx', 05:08:49 INFO - 'cart']}, 05:08:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:08:49 INFO - 'tsvgr_opacity', 05:08:49 INFO - 'tart', 05:08:49 INFO - 'tscrollx', 05:08:49 INFO - 'cart']}, 05:08:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'talos_options': ['--disable-e10s'], 05:08:49 INFO - 'tests': ['tp5o']}, 05:08:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'tests': ['tp5o']}, 05:08:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'talos_options': ['--disable-e10s', 05:08:49 INFO - '--xperf_path', 05:08:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:49 INFO - 'tests': ['tp5n']}, 05:08:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:49 INFO - 'talos_options': ['--xperf_path', 05:08:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:49 INFO - 'tests': ['tp5n']}}, 05:08:49 INFO - 'talos.zip': {'path': '', 05:08:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:49 INFO - Running post-action listener: _resource_record_post_action 05:08:49 INFO - ##### 05:08:49 INFO - ##### Finished populate-webroot step (success) 05:08:49 INFO - ##### 05:08:49 INFO - ##### 05:08:49 INFO - ##### Running create-virtualenv step. 05:08:49 INFO - ##### 05:08:49 INFO - Running pre-action listener: _resource_record_pre_action 05:08:49 INFO - Running main action method: create_virtualenv 05:08:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:49 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:08:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:49 INFO - Using real prefix '/usr' 05:08:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:50 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:52 INFO - Installing pip.................done. 05:08:52 INFO - Return code: 0 05:08:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:52 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:08:52 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:08:52 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:08:52 INFO - Using env: {'DISPLAY': ':0', 05:08:52 INFO - 'HOME': '/home/cltbld', 05:08:52 INFO - 'LANG': 'en_US.UTF-8', 05:08:52 INFO - 'LANGUAGE': 'en_US:en', 05:08:52 INFO - 'LOGNAME': 'cltbld', 05:08:52 INFO - 'MAIL': '/var/mail/cltbld', 05:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:52 INFO - 'MOZ_NO_REMOTE': '1', 05:08:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:52 INFO - 'NO_EM_RESTART': '1', 05:08:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:52 INFO - 'PWD': '/builds/slave/test', 05:08:52 INFO - 'SHELL': '/bin/bash', 05:08:52 INFO - 'SHLVL': '1', 05:08:52 INFO - 'TERM': 'linux', 05:08:52 INFO - 'TMOUT': '86400', 05:08:52 INFO - 'USER': 'cltbld', 05:08:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:52 INFO - '_': '/tools/buildbot/bin/python'} 05:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:52 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:08:52 INFO - Cleaning up... 05:08:52 INFO - Return code: 0 05:08:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:52 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:08:52 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:08:52 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:08:52 INFO - Using env: {'DISPLAY': ':0', 05:08:52 INFO - 'HOME': '/home/cltbld', 05:08:52 INFO - 'LANG': 'en_US.UTF-8', 05:08:52 INFO - 'LANGUAGE': 'en_US:en', 05:08:52 INFO - 'LOGNAME': 'cltbld', 05:08:52 INFO - 'MAIL': '/var/mail/cltbld', 05:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:52 INFO - 'MOZ_NO_REMOTE': '1', 05:08:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:52 INFO - 'NO_EM_RESTART': '1', 05:08:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:52 INFO - 'PWD': '/builds/slave/test', 05:08:52 INFO - 'SHELL': '/bin/bash', 05:08:52 INFO - 'SHLVL': '1', 05:08:52 INFO - 'TERM': 'linux', 05:08:52 INFO - 'TMOUT': '86400', 05:08:52 INFO - 'USER': 'cltbld', 05:08:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:52 INFO - '_': '/tools/buildbot/bin/python'} 05:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:52 INFO - Downloading/unpacking psutil>=3.1.1 05:08:52 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:08:52 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:08:52 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:08:52 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:08:52 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:08:52 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:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:54 INFO - Installing collected packages: psutil 05:08:54 INFO - Running setup.py install for psutil 05:08:54 INFO - building 'psutil._psutil_linux' extension 05:08:54 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:08:55 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:08:55 INFO - building 'psutil._psutil_posix' extension 05:08:55 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:08:55 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:08:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:55 INFO - Successfully installed psutil 05:08:55 INFO - Cleaning up... 05:08:55 INFO - Return code: 0 05:08:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:55 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:08:55 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:08:55 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:08:55 INFO - Using env: {'DISPLAY': ':0', 05:08:55 INFO - 'HOME': '/home/cltbld', 05:08:55 INFO - 'LANG': 'en_US.UTF-8', 05:08:55 INFO - 'LANGUAGE': 'en_US:en', 05:08:55 INFO - 'LOGNAME': 'cltbld', 05:08:55 INFO - 'MAIL': '/var/mail/cltbld', 05:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:55 INFO - 'MOZ_NO_REMOTE': '1', 05:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:55 INFO - 'NO_EM_RESTART': '1', 05:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:55 INFO - 'PWD': '/builds/slave/test', 05:08:55 INFO - 'SHELL': '/bin/bash', 05:08:55 INFO - 'SHLVL': '1', 05:08:55 INFO - 'TERM': 'linux', 05:08:55 INFO - 'TMOUT': '86400', 05:08:55 INFO - 'USER': 'cltbld', 05:08:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:55 INFO - '_': '/tools/buildbot/bin/python'} 05:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:08:55 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:08:55 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:08:55 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:08:55 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:08:55 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:08:55 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:08:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:08:57 INFO - Installing collected packages: mozsystemmonitor 05:08:57 INFO - Running setup.py install for mozsystemmonitor 05:08:57 INFO - Successfully installed mozsystemmonitor 05:08:57 INFO - Cleaning up... 05:08:57 INFO - Return code: 0 05:08:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:08:57 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:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 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:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:08:57 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:08:57 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:08:57 INFO - Using env: {'DISPLAY': ':0', 05:08:57 INFO - 'HOME': '/home/cltbld', 05:08:57 INFO - 'LANG': 'en_US.UTF-8', 05:08:57 INFO - 'LANGUAGE': 'en_US:en', 05:08:57 INFO - 'LOGNAME': 'cltbld', 05:08:57 INFO - 'MAIL': '/var/mail/cltbld', 05:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:57 INFO - 'MOZ_NO_REMOTE': '1', 05:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:57 INFO - 'NO_EM_RESTART': '1', 05:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:57 INFO - 'PWD': '/builds/slave/test', 05:08:57 INFO - 'SHELL': '/bin/bash', 05:08:57 INFO - 'SHLVL': '1', 05:08:57 INFO - 'TERM': 'linux', 05:08:57 INFO - 'TMOUT': '86400', 05:08:57 INFO - 'USER': 'cltbld', 05:08:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:57 INFO - '_': '/tools/buildbot/bin/python'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 INFO - Downloading/unpacking jsonschema==2.5.1 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:08:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:59 INFO - Downloading functools32-3.2.3-2.tar.gz 05:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:08:59 INFO - warning: no files found matching '*.txt' 05:08:59 INFO - no previously-included directories found matching 'build' 05:08:59 INFO - no previously-included directories found matching 'dist' 05:08:59 INFO - no previously-included directories found matching '.git*' 05:08:59 INFO - Installing collected packages: jsonschema, functools32 05:09:00 INFO - Running setup.py install for functools32 05:09:00 INFO - warning: no files found matching '*.txt' 05:09:00 INFO - no previously-included directories found matching 'build' 05:09:00 INFO - no previously-included directories found matching 'dist' 05:09:00 INFO - no previously-included directories found matching '.git*' 05:09:00 INFO - Successfully installed jsonschema functools32 05:09:00 INFO - Cleaning up... 05:09:00 INFO - Return code: 0 05:09:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:09:00 INFO - Using env: {'DISPLAY': ':0', 05:09:00 INFO - 'HOME': '/home/cltbld', 05:09:00 INFO - 'LANG': 'en_US.UTF-8', 05:09:00 INFO - 'LANGUAGE': 'en_US:en', 05:09:00 INFO - 'LOGNAME': 'cltbld', 05:09:00 INFO - 'MAIL': '/var/mail/cltbld', 05:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:00 INFO - 'MOZ_NO_REMOTE': '1', 05:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:00 INFO - 'NO_EM_RESTART': '1', 05:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:00 INFO - 'PWD': '/builds/slave/test', 05:09:00 INFO - 'SHELL': '/bin/bash', 05:09:00 INFO - 'SHLVL': '1', 05:09:00 INFO - 'TERM': 'linux', 05:09:00 INFO - 'TMOUT': '86400', 05:09:00 INFO - 'USER': 'cltbld', 05:09:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:00 INFO - '_': '/tools/buildbot/bin/python'} 05:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:02 INFO - Downloading docopt-0.6.1.tar.gz 05:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:03 INFO - Running setup.py install for blobuploader 05:09:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:03 INFO - Running setup.py install for requests 05:09:03 INFO - Running setup.py install for docopt 05:09:03 INFO - Successfully installed blobuploader requests docopt 05:09:03 INFO - Cleaning up... 05:09:03 INFO - Return code: 0 05:09:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:03 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:03 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:03 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:03 INFO - Using env: {'DISPLAY': ':0', 05:09:03 INFO - 'HOME': '/home/cltbld', 05:09:03 INFO - 'LANG': 'en_US.UTF-8', 05:09:03 INFO - 'LANGUAGE': 'en_US:en', 05:09:03 INFO - 'LOGNAME': 'cltbld', 05:09:03 INFO - 'MAIL': '/var/mail/cltbld', 05:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:03 INFO - 'MOZ_NO_REMOTE': '1', 05:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:03 INFO - 'NO_EM_RESTART': '1', 05:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:03 INFO - 'PWD': '/builds/slave/test', 05:09:03 INFO - 'SHELL': '/bin/bash', 05:09:03 INFO - 'SHLVL': '1', 05:09:03 INFO - 'TERM': 'linux', 05:09:03 INFO - 'TMOUT': '86400', 05:09:03 INFO - 'USER': 'cltbld', 05:09:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:03 INFO - '_': '/tools/buildbot/bin/python'} 05:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:03 INFO - Running setup.py (path:/tmp/pip-B9s353-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:03 INFO - Running setup.py (path:/tmp/pip-RO2kkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:03 INFO - Running setup.py (path:/tmp/pip-ExwoFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:04 INFO - Running setup.py (path:/tmp/pip-9c1KP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:04 INFO - Running setup.py (path:/tmp/pip-RWQvfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:04 INFO - Running setup.py (path:/tmp/pip-2oZVt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:04 INFO - Running setup.py (path:/tmp/pip-wzeLos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:04 INFO - Running setup.py (path:/tmp/pip-k58A1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:04 INFO - Running setup.py (path:/tmp/pip-8hvLXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:04 INFO - Running setup.py (path:/tmp/pip-lLVurn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:04 INFO - Running setup.py (path:/tmp/pip-npksvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:04 INFO - Running setup.py (path:/tmp/pip-s59o8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:04 INFO - Running setup.py (path:/tmp/pip-5dGnHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:04 INFO - Running setup.py (path:/tmp/pip-jUMB54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:04 INFO - Running setup.py (path:/tmp/pip-rZgM9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:04 INFO - Running setup.py (path:/tmp/pip-csx0Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:04 INFO - Running setup.py (path:/tmp/pip-qmNMkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:05 INFO - Running setup.py install for manifestparser 05:09:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Running setup.py install for mozcrash 05:09:05 INFO - Running setup.py install for mozdebug 05:09:05 INFO - Running setup.py install for mozdevice 05:09:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Running setup.py install for mozfile 05:09:05 INFO - Running setup.py install for mozhttpd 05:09:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Running setup.py install for mozinfo 05:09:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Running setup.py install for mozInstall 05:09:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:05 INFO - Running setup.py install for mozleak 05:09:05 INFO - Running setup.py install for mozlog 05:09:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Running setup.py install for moznetwork 05:09:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Running setup.py install for mozprocess 05:09:06 INFO - Running setup.py install for mozprofile 05:09:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Running setup.py install for mozrunner 05:09:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Running setup.py install for mozscreenshot 05:09:06 INFO - Running setup.py install for moztest 05:09:06 INFO - Running setup.py install for mozversion 05:09:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:06 INFO - Cleaning up... 05:09:06 INFO - Return code: 0 05:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:06 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:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:06 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:06 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:06 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:06 INFO - Using env: {'DISPLAY': ':0', 05:09:06 INFO - 'HOME': '/home/cltbld', 05:09:06 INFO - 'LANG': 'en_US.UTF-8', 05:09:06 INFO - 'LANGUAGE': 'en_US:en', 05:09:06 INFO - 'LOGNAME': 'cltbld', 05:09:06 INFO - 'MAIL': '/var/mail/cltbld', 05:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:06 INFO - 'MOZ_NO_REMOTE': '1', 05:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:06 INFO - 'NO_EM_RESTART': '1', 05:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:06 INFO - 'PWD': '/builds/slave/test', 05:09:06 INFO - 'SHELL': '/bin/bash', 05:09:06 INFO - 'SHLVL': '1', 05:09:06 INFO - 'TERM': 'linux', 05:09:06 INFO - 'TMOUT': '86400', 05:09:06 INFO - 'USER': 'cltbld', 05:09:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:06 INFO - '_': '/tools/buildbot/bin/python'} 05:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:07 INFO - Running setup.py (path:/tmp/pip-wJmfAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:07 INFO - Running setup.py (path:/tmp/pip-IeVJvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:07 INFO - Running setup.py (path:/tmp/pip-gWHGpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:07 INFO - Running setup.py (path:/tmp/pip-EybvQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:07 INFO - Running setup.py (path:/tmp/pip-_QKHbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:07 INFO - Running setup.py (path:/tmp/pip-TY9YrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:07 INFO - Running setup.py (path:/tmp/pip-_YOnDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:07 INFO - Running setup.py (path:/tmp/pip-jYUYHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:07 INFO - Running setup.py (path:/tmp/pip-IP2N6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:07 INFO - Running setup.py (path:/tmp/pip-8ntxaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:07 INFO - Running setup.py (path:/tmp/pip-Nzzq2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:07 INFO - Running setup.py (path:/tmp/pip-BjTAJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:07 INFO - Running setup.py (path:/tmp/pip-z84bn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:07 INFO - Running setup.py (path:/tmp/pip-KClcls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:08 INFO - Running setup.py (path:/tmp/pip-hm0B4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:08 INFO - Running setup.py (path:/tmp/pip-fd1NKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:08 INFO - Running setup.py (path:/tmp/pip-3U_2AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:08 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:08 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:08 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:08 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:08 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:08 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:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:08 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:08 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:08 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:08 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:08 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:08 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:10 INFO - Downloading blessings-1.6.tar.gz 05:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:10 INFO - Installing collected packages: blessings 05:09:10 INFO - Running setup.py install for blessings 05:09:10 INFO - Successfully installed blessings 05:09:10 INFO - Cleaning up... 05:09:10 INFO - Return code: 0 05:09:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:10 INFO - Reading from file tmpfile_stdout 05:09:10 INFO - Current package versions: 05:09:10 INFO - argparse == 1.2.1 05:09:10 INFO - blessings == 1.6 05:09:10 INFO - blobuploader == 1.2.4 05:09:10 INFO - docopt == 0.6.1 05:09:10 INFO - functools32 == 3.2.3-2 05:09:10 INFO - jsonschema == 2.5.1 05:09:10 INFO - manifestparser == 1.1 05:09:10 INFO - mozInstall == 1.12 05:09:10 INFO - mozcrash == 0.17 05:09:10 INFO - mozdebug == 0.1 05:09:10 INFO - mozdevice == 0.48 05:09:10 INFO - mozfile == 1.2 05:09:10 INFO - mozhttpd == 0.7 05:09:10 INFO - mozinfo == 0.9 05:09:10 INFO - mozleak == 0.1 05:09:10 INFO - mozlog == 3.2 05:09:10 INFO - moznetwork == 0.27 05:09:10 INFO - mozprocess == 0.22 05:09:10 INFO - mozprofile == 0.28 05:09:10 INFO - mozrunner == 6.11 05:09:10 INFO - mozscreenshot == 0.1 05:09:10 INFO - mozsystemmonitor == 0.3 05:09:10 INFO - moztest == 0.7 05:09:10 INFO - mozversion == 1.4 05:09:10 INFO - psutil == 3.1.1 05:09:10 INFO - requests == 1.2.3 05:09:10 INFO - wsgiref == 0.1.2 05:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:10 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:10 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:10 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:10 INFO - Using env: {'DISPLAY': ':0', 05:09:10 INFO - 'HOME': '/home/cltbld', 05:09:10 INFO - 'LANG': 'en_US.UTF-8', 05:09:10 INFO - 'LANGUAGE': 'en_US:en', 05:09:10 INFO - 'LOGNAME': 'cltbld', 05:09:10 INFO - 'MAIL': '/var/mail/cltbld', 05:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:10 INFO - 'MOZ_NO_REMOTE': '1', 05:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:10 INFO - 'NO_EM_RESTART': '1', 05:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:10 INFO - 'PWD': '/builds/slave/test', 05:09:10 INFO - 'SHELL': '/bin/bash', 05:09:10 INFO - 'SHLVL': '1', 05:09:10 INFO - 'TERM': 'linux', 05:09:10 INFO - 'TMOUT': '86400', 05:09:10 INFO - 'USER': 'cltbld', 05:09:10 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:10 INFO - '_': '/tools/buildbot/bin/python'} 05:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 INFO - Cleaning up... 05:09:10 INFO - Return code: 0 05:09:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:09:10 INFO - Using env: {'DISPLAY': ':0', 05:09:10 INFO - 'HOME': '/home/cltbld', 05:09:10 INFO - 'LANG': 'en_US.UTF-8', 05:09:10 INFO - 'LANGUAGE': 'en_US:en', 05:09:10 INFO - 'LOGNAME': 'cltbld', 05:09:10 INFO - 'MAIL': '/var/mail/cltbld', 05:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:10 INFO - 'MOZ_NO_REMOTE': '1', 05:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:10 INFO - 'NO_EM_RESTART': '1', 05:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:10 INFO - 'PWD': '/builds/slave/test', 05:09:10 INFO - 'SHELL': '/bin/bash', 05:09:10 INFO - 'SHLVL': '1', 05:09:10 INFO - 'TERM': 'linux', 05:09:10 INFO - 'TMOUT': '86400', 05:09:10 INFO - 'USER': 'cltbld', 05:09:10 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:10 INFO - '_': '/tools/buildbot/bin/python'} 05:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:11 INFO - Cleaning up... 05:09:11 INFO - Return code: 0 05:09:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 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 0x7fbf1b10ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 05:09:11 INFO - 'HOME': '/home/cltbld', 05:09:11 INFO - 'LANG': 'en_US.UTF-8', 05:09:11 INFO - 'LANGUAGE': 'en_US:en', 05:09:11 INFO - 'LOGNAME': 'cltbld', 05:09:11 INFO - 'MAIL': '/var/mail/cltbld', 05:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:11 INFO - 'MOZ_NO_REMOTE': '1', 05:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:11 INFO - 'NO_EM_RESTART': '1', 05:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:11 INFO - 'PWD': '/builds/slave/test', 05:09:11 INFO - 'SHELL': '/bin/bash', 05:09:11 INFO - 'SHLVL': '1', 05:09:11 INFO - 'TERM': 'linux', 05:09:11 INFO - 'TMOUT': '86400', 05:09:11 INFO - 'USER': 'cltbld', 05:09:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:11 INFO - '_': '/tools/buildbot/bin/python'} 05:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:11 INFO - Downloading/unpacking flake8 05:09:11 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:11 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:11 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:11 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:11 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:11 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 - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 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:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 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:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 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:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:13 INFO - no previously-included directories found matching 'docs/_build' 05:09:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:13 INFO - Running setup.py install for pep8 05:09:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:14 INFO - no previously-included directories found matching 'docs/_build' 05:09:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:14 INFO - Cleaning up... 05:09:14 INFO - Return code: 0 05:09:14 INFO - Running post-action listener: _resource_record_post_action 05:09:14 INFO - Running post-action listener: _start_resource_monitoring 05:09:14 INFO - Starting resource monitoring. 05:09:14 INFO - ##### 05:09:14 INFO - ##### Finished create-virtualenv step (success) 05:09:14 INFO - ##### 05:09:14 INFO - ##### 05:09:14 INFO - ##### Running install step. 05:09:14 INFO - ##### 05:09:14 INFO - Running pre-action listener: _resource_record_pre_action 05:09:14 INFO - Running main action method: install 05:09:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:14 INFO - Reading from file tmpfile_stdout 05:09:14 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:14 INFO - Reading from file tmpfile_stdout 05:09:14 INFO - Output received: 05:09:14 INFO - Usage: mozinstall [options] installer 05:09:14 INFO - Options: 05:09:14 INFO - -h, --help show this help message and exit 05:09:14 INFO - -d DEST, --destination=DEST 05:09:14 INFO - Directory to install application into. [default: 05:09:14 INFO - "/builds/slave/test"] 05:09:14 INFO - --app=APP Application being installed. [default: firefox] 05:09:14 INFO - mkdir: /builds/slave/test/build/application 05:09:14 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:14 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:30 INFO - Reading from file tmpfile_stdout 05:09:30 INFO - Output received: 05:09:30 INFO - /builds/slave/test/build/application/firefox/firefox 05:09:30 INFO - Running post-action listener: _resource_record_post_action 05:09:30 INFO - ##### 05:09:30 INFO - ##### Finished install step (success) 05:09:30 INFO - ##### 05:09:30 INFO - ##### 05:09:30 INFO - ##### Running run-tests step. 05:09:30 INFO - ##### 05:09:30 INFO - Running pre-action listener: _resource_record_pre_action 05:09:30 INFO - Running main action method: run_tests 05:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:30 INFO - Python 2.7.3 05:09:30 INFO - Return code: 0 05:09:30 INFO - grabbing minidump binary from tooltool 05:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:30 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 0x25771c0>, '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 0x25780c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2578550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:30 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:30 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:30 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:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:30 INFO - Return code: 0 05:09:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:09:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:09:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:09:31 INFO - Return code: 0 05:09:31 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/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:31 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/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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: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 - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 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': '/builds/slave/test/build/venv/bin:/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 - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 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': 'c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 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/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:31 INFO - mozversion application_buildid: 20160609043328 05:09:31 INFO - mozversion application_changeset: 1c86ba5d7a5b9cb16ef3ad77ff740688a923108a 05:09:31 INFO - mozversion application_display_name: Nightly 05:09:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:31 INFO - mozversion application_name: Firefox 05:09:31 INFO - mozversion application_remotingname: firefox 05:09:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:09:31 INFO - mozversion application_vendor: Mozilla 05:09:31 INFO - mozversion application_version: 50.0a1 05:09:31 INFO - mozversion platform_buildid: 20160609043328 05:09:31 INFO - mozversion platform_changeset: 1c86ba5d7a5b9cb16ef3ad77ff740688a923108a 05:09:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:09:31 INFO - mozversion platform_version: 50.0a1 05:09:31 INFO - using testdate: 1465474171 05:09:31 INFO - actual date: 1465474171 05:09:31 INFO - starting webserver on 'localhost:59098' 05:09:31 INFO - SUITE-START | Running 2 tests 05:09:31 INFO - TEST-START | dromaeo_css 05:09:31 INFO - operating with platform_type : linux_ 05:09:31 INFO - Initialising browser for dromaeo_css test... 05:09:31 INFO - TEST-INFO | started process 13702 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc1Yd1E/profile http://localhost:59098/getInfo.html) 05:09:36 INFO - PROCESS | 13702 | __metrics Screen width/height:1600/1200 05:09:36 INFO - PROCESS | 13702 | colorDepth:24 05:09:36 INFO - PROCESS | 13702 | Browser inner width/height: 1024/697 05:09:36 INFO - PROCESS | 13702 | __metrics 05:09:37 ERROR - PROCESS | 13702 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:09:37 INFO - PROCESS | 13702 | console.error: PushService: 05:09:37 INFO - PROCESS | 13702 | stateChangeProcessEnqueue: Error transitioning state 05:09:37 INFO - PROCESS | 13702 | UnknownError 05:09:38 INFO - TEST-INFO | 13702: exit 0 05:09:38 INFO - Browser initialized. 05:09:38 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:38 INFO - TEST-INFO | started process 13782 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc1Yd1E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:50 INFO - PROCESS | 13782 | RSS: Main: 174911488 05:09:50 INFO - PROCESS | 13782 | 05:10:42 INFO - PROCESS | 13782 | 1465474242393 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:12:53 INFO - PROCESS | 13782 | Cycle 1(1): loaded http://localhost:59098/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59098/tests/dromaeo/cssquery-ext.html) 05:12:54 INFO - PROCESS | 13782 | RSS: Main: 250314752 05:12:54 INFO - PROCESS | 13782 | 05:15:57 INFO - PROCESS | 13782 | Cycle 1(1): loaded http://localhost:59098/tests/dromaeo/cssquery-ext.html (next: http://localhost:59098/tests/dromaeo/cssquery-jquery.html) 05:15:58 INFO - PROCESS | 13782 | RSS: Main: 256675840 05:15:58 INFO - PROCESS | 13782 | 05:19:00 INFO - PROCESS | 13782 | Cycle 1(1): loaded http://localhost:59098/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59098/tests/dromaeo/cssquery-mootools.html) 05:19:00 INFO - PROCESS | 13782 | RSS: Main: 263258112 05:19:00 INFO - PROCESS | 13782 | 05:22:03 INFO - PROCESS | 13782 | Cycle 1(1): loaded http://localhost:59098/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59098/tests/dromaeo/cssquery-prototype.html) 05:22:03 INFO - PROCESS | 13782 | RSS: Main: 258056192 05:22:03 INFO - PROCESS | 13782 | 05:25:06 INFO - PROCESS | 13782 | Cycle 1(1): loaded http://localhost:59098/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59098/tests/dromaeo/cssquery-yui.html) 05:25:07 INFO - PROCESS | 13782 | RSS: Main: 232853504 05:25:07 INFO - PROCESS | 13782 | 05:28:09 INFO - PROCESS | 13782 | Cycle 1(1): loaded http://localhost:59098/tests/dromaeo/cssquery-yui.html (next: http://localhost:59098/tests/dromaeo/cssquery-dojo.html) 05:28:10 INFO - PROCESS | 13782 | RSS: Main: 336818176 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | __start_tp_report 05:28:10 INFO - PROCESS | 13782 | _x_x_mozilla_page_load 05:28:10 INFO - PROCESS | 13782 | _x_x_mozilla_page_load_details 05:28:10 INFO - PROCESS | 13782 | |i|pagename|runs| 05:28:10 INFO - PROCESS | 13782 | |0;dojo.html;2578.3940773918125;2601.323655849481;2628.0407651207315;2632.5261452938475;2635.5783074708042;4565.429321334833;4678.766061696919;4704.905901881961;4754.239321708523;4801.399825021872;4683.437987441512;4712.481627021025;4713.528647135285;4727.4563425206115;4732.266498692699;4145.419641250227;4289.61393474588;4375.606195442409;4403;4500.729956202622;4402.86791396257;4433.2241857674935;4453.376527286183;4461.442319710036;4486.685931984763;4217.704760666755;4259.659227261812;4294.914101717948;4338.783060846945;4361.214981303364;4255.829766809324;4256.1487702459635;4270.701050926436;4313.051128751669;4331.826726930919;4295.463067116611;4350.2387082260475;4375.7155784873885;4408.426904502427;4412.779361031936;4605.884852878671;4608.4930657627965;4676.134915040729;4681.485036645966;4725.952740472586;5056.696598204119;5098.260752190949;5117.078925793356;5123.38519377677;5128.153854613985;5094.59243260542;5113.309703190059;5121.462246464105;5142.537171654569;5154.123798954187;4593.242115051013;4678.625709943264;4738.099761045391;4742.5020372861045;4743.596794272526;5213.687178769286;5229.241759944786;5243.554297884646;5244.76398562058;5249.947500525022;4986.750662466862;5027.145385284511;5027.748612569357;5028.949710502849;5032.144535428986;4888.828890988798;4999.975000125049;5059.342285502935;5061.924071138936;5067.087924173684;5024.171011783088;5038.042771873333;5041.42023667283;5052.368453943257;5086.643934924594;4841.540053694894;5002.749862506933;5008.674436161711;5009.423916249678;5013.849584512471;4182.916341673211;4215.97892010538;4239.97880010598;4241.512226093977;4284.057507348379;3665.890023299305;3666.3034023535442;3674.3569875272256;3674.595794462607;3713.183099718112;4255.042615411569;4343.391925130484;4349.717268377545;4398.362237475548;4398.494173170063;5047.747612619428;5075.264086707407;5082.263071854561;5111.591072714174;5120.590352771769;4385.934210986838;4391.407160033355;4391.626711729475;4391.890202744957;4412.646988240933;4444.400005999213;4508.81964721414;4548.226801443762;4552.590266875997;4584.358189853423;4772.427308722906;4777.784999674954;4795.20879054966;4796.808127674994;4844.8304309349705;5096.668716533413;5101.668391554537;5102.183650615883;5103.260027296095;5137.948620513747;4633.073385323017;4705.623550116051;4799.0401919615515;4808.374911261513;4856.441509226484;4944.480829512993;4975.950240497548;4997.400311962588;4999.350084489139;5044.4451110377095;5073.315102461121;5074.67014644047;5076.7207803571155;5076.974615126901;5129.487051294841;4796.784144713426;4843;4890.119778439915;4918.213085906238;4922.326229834637;4901.534354236342;4912.1894887344615;4923.138450771173;4924.212126059813;4950.752462376939;4851.975740121276;4856.587190088882;4873.731944742932;4880.951190488192;4883.145449546386;4744.620430365631;4856.902861942671;4896.36347274852;4910.631702622389;4911.312416261798;4830.637702172281;4836.854894353175;4843.903121937612;4901.117798796251;4951.183054796063 05:28:10 INFO - PROCESS | 13782 | |1;ext.html;558.2268558047105;577.1890493856132;580.33551583437;580.3760956971257;581.2356750872602;29845.1046468606;30524.694753052492;40385.7980710096;42054.78972605133;42070.78964605173;41740.58259417405;42239.577604223945;42292.36561451581;42302.36546451798;42395.78802105985;7780.5720685362285;7945.880811787828;7951.085625153095;7952.840943181133;7956.363490920741;7804.531728096304;7887.211278872095;7899.723509677163;7900.446968712193;7901;7911.52530848148;7930.286274235317;7938.682452701885;7947.7218297359605;7954.2841144297;7881.60591970402;7923.683052677886;7926.167752386003;7927.08838483576;7928.722494712657;7816.960915195445;7842.058952925629;7848.568328741917;7871.60641967905;7871.921280787176;39816.60183398158;39987.40018899719;40061;40153.79923100395;40179.59820401788;2956.3200463893427;3073.846307684629;3185.904422867318;3426.5374174486374;3904.824282907276;28393;28755.712442875512;30424.620179197587;30825;31335.686643133733;3229.0958531611186;3267.9282868525897;3282.294306724066;3327.8169700666454;3344.832758362072;451263;458105;460192;462070.9999999966;466326;60840.69579652162;60892.39107608956;61141;61374.69312653408;61507;34581.308373832384;35978.28043439169;35998.10004749902;38181.80909095437;64158.679206603665;16199.757003644954;16348.427805026768;16369.672606547801;16381.672366552602;16386.918065409598;12254.509819607294;12382.75234495305;12406.689832754253;12407.44166512509;12408.81386779199;19642.312519061765;19689.11398987049;19718.605627887362;19846.305379311943;19883.309918657076;7261.3890179736245;7387.45402206933;7496;7532.54804711719;7640.885386719204;834.3992325525612;877.3814460805118;877.5349064995562;893.396957053986;995.1640621877513;27777.444451110863;28663.56682165925;29375.55936660952;29496;29706.554401683992;909.3900505548985;911.9726408207764;984.7390441532999;1008.1481148429565;1044.2116202267239;14986.850131498764;15134.243287835565;15239.847601523843;15396.923015384851;15405.38378464849;420842;424769;427469;427848;428198;51683;52166;52380.47619523756;52438;52438.47561524412;1096.9890301097046;1169.5146514196513;1212.89690376319;1218.0925210718071;1235.3020543392954;29215.269618259714;29886.551701724056;30212.24469388283;30462.695373045986;30748.538771917993;44369;48036.279455808886;50096.24855627241;50140;55322.44677553173;377209;378530;380925;383375;385194;32802.50796238106;33698.66301337053;34648;40587.98530036773;41349.37975930423;38729;40983.18033639251;42242;44090.779546102065;45949.77025114853;38240.2351952965;38530.422043669925;38836.6116338833;38865;38904.864605799594;38251.80874095612;38274.617253827106;38522.22955540817;38683.613163868;38963;37059.44410833894;37776.24447511089;38081.42877856779;38287;38473;33886.491702624975;34685;34877.82561087178;35324.82337588296;35373;25928.8703556481;25934.48131037331;26259.21222363332;26362.736372636027;26683.466330673666 05:28:10 INFO - PROCESS | 13782 | |2;jquery.html;345.46280533769976;347.52389226759334;352.09335959903257;355.2965129044492;367.13722751533896;15704.92147539262;16037.759433608491;17198.140092995363;17198.65602687947;17212.741808872845;12815.743685126292;13589.38847751851;15989.120598367113;15990.280437380346;16793.91603041983;4786.162421576219;4979.402471703401;5004.949950500503;5164.974175129119;5171.948280517203;5014.097462456756;5033.899322013557;5056.558880718977;5111.1566591512355;5117.411497677777;3581.1405262736885;3785.9621403786023;3857.594952529972;5886.941130588703;5910.793122240722;5942.97028514859;5971.193888824996;5988.311344195408;6007.279126504825;6010.308814486324;5056.898862022776;5142.6914385137015;5158.200478925773;5167.560757335631;5178.171492561172;19446.513837154183;19601;19663.01684915763;20364.69452958207;20435.48911277215;1959.9510012249666;2081.105124796352;2123.490362313049;2383.8569685818907;2384.7615238476187;12137.089718271076;12222.20555663879;12961.935190323988;13211.075224734272;13486.527971520949;1623.310093210381;1628.7638292447648;1653.9173041347885;1702.1914590569381;1713.8114807371303;40707;52724.47275527234;54835.17747233795;68252.6587367065;68329.65835170842;8977.281817454588;8995.730128096167;9108.772280693036;9185.173334399802;9320.580573874191;12276.017918566493;12362.505499780087;12376.257424554555;12380.071494637757;12405.565805196955;7246.927530724731;7343.6305398289005;7407.592582407911;7494.54074825478;7610.429217808686;7461.85076298471;7466;7507.8498430031095;7524.811879703051;7599.278068583476;5587.832365029056;5623.156526520973;5761.769529218868;5775.768969241183;5820.330661973842;1633.8202797692245;1649.3237772513453;1662.276909544352;1666.033700453734;1689.4678176374402;793.7182300283316;826.2233500509622;828.1552816127517;894.8120894612074;939.3706216834601;16280.348786048662;16354.264058117174;16653.3338666452;16904.661906761798;16981.386229501713;823.5511646152861;861.4056301152185;907.9001309856043;911.1435250864166;966.4587830814626;7778.377729781604;9390.812183756287;9713.61145554184;9781.119699226962;9913.405195688281;297442;302292;303470;401301;411614;85872;87182;87430;88464.11535884559;88641.55679221691;901.1574178143456;916.1067958740308;924.1543987251724;941.6327632223438;951.7287573041511;15061.472848450252;15398.307076181374;15401.614959626098;15643.139627320606;15756.60608484797;37197.25605487929;38187.42718859164;38340.424893627176;38414.807925960195;38451.230975380895;51797.48202517926;52670.473295268086;53289.73355133199;53293.46706532885;53650.09848916694;6518.934810652023;6988.231294557696;7112.608806515691;7230.481598864087;7254.274572542704;10681.145508359468;11725.120615954009;11787.057035437316;11974.700632484257;12036.277823330629;10238.641647542301;10271.589136434757;10312.587496500057;10446.164306855282;10515.789684206426;10255.128314093086;10336.896631033593;10412.167026637699;10542.683719488428;10577.735556611146;10175.745606359604;10230.795384092127;10442.947785261025;10500.632477863239;10745.71051473828;6844.384005439534;7007.789766307019;7008.859822803617;7228.891566626399;7248.275172482709;9604.519774011133;10184.134348580157;10282.588696452058;10288.434136122585;10302.536385862804 05:28:10 INFO - PROCESS | 13782 | |3;mootools.html;1203.7712834561428;1362.3528823808692;1402.5652047865162;1469.8456662050485;1722.1733567887413;6365.140706004688;7082.114735658043;7349.742759003436;7362.042934418531;7592.620368981557;7404.66678999445;7423.591702456362;7481.850362992751;7483.962580187105;7511.136219334778;2148.4628842789343;2176.3035828534876;2179.1610230061383;2180.2042254577123;2182.6289530779727;2120.1943261560587;2140.7538133114736;2166.869987800737;2171.2183613898983;2175.260411460103;2102.054075665949;2138.4119367174076;2145.206273678741;2146.9033893474752;2171.576542574199;2060.495838038233;2095.5913596848623;2097.1820989813987;2097.307888396833;2097.3603051069417;2024.8278896293832;2104.053176070759;2108.1778106538463;2109.1141720477285;2114.7779483154354;7323.890141647879;7419.443541734391;7593.05086864142;7605.087389513238;7606;4389.802458889358;4593.40285762852;4596.954030459686;4601.125786100663;4601.263797792337;2674.3046807830106;2758.434520923206;2818.154553633922;2819.84490853003;2826.745592896649;871.6992637540038;874.6501399440211;877.0265005843509;880.7798050487378;884.7920738626418;10023.899761002369;10108.494575271281;10112;10118.291719579633;10140.645077422254;3890.3580909149673;3977.204559088194;4017.1764788218347;4048.27131116396;4054.4729185205733;8069.83860322802;8088.474249173785;8152.918470815334;8168.836623267502;8175.509469431853;4425.35832304314;4488.1248156609145;4493.460784705856;4498.1003799239625;4504;3543.7342199335153;3949.4273330366937;4088.1414902870138;4202.453681021448;4225.68307376948;3248.7888287261253;3270.1497610620936;3343.5319055332275;3347.7154441872713;3360.479125735515;951.509972364233;989.5992123190119;993.6820217530466;997.8952210017843;1002.1331548210891;685.1230425055936;686.1491750229779;732.9816754581178;735.5108852612952;852.863541833316;4790.520947905182;4803.855884323406;4920.237363208708;4943.406791185009;4947.134251505972;392.0923063108951;397.5845241722407;397.622258854084;403.5157810627259;407.8225971702319;3387.864485420605;3413.3855905936675;3433.2275238071734;3445.0698311455767;3449.913752156166;8832.205101540763;8950.86573701395;9175.128362805523;9183.219426348702;9232.307576931622;9013;9093.954530227438;9132.589033493508;9141.222996045411;9160.587773550205;6765.323467653196;7039.331263529956;7050.224475307712;7074.045003924404;7151.213366529678;5182.507661772125;5230.215467679726;5248.553872920845;5319.468053194804;5334.786608535615;8436.367272454714;8626.56867156627;8643.611037503202;8647.913520864711;8648.221660050625;8346.87479687793;8607.870881936651;8661.437006594551;8665.220130188314;8748.69379571712;5725.2843394575675;6324.335944725922;6562.80311590653;6566.212054553484;6686;7060.470464715064;7230.204677485578;7233.782986510413;7241;7241.058662374068;6238.685005431183;6500.544961852814;6500.902486462802;6575.835604109936;6591.274959754519;6124.8775024500155;6380.553361264667;6440.549161558646;6471.029345598198;6746.898796518154;5802.071668533184;5843.912341314799;5885.970570147293;5886.381929897299;5887.61730487517;4193.475815523059;4223.514295856015;4347.782610869507;4381.3208952612395;4402.647788176874;4082.857100001486;4260.44614200152;4350.173467041379;4361.694681372401;4406.427164468727 05:28:10 INFO - PROCESS | 13782 | |4;prototype.html;284.951378925554;288.69686828829725;290.8967316602605;292.83601183337333;299.9925001874954;4701.495521433147;4771.665983381165;4821.855344339667;4827.082854257697;4866.099771542263;4778.71327720337;4783;4789.760511974405;4807.668280883778;4840.636952228588;3550.6626870447267;3590.2101537661674;3757.830897609614;3759.473673685686;3790.654317717212;3719.7210209234418;3722.9441558376648;3753.3244016077097;3768.2651882882847;3773.8867833964887;3675.981620091909;3684.613115622861;3700.481932529448;3709.8701545445915;3740.532433445819;3556.733245006622;3620.5517792882924;3708.406654935224;3716.3682174030478;3755.6056614055406;3241.4003409369343;3272.4927636216185;3289.3585750778607;3291.835408229579;3300.5709257796575;4607.516210797851;4619.198512580097;4620.237660785966;4624.959384138576;4630.467496237942;3978.76127432355;4195.601417865298;4211.231282861206;4216.683748718828;4243.193793179286;4935.481774413706;4954;4954.027583449937;4959.528844759778;4961.040389046307;3588.6949609283242;3593.838277277529;3593.94609080864;3599.190182209008;3621.6740493355724;5005.2492126181;5229.633925625208;5230.267762513213;5236.413057055929;5256.448072952323;3651.1784848409425;3675.209829886587;3678.7240956928335;3694.2057457646197;3713.350163721338;3713.3687273163087;3720.1257704439436;3726.087108658342;3736.308782875177;3763.040424691686;4287.506936702257;4289.356596510548;4291.485021797342;4302.333138363559;4305.720128191658;4822.541858523435;4995.425526064477;5014.724190169576;5025.723585202849;5043.470435604208;1288.091895213872;1288.7229245712028;1291.5544137272625;1299.4152631315947;1304.4651692805903;803.6624617660597;820.3929092471528;824.5423789796721;842.4650347029681;843.8227972125923;4321.935170972438;4449.087936972913;4459.420275364247;4464.709793863389;4476.2837945928495;5065.189569668835;5088.135017047037;5094.898102038012;5101;5120.873407850267;4355.1725172217175;4393.495707446725;4405.140997505456;4419.359192917009;4452.465704115527;2557.8465292082533;2720.387912719662;2731.945361092767;2737;2745.643066401355;4816.975915120472;4831.637627177975;4861.669756217003;4894.926576101361;4917.213245880642;4961.685153434343;5239.458862451708;5242.816501422432;5244.449332820075;5274.26160337553;4863.148948933993;4875.317455556225;4880.219164933594;4884.975575122173;4897.975510122426;5106.7701953412925;5110.3356563646485;5132.8460146195675;5149.678457394884;5168.147255702767;4797.745059821878;5038.420581633159;5043.445221025758;5063.949360506348;5064.620153488429;4897.167481528078;4922.301805876967;4923.852284431473;4932.186189278729;4936.531029552187;3575.499430079843;3583.9283214335046;3594.676479116892;3596.4964904913854;3619.8371073301237;5078.669886457368;5158.819441319536;5162.174052151638;5205.895882082262;5206.843794686166;5026.84919452417;5032.6225533084435;5047.167217409086;5061.670991385548;5062.54437100648;5026.195808670595;5048.293238946624;5057;5058.595312374928;5079.060373830853;4711.976440117777;4937.407511098691;4938.802447902043;4972.2790195422185;4992.8751781205765;4830.541098595628;4962.528559786814;5056.469070747518;5057.418396884405;5064.645474816727;4948.292838970549;4954.27514866829;4956.281339205723;4980.975095124501;4989.101961646793 05:28:10 INFO - PROCESS | 13782 | |5;yui.html;508.30870016777175;524.237234823332;576.8875069361474;604.2356419129799;667.3060017581715;12625.431855566498;13087.411066502007;13544.729105417886;13593.45626174953;13609.727805443898;12813.295268760214;12884.935575322133;12985.220886746802;12988.54540091097;12996.220226786376;632.0329895260236;756.130449982519;774.0517865614619;782.7808213700145;793.9682412703485;787.0476723164977;789.7235967411417;790.1229635105029;790.1663744749287;791.1376599506566;825.686239229092;832.2094010689839;832.4464231286188;837.0750320895414;838.2748922182299;659.0213532903634;663.8373598468357;666.3136968922018;668.5086461450829;831.8835363049178;576.2854060964415;594.4441946779775;601.485729701108;601.7863658401292;602.4939051197016;12837.358132093357;12852.421641026083;12860.485580576766;12860.971122310286;12866.806997895039;11733.589324373692;12267.93866030673;12306.446209920576;12330.753384932252;12671.429785659668;9502.239820814388;9543.665971690958;9820.558074886647;9839.311248212627;10821.83767243492;4131.5248746394245;4142.357934520124;4174.102567947906;4227.32362821947;4240.978795106004;56705.71647141738;57742;58012.419875801126;59301.40698593045;59402.40597594012;9971;10159.847602285972;10169.440680762482;10215.540300686487;10246.846297305547;17772.733408998876;17780.91109544444;17788.644227115386;17797.911010445125;17804.109794510223;6033.7586496540525;6047.244094488189;6104.603200791934;6107.267127944675;6246.906296405557;3426.4860270959557;3437.037629463754;3469.809160496197;3483.198864261234;3614.2590768892314;2723.891044358243;2785.6935737068907;2796.7762578993634;2799.048323569996;2799.580062990527;481.1795888010933;481.4511456939092;485.46841208876225;497.65164384930694;498.51145877039977;475.55060467857544;527.9788808447696;535.4886083790049;551.657972057327;573.9569532285095;7113.181984071898;7128.215896251409;7374.188839227681;7409.629518524052;7488.513246638951;512.423523536022;520.4409373884721;547.819219657512;554.7808615596816;567.4477769165056;3691.8338674759702;3701.0932321580844;3704.981475092607;3777.225668737957;3816.656500914931;78224.60887695524;81168;81767.59116204381;90518.54740726254;91244.54377728202;49267.75366123218;54246.45753542493;77218.22781772223;78591;80504.5974770134;70325;72938;73818.6309068462;75565.24434755582;76311.61844190855;8571.61427735766;8649.697260595849;8849.469031858109;8889.822203555763;8904.154105359981;38283.214598175226;38736.61263387443;39019;39158;43467.56532434635;54372.72813635907;55233.723831382194;57302.7134864323;60410.39589604048;60767.39232607617;10685.145188384755;10782.784344313226;11018.228723989243;11563.942180289381;11673.649790506299;12427.378631068592;13100.475980960655;13245.735085298433;13294.86705132975;13530.391132398869;11512.712182195177;11602.593909213138;12009.219400738924;12243.938780306042;12564.371781411075;12034.713404453127;12334.568290109803;12390.938045309716;12614.243145411305;12809.935950320189;11545.538178472767;11576.768464630828;11673.883261167279;11714;12002.939985300018;9436.905630943877;9688.9031109688;9893.307468477138;10082.949585252027;10204.897951020395;9580.329376943548;9785.608575656894;9912.918287159777;10022.94300238405;10025.147862431775 05:28:10 INFO - PROCESS | 13782 | __end_tp_report 05:28:10 INFO - PROCESS | 13782 | __start_cc_report 05:28:10 INFO - PROCESS | 13782 | _x_x_mozilla_cycle_collect,1049 05:28:10 INFO - PROCESS | 13782 | __end_cc_report 05:28:10 INFO - PROCESS | 13782 | __startTimestamp1465475290177__endTimestamp 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | ------- Summary: start ------- 05:28:10 INFO - PROCESS | 13782 | Number of tests: 6 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | [#0] dojo.html Cycles:165 Average:4668.83 Median:4799.04 stddev:502.78 (10.5%) stddev-sans-first:476.83 05:28:10 INFO - PROCESS | 13782 | Values: 2578.4 2601.3 2628.0 2632.5 2635.6 4565.4 4678.8 4704.9 4754.2 4801.4 4683.4 4712.5 4713.5 4727.5 4732.3 4145.4 4289.6 4375.6 4403.0 4500.7 4402.9 4433.2 4453.4 4461.4 4486.7 4217.7 4259.7 4294.9 4338.8 4361.2 4255.8 4256.1 4270.7 4313.1 4331.8 4295.5 4350.2 4375.7 4408.4 4412.8 4605.9 4608.5 4676.1 4681.5 4726.0 5056.7 5098.3 5117.1 5123.4 5128.2 5094.6 5113.3 5121.5 5142.5 5154.1 4593.2 4678.6 4738.1 4742.5 4743.6 5213.7 5229.2 5243.6 5244.8 5249.9 4986.8 5027.1 5027.7 5028.9 5032.1 4888.8 5000.0 5059.3 5061.9 5067.1 5024.2 5038.0 5041.4 5052.4 5086.6 4841.5 5002.7 5008.7 5009.4 5013.8 4182.9 4216.0 4240.0 4241.5 4284.1 3665.9 3666.3 3674.4 3674.6 3713.2 4255.0 4343.4 4349.7 4398.4 4398.5 5047.7 5075.3 5082.3 5111.6 5120.6 4385.9 4391.4 4391.6 4391.9 4412.6 4444.4 4508.8 4548.2 4552.6 4584.4 4772.4 4777.8 4795.2 4796.8 4844.8 5096.7 5101.7 5102.2 5103.3 5137.9 4633.1 4705.6 4799.0 4808.4 4856.4 4944.5 4976.0 4997.4 4999.4 5044.4 5073.3 5074.7 5076.7 5077.0 5129.5 4796.8 4843.0 4890.1 4918.2 4922.3 4901.5 4912.2 4923.1 4924.2 4950.8 4852.0 4856.6 4873.7 4881.0 4883.1 4744.6 4856.9 4896.4 4910.6 4911.3 4830.6 4836.9 4843.9 4901.1 4951.2 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | [#1] ext.html Cycles:180 Average:57099.83 Median:21433.91 stddev:112061.64 (522.8%) stddev-sans-first:112295.59 05:28:10 INFO - PROCESS | 13782 | Values: 558.2 577.2 580.3 580.4 581.2 29845.1 30524.7 40385.8 42054.8 42070.8 41740.6 42239.6 42292.4 42302.4 42395.8 7780.6 7945.9 7951.1 7952.8 7956.4 7804.5 7887.2 7899.7 7900.4 7901.0 7911.5 7930.3 7938.7 7947.7 7954.3 7881.6 7923.7 7926.2 7927.1 7928.7 7817.0 7842.1 7848.6 7871.6 7871.9 39816.6 39987.4 40061.0 40153.8 40179.6 2956.3 3073.8 3185.9 3426.5 3904.8 28393.0 28755.7 30424.6 30825.0 31335.7 3229.1 3267.9 3282.3 3327.8 3344.8 451263.0 458105.0 460192.0 462071.0 466326.0 60840.7 60892.4 61141.0 61374.7 61507.0 34581.3 35978.3 35998.1 38181.8 64158.7 16199.8 16348.4 16369.7 16381.7 16386.9 12254.5 12382.8 12406.7 12407.4 12408.8 19642.3 19689.1 19718.6 19846.3 19883.3 7261.4 7387.5 7496.0 7532.5 7640.9 834.4 877.4 877.5 893.4 995.2 27777.4 28663.6 29375.6 29496.0 29706.6 909.4 912.0 984.7 1008.1 1044.2 14986.9 15134.2 15239.8 15396.9 15405.4 420842.0 424769.0 427469.0 427848.0 428198.0 51683.0 52166.0 52380.5 52438.0 52438.5 1097.0 1169.5 1212.9 1218.1 1235.3 29215.3 29886.6 30212.2 30462.7 30748.5 44369.0 48036.3 50096.2 50140.0 55322.4 377209.0 378530.0 380925.0 383375.0 385194.0 32802.5 33698.7 34648.0 40588.0 41349.4 38729.0 40983.2 42242.0 44090.8 45949.8 38240.2 38530.4 38836.6 38865.0 38904.9 38251.8 38274.6 38522.2 38683.6 38963.0 37059.4 37776.2 38081.4 38287.0 38473.0 33886.5 34685.0 34877.8 35324.8 35373.0 25928.9 25934.5 26259.2 26362.7 26683.5 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | [#2] jquery.html Cycles:180 Average:23003.64 Median:221004.00 stddev:57791.05 (26.1%) stddev-sans-first:57928.12 05:28:10 INFO - PROCESS | 13782 | Values: 345.5 347.5 352.1 355.3 367.1 15704.9 16037.8 17198.1 17198.7 17212.7 12815.7 13589.4 15989.1 15990.3 16793.9 4786.2 4979.4 5004.9 5165.0 5171.9 5014.1 5033.9 5056.6 5111.2 5117.4 3581.1 3786.0 3857.6 5886.9 5910.8 5943.0 5971.2 5988.3 6007.3 6010.3 5056.9 5142.7 5158.2 5167.6 5178.2 19446.5 19601.0 19663.0 20364.7 20435.5 1960.0 2081.1 2123.5 2383.9 2384.8 12137.1 12222.2 12961.9 13211.1 13486.5 1623.3 1628.8 1653.9 1702.2 1713.8 40707.0 52724.5 54835.2 68252.7 68329.7 8977.3 8995.7 9108.8 9185.2 9320.6 12276.0 12362.5 12376.3 12380.1 12405.6 7246.9 7343.6 7407.6 7494.5 7610.4 7461.9 7466.0 7507.8 7524.8 7599.3 5587.8 5623.2 5761.8 5775.8 5820.3 1633.8 1649.3 1662.3 1666.0 1689.5 793.7 826.2 828.2 894.8 939.4 16280.3 16354.3 16653.3 16904.7 16981.4 823.6 861.4 907.9 911.1 966.5 7778.4 9390.8 9713.6 9781.1 9913.4 297442.0 302292.0 303470.0 401301.0 411614.0 85872.0 87182.0 87430.0 88464.1 88641.6 901.2 916.1 924.2 941.6 951.7 15061.5 15398.3 15401.6 15643.1 15756.6 37197.3 38187.4 38340.4 38414.8 38451.2 51797.5 52670.5 53289.7 53293.5 53650.1 6518.9 6988.2 7112.6 7230.5 7254.3 10681.1 11725.1 11787.1 11974.7 12036.3 10238.6 10271.6 10312.6 10446.2 10515.8 10255.1 10336.9 10412.2 10542.7 10577.7 10175.7 10230.8 10442.9 10500.6 10745.7 6844.4 7007.8 7008.9 7228.9 7248.3 9604.5 10184.1 10282.6 10288.4 10302.5 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | [#3] mootools.html Cycles:180 Average:4874.92 Median:5064.82 stddev:2736.95 (54.0%) stddev-sans-first:2730.72 05:28:10 INFO - PROCESS | 13782 | Values: 1203.8 1362.4 1402.6 1469.8 1722.2 6365.1 7082.1 7349.7 7362.0 7592.6 7404.7 7423.6 7481.9 7484.0 7511.1 2148.5 2176.3 2179.2 2180.2 2182.6 2120.2 2140.8 2166.9 2171.2 2175.3 2102.1 2138.4 2145.2 2146.9 2171.6 2060.5 2095.6 2097.2 2097.3 2097.4 2024.8 2104.1 2108.2 2109.1 2114.8 7323.9 7419.4 7593.1 7605.1 7606.0 4389.8 4593.4 4597.0 4601.1 4601.3 2674.3 2758.4 2818.2 2819.8 2826.7 871.7 874.7 877.0 880.8 884.8 10023.9 10108.5 10112.0 10118.3 10140.6 3890.4 3977.2 4017.2 4048.3 4054.5 8069.8 8088.5 8152.9 8168.8 8175.5 4425.4 4488.1 4493.5 4498.1 4504.0 3543.7 3949.4 4088.1 4202.5 4225.7 3248.8 3270.1 3343.5 3347.7 3360.5 951.5 989.6 993.7 997.9 1002.1 685.1 686.1 733.0 735.5 852.9 4790.5 4803.9 4920.2 4943.4 4947.1 392.1 397.6 397.6 403.5 407.8 3387.9 3413.4 3433.2 3445.1 3449.9 8832.2 8950.9 9175.1 9183.2 9232.3 9013.0 9094.0 9132.6 9141.2 9160.6 6765.3 7039.3 7050.2 7074.0 7151.2 5182.5 5230.2 5248.6 5319.5 5334.8 8436.4 8626.6 8643.6 8647.9 8648.2 8346.9 8607.9 8661.4 8665.2 8748.7 5725.3 6324.3 6562.8 6566.2 6686.0 7060.5 7230.2 7233.8 7241.0 7241.1 6238.7 6500.5 6500.9 6575.8 6591.3 6124.9 6380.6 6440.5 6471.0 6746.9 5802.1 5843.9 5886.0 5886.4 5887.6 4193.5 4223.5 4347.8 4381.3 4402.6 4082.9 4260.4 4350.2 4361.7 4406.4 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | [#4] prototype.html Cycles:180 Average:4157.74 Median:4793.75 stddev:1210.09 (25.2%) stddev-sans-first:1178.06 05:28:10 INFO - PROCESS | 13782 | Values: 285.0 288.7 290.9 292.8 300.0 4701.5 4771.7 4821.9 4827.1 4866.1 4778.7 4783.0 4789.8 4807.7 4840.6 3550.7 3590.2 3757.8 3759.5 3790.7 3719.7 3722.9 3753.3 3768.3 3773.9 3676.0 3684.6 3700.5 3709.9 3740.5 3556.7 3620.6 3708.4 3716.4 3755.6 3241.4 3272.5 3289.4 3291.8 3300.6 4607.5 4619.2 4620.2 4625.0 4630.5 3978.8 4195.6 4211.2 4216.7 4243.2 4935.5 4954.0 4954.0 4959.5 4961.0 3588.7 3593.8 3593.9 3599.2 3621.7 5005.2 5229.6 5230.3 5236.4 5256.4 3651.2 3675.2 3678.7 3694.2 3713.4 3713.4 3720.1 3726.1 3736.3 3763.0 4287.5 4289.4 4291.5 4302.3 4305.7 4822.5 4995.4 5014.7 5025.7 5043.5 1288.1 1288.7 1291.6 1299.4 1304.5 803.7 820.4 824.5 842.5 843.8 4321.9 4449.1 4459.4 4464.7 4476.3 5065.2 5088.1 5094.9 5101.0 5120.9 4355.2 4393.5 4405.1 4419.4 4452.5 2557.8 2720.4 2731.9 2737.0 2745.6 4817.0 4831.6 4861.7 4894.9 4917.2 4961.7 5239.5 5242.8 5244.4 5274.3 4863.1 4875.3 4880.2 4885.0 4898.0 5106.8 5110.3 5132.8 5149.7 5168.1 4797.7 5038.4 5043.4 5063.9 5064.6 4897.2 4922.3 4923.9 4932.2 4936.5 3575.5 3583.9 3594.7 3596.5 3619.8 5078.7 5158.8 5162.2 5205.9 5206.8 5026.8 5032.6 5047.2 5061.7 5062.5 5026.2 5048.3 5057.0 5058.6 5079.1 4712.0 4937.4 4938.8 4972.3 4992.9 4830.5 4962.5 5056.5 5057.4 5064.6 4948.3 4954.3 4956.3 4981.0 4989.1 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - PROCESS | 13782 | [#5] yui.html Cycles:180 Average:16483.54 Median:503.41 stddev:22595.01 (4488.4%) stddev-sans-first:22626.55 05:28:10 INFO - PROCESS | 13782 | Values: 508.3 524.2 576.9 604.2 667.3 12625.4 13087.4 13544.7 13593.5 13609.7 12813.3 12884.9 12985.2 12988.5 12996.2 632.0 756.1 774.1 782.8 794.0 787.0 789.7 790.1 790.2 791.1 825.7 832.2 832.4 837.1 838.3 659.0 663.8 666.3 668.5 831.9 576.3 594.4 601.5 601.8 602.5 12837.4 12852.4 12860.5 12861.0 12866.8 11733.6 12267.9 12306.4 12330.8 12671.4 9502.2 9543.7 9820.6 9839.3 10821.8 4131.5 4142.4 4174.1 4227.3 4241.0 56705.7 57742.0 58012.4 59301.4 59402.4 9971.0 10159.8 10169.4 10215.5 10246.8 17772.7 17780.9 17788.6 17797.9 17804.1 6033.8 6047.2 6104.6 6107.3 6246.9 3426.5 3437.0 3469.8 3483.2 3614.3 2723.9 2785.7 2796.8 2799.0 2799.6 481.2 481.5 485.5 497.7 498.5 475.6 528.0 535.5 551.7 574.0 7113.2 7128.2 7374.2 7409.6 7488.5 512.4 520.4 547.8 554.8 567.4 3691.8 3701.1 3705.0 3777.2 3816.7 78224.6 81168.0 81767.6 90518.5 91244.5 49267.8 54246.5 77218.2 78591.0 80504.6 70325.0 72938.0 73818.6 75565.2 76311.6 8571.6 8649.7 8849.5 8889.8 8904.2 38283.2 38736.6 39019.0 39158.0 43467.6 54372.7 55233.7 57302.7 60410.4 60767.4 10685.1 10782.8 11018.2 11563.9 11673.6 12427.4 13100.5 13245.7 13294.9 13530.4 11512.7 11602.6 12009.2 12243.9 12564.4 12034.7 12334.6 12390.9 12614.2 12809.9 11545.5 11576.8 11673.9 11714.0 12002.9 9436.9 9688.9 9893.3 10082.9 10204.9 9580.3 9785.6 9912.9 10022.9 10025.1 05:28:10 INFO - PROCESS | 13782 | -------- Summary: end -------- 05:28:10 INFO - PROCESS | 13782 | 05:28:10 INFO - TEST-INFO | 13782: exit 0 05:28:10 INFO - TEST-OK | dromaeo_css | took 1119267ms 05:28:10 INFO - TEST-START | kraken 05:28:10 INFO - operating with platform_type : linux_ 05:28:10 INFO - Initialising browser for kraken test... 05:28:10 INFO - TEST-INFO | started process 13963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvK8Y_v/profile http://localhost:59098/getInfo.html) 05:28:15 INFO - PROCESS | 13963 | __metrics Screen width/height:1600/1200 05:28:15 INFO - PROCESS | 13963 | colorDepth:24 05:28:15 INFO - PROCESS | 13963 | Browser inner width/height: 1024/697 05:28:15 INFO - PROCESS | 13963 | __metrics 05:28:16 ERROR - PROCESS | 13963 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:28:16 INFO - PROCESS | 13963 | console.error: PushService: 05:28:16 INFO - PROCESS | 13963 | stateChangeProcessEnqueue: Error transitioning state 05:28:16 INFO - PROCESS | 13963 | UnknownError 05:28:17 INFO - TEST-INFO | 13963: exit 0 05:28:17 INFO - Browser initialized. 05:28:17 INFO - Running cycle 1/1 for kraken test... 05:28:17 INFO - TEST-INFO | started process 14042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvK8Y_v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:29 INFO - PROCESS | 14042 | RSS: Main: 175181824 05:28:29 INFO - PROCESS | 14042 | 05:28:32 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59098/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:33 INFO - PROCESS | 14042 | RSS: Main: 232779776 05:28:33 INFO - PROCESS | 14042 | 05:28:36 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59098/tests/kraken/driver.html?testName=audio-dft) 05:28:36 INFO - PROCESS | 14042 | RSS: Main: 220430336 05:28:36 INFO - PROCESS | 14042 | 05:28:40 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59098/tests/kraken/driver.html?testName=audio-fft) 05:28:40 INFO - PROCESS | 14042 | RSS: Main: 287973376 05:28:40 INFO - PROCESS | 14042 | 05:28:42 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59098/tests/kraken/driver.html?testName=audio-oscillator) 05:28:43 INFO - PROCESS | 14042 | RSS: Main: 197525504 05:28:43 INFO - PROCESS | 14042 | 05:28:45 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59098/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:46 INFO - PROCESS | 14042 | RSS: Main: 201695232 05:28:46 INFO - PROCESS | 14042 | 05:28:49 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59098/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:50 INFO - PROCESS | 14042 | RSS: Main: 237375488 05:28:50 INFO - PROCESS | 14042 | 05:28:53 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59098/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:54 INFO - PROCESS | 14042 | RSS: Main: 233525248 05:28:54 INFO - PROCESS | 14042 | 05:28:57 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59098/tests/kraken/driver.html?testName=json-parse-financial) 05:28:57 INFO - PROCESS | 14042 | RSS: Main: 236560384 05:28:57 INFO - PROCESS | 14042 | 05:28:59 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59098/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:00 INFO - PROCESS | 14042 | RSS: Main: 221151232 05:29:00 INFO - PROCESS | 14042 | 05:29:02 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:02 INFO - PROCESS | 14042 | RSS: Main: 225095680 05:29:02 INFO - PROCESS | 14042 | 05:29:04 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:05 INFO - PROCESS | 14042 | RSS: Main: 234446848 05:29:05 INFO - PROCESS | 14042 | 05:29:08 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:08 INFO - PROCESS | 14042 | RSS: Main: 240361472 05:29:08 INFO - PROCESS | 14042 | 05:29:11 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:12 INFO - PROCESS | 14042 | RSS: Main: 240775168 05:29:12 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | Cycle 1(1): loaded http://localhost:59098/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59098/tests/kraken/driver.html?testName=ai-astar) 05:29:14 INFO - PROCESS | 14042 | RSS: Main: 283648000 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | __start_tp_report 05:29:14 INFO - PROCESS | 14042 | _x_x_mozilla_page_load 05:29:14 INFO - PROCESS | 14042 | _x_x_mozilla_page_load_details 05:29:14 INFO - PROCESS | 14042 | |i|pagename|runs| 05:29:14 INFO - PROCESS | 14042 | |0;ai-astar;114;109;113;116;115;114;113;112;114;114 05:29:14 INFO - PROCESS | 14042 | |1;audio-beat-detection;156;131;145;186;130;170;158;130;188;128 05:29:14 INFO - PROCESS | 14042 | |2;audio-dft;163;160;161;162;162;161;163;162;163;162 05:29:14 INFO - PROCESS | 14042 | |3;audio-fft;74;76;77;100;76;77;76;75;108;74 05:29:14 INFO - PROCESS | 14042 | |4;audio-oscillator;87;87;112;87;86;87;120;86;88;131 05:29:14 INFO - PROCESS | 14042 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;117 05:29:14 INFO - PROCESS | 14042 | |6;imaging-darkroom;147;147;148;147;147;147;147;147;147;147 05:29:14 INFO - PROCESS | 14042 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;94 05:29:14 INFO - PROCESS | 14042 | |8;json-parse-financial;64;64;64;65;64;65;63;64;63;63 05:29:14 INFO - PROCESS | 14042 | |9;json-stringify-tinderbox;63;63;64;64;64;64;77;64;64;64 05:29:14 INFO - PROCESS | 14042 | |10;stanford-crypto-aes;85;84;84;90;88;94;86;87;122;86 05:29:14 INFO - PROCESS | 14042 | |11;stanford-crypto-ccm;136;131;139;131;130;130;138;131;156;132 05:29:14 INFO - PROCESS | 14042 | |12;stanford-crypto-pbkdf2;174;170;175;167;169;169;170;171;172;169 05:29:14 INFO - PROCESS | 14042 | |13;stanford-crypto-sha256-iterative;68;70;68;66;71;67;70;68;69;65 05:29:14 INFO - PROCESS | 14042 | __end_tp_report 05:29:14 INFO - PROCESS | 14042 | __start_cc_report 05:29:14 INFO - PROCESS | 14042 | _x_x_mozilla_cycle_collect,1905 05:29:14 INFO - PROCESS | 14042 | __end_cc_report 05:29:14 INFO - PROCESS | 14042 | __startTimestamp1465475354484__endTimestamp 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | ------- Summary: start ------- 05:29:14 INFO - PROCESS | 14042 | Number of tests: 14 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.90 (1.7%) stddev-sans-first:2.00 05:29:14 INFO - PROCESS | 14042 | Values: 114.0 109.0 113.0 116.0 115.0 114.0 113.0 112.0 114.0 114.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#1] audio-beat-detection Cycles:10 Average:152.20 Median:157.00 stddev:23.24 (14.8%) stddev-sans-first:24.61 05:29:14 INFO - PROCESS | 14042 | Values: 156.0 131.0 145.0 186.0 130.0 170.0 158.0 130.0 188.0 128.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 05:29:14 INFO - PROCESS | 14042 | Values: 163.0 160.0 161.0 162.0 162.0 161.0 163.0 162.0 163.0 162.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:12.16 (16.0%) stddev-sans-first:12.60 05:29:14 INFO - PROCESS | 14042 | Values: 74.0 76.0 77.0 100.0 76.0 77.0 76.0 75.0 108.0 74.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:87.00 stddev:17.10 (19.7%) stddev-sans-first:17.75 05:29:14 INFO - PROCESS | 14042 | Values: 87.0 87.0 112.0 87.0 86.0 87.0 120.0 86.0 88.0 131.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:29:14 INFO - PROCESS | 14042 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 05:29:14 INFO - PROCESS | 14042 | Values: 147.0 147.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 05:29:14 INFO - PROCESS | 14042 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 05:29:14 INFO - PROCESS | 14042 | Values: 64.0 64.0 64.0 65.0 64.0 65.0 63.0 64.0 63.0 63.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:64.00 stddev:4.20 (6.6%) stddev-sans-first:4.39 05:29:14 INFO - PROCESS | 14042 | Values: 63.0 63.0 64.0 64.0 64.0 64.0 77.0 64.0 64.0 64.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:86.50 stddev:11.44 (13.2%) stddev-sans-first:11.96 05:29:14 INFO - PROCESS | 14042 | Values: 85.0 84.0 84.0 90.0 88.0 94.0 86.0 87.0 122.0 86.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:134.00 stddev:7.97 (6.0%) stddev-sans-first:8.46 05:29:14 INFO - PROCESS | 14042 | Values: 136.0 131.0 139.0 131.0 130.0 130.0 138.0 131.0 156.0 132.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:170.50 stddev:2.46 (1.4%) stddev-sans-first:2.28 05:29:14 INFO - PROCESS | 14042 | Values: 174.0 170.0 175.0 167.0 169.0 169.0 170.0 171.0 172.0 169.0 05:29:14 INFO - PROCESS | 14042 | 05:29:14 INFO - PROCESS | 14042 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:1.87 (2.7%) stddev-sans-first:1.99 05:29:14 INFO - PROCESS | 14042 | Values: 68.0 70.0 68.0 66.0 71.0 67.0 70.0 68.0 69.0 65.0 05:29:14 INFO - PROCESS | 14042 | -------- Summary: end -------- 05:29:14 INFO - PROCESS | 14042 | 05:29:15 INFO - TEST-INFO | 14042: exit 0 05:29:15 INFO - TEST-OK | kraken | took 64594ms 05:29:15 INFO - SUITE-END | took 1183s 05:29:15 INFO - Completed test suite (00:19:43) 05:29:15 INFO - javascript benchmark 05:29:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2578.3940773918125, 2601.323655849481, 2628.0407651207315, 2632.5261452938475, 2635.5783074708042, 4565.429321334833, 4678.766061696919, 4704.905901881961, 4754.239321708523, 4801.399825021872, 4683.437987441512, 4712.481627021025, 4713.528647135285, 4727.4563425206115, 4732.266498692699, 4145.419641250227, 4289.61393474588, 4375.606195442409, 4403.0, 4500.729956202622, 4402.86791396257, 4433.2241857674935, 4453.376527286183, 4461.442319710036, 4486.685931984763, 4217.704760666755, 4259.659227261812, 4294.914101717948, 4338.783060846945, 4361.214981303364, 4255.829766809324, 4256.1487702459635, 4270.701050926436, 4313.051128751669, 4331.826726930919, 4295.463067116611, 4350.2387082260475, 4375.7155784873885, 4408.426904502427, 4412.779361031936, 4605.884852878671, 4608.4930657627965, 4676.134915040729, 4681.485036645966, 4725.952740472586, 5056.696598204119, 5098.260752190949, 5117.078925793356, 5123.38519377677, 5128.153854613985, 5094.59243260542, 5113.309703190059, 5121.462246464105, 5142.537171654569, 5154.123798954187, 4593.242115051013, 4678.625709943264, 4738.099761045391, 4742.5020372861045, 4743.596794272526, 5213.687178769286, 5229.241759944786, 5243.554297884646, 5244.76398562058, 5249.947500525022, 4986.750662466862, 5027.145385284511, 5027.748612569357, 5028.949710502849, 5032.144535428986, 4888.828890988798, 4999.975000125049, 5059.342285502935, 5061.924071138936, 5067.087924173684, 5024.171011783088, 5038.042771873333, 5041.42023667283, 5052.368453943257, 5086.643934924594, 4841.540053694894, 5002.749862506933, 5008.674436161711, 5009.423916249678, 5013.849584512471, 4182.916341673211, 4215.97892010538, 4239.97880010598, 4241.512226093977, 4284.057507348379, 3665.890023299305, 3666.3034023535442, 3674.3569875272256, 3674.595794462607, 3713.183099718112, 4255.042615411569, 4343.391925130484, 4349.717268377545, 4398.362237475548, 4398.494173170063, 5047.747612619428, 5075.264086707407, 5082.263071854561, 5111.591072714174, 5120.590352771769, 4385.934210986838, 4391.407160033355, 4391.626711729475, 4391.890202744957, 4412.646988240933, 4444.400005999213, 4508.81964721414, 4548.226801443762, 4552.590266875997, 4584.358189853423, 4772.427308722906, 4777.784999674954, 4795.20879054966, 4796.808127674994, 4844.8304309349705, 5096.668716533413, 5101.668391554537, 5102.183650615883, 5103.260027296095, 5137.948620513747, 4633.073385323017, 4705.623550116051, 4799.0401919615515, 4808.374911261513, 4856.441509226484, 4944.480829512993, 4975.950240497548, 4997.400311962588, 4999.350084489139, 5044.4451110377095, 5073.315102461121, 5074.67014644047, 5076.7207803571155, 5076.974615126901, 5129.487051294841, 4796.784144713426, 4843.0, 4890.119778439915, 4918.213085906238, 4922.326229834637, 4901.534354236342, 4912.1894887344615, 4923.138450771173, 4924.212126059813, 4950.752462376939, 4851.975740121276, 4856.587190088882, 4873.731944742932, 4880.951190488192, 4883.145449546386, 4744.620430365631, 4856.902861942671, 4896.36347274852, 4910.631702622389, 4911.312416261798, 4830.637702172281, 4836.854894353175, 4843.903121937612, 4901.117798796251, 4951.183054796063], "value": 4635.868818291181, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.2268558047105, 577.1890493856132, 580.33551583437, 580.3760956971257, 581.2356750872602, 29845.1046468606, 30524.694753052492, 40385.7980710096, 42054.78972605133, 42070.78964605173, 41740.58259417405, 42239.577604223945, 42292.36561451581, 42302.36546451798, 42395.78802105985, 7780.5720685362285, 7945.880811787828, 7951.085625153095, 7952.840943181133, 7956.363490920741, 7804.531728096304, 7887.211278872095, 7899.723509677163, 7900.446968712193, 7901.0, 7911.52530848148, 7930.286274235317, 7938.682452701885, 7947.7218297359605, 7954.2841144297, 7881.60591970402, 7923.683052677886, 7926.167752386003, 7927.08838483576, 7928.722494712657, 7816.960915195445, 7842.058952925629, 7848.568328741917, 7871.60641967905, 7871.921280787176, 39816.60183398158, 39987.40018899719, 40061.0, 40153.79923100395, 40179.59820401788, 2956.3200463893427, 3073.846307684629, 3185.904422867318, 3426.5374174486374, 3904.824282907276, 28393.0, 28755.712442875512, 30424.620179197587, 30825.0, 31335.686643133733, 3229.0958531611186, 3267.9282868525897, 3282.294306724066, 3327.8169700666454, 3344.832758362072, 451263.0, 458105.0, 460192.0, 462070.9999999966, 466326.0, 60840.69579652162, 60892.39107608956, 61141.0, 61374.69312653408, 61507.0, 34581.308373832384, 35978.28043439169, 35998.10004749902, 38181.80909095437, 64158.679206603665, 16199.757003644954, 16348.427805026768, 16369.672606547801, 16381.672366552602, 16386.918065409598, 12254.509819607294, 12382.75234495305, 12406.689832754253, 12407.44166512509, 12408.81386779199, 19642.312519061765, 19689.11398987049, 19718.605627887362, 19846.305379311943, 19883.309918657076, 7261.3890179736245, 7387.45402206933, 7496.0, 7532.54804711719, 7640.885386719204, 834.3992325525612, 877.3814460805118, 877.5349064995562, 893.396957053986, 995.1640621877513, 27777.444451110863, 28663.56682165925, 29375.55936660952, 29496.0, 29706.554401683992, 909.3900505548985, 911.9726408207764, 984.7390441532999, 1008.1481148429565, 1044.2116202267239, 14986.850131498764, 15134.243287835565, 15239.847601523843, 15396.923015384851, 15405.38378464849, 420842.0, 424769.0, 427469.0, 427848.0, 428198.0, 51683.0, 52166.0, 52380.47619523756, 52438.0, 52438.47561524412, 1096.9890301097046, 1169.5146514196513, 1212.89690376319, 1218.0925210718071, 1235.3020543392954, 29215.269618259714, 29886.551701724056, 30212.24469388283, 30462.695373045986, 30748.538771917993, 44369.0, 48036.279455808886, 50096.24855627241, 50140.0, 55322.44677553173, 377209.0, 378530.0, 380925.0, 383375.0, 385194.0, 32802.50796238106, 33698.66301337053, 34648.0, 40587.98530036773, 41349.37975930423, 38729.0, 40983.18033639251, 42242.0, 44090.779546102065, 45949.77025114853, 38240.2351952965, 38530.422043669925, 38836.6116338833, 38865.0, 38904.864605799594, 38251.80874095612, 38274.617253827106, 38522.22955540817, 38683.613163868, 38963.0, 37059.44410833894, 37776.24447511089, 38081.42877856779, 38287.0, 38473.0, 33886.491702624975, 34685.0, 34877.82561087178, 35324.82337588296, 35373.0, 25928.8703556481, 25934.48131037331, 26259.21222363332, 26362.736372636027, 26683.466330673666], "value": 18679.085347329914, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.46280533769976, 347.52389226759334, 352.09335959903257, 355.2965129044492, 367.13722751533896, 15704.92147539262, 16037.759433608491, 17198.140092995363, 17198.65602687947, 17212.741808872845, 12815.743685126292, 13589.38847751851, 15989.120598367113, 15990.280437380346, 16793.91603041983, 4786.162421576219, 4979.402471703401, 5004.949950500503, 5164.974175129119, 5171.948280517203, 5014.097462456756, 5033.899322013557, 5056.558880718977, 5111.1566591512355, 5117.411497677777, 3581.1405262736885, 3785.9621403786023, 3857.594952529972, 5886.941130588703, 5910.793122240722, 5942.97028514859, 5971.193888824996, 5988.311344195408, 6007.279126504825, 6010.308814486324, 5056.898862022776, 5142.6914385137015, 5158.200478925773, 5167.560757335631, 5178.171492561172, 19446.513837154183, 19601.0, 19663.01684915763, 20364.69452958207, 20435.48911277215, 1959.9510012249666, 2081.105124796352, 2123.490362313049, 2383.8569685818907, 2384.7615238476187, 12137.089718271076, 12222.20555663879, 12961.935190323988, 13211.075224734272, 13486.527971520949, 1623.310093210381, 1628.7638292447648, 1653.9173041347885, 1702.1914590569381, 1713.8114807371303, 40707.0, 52724.47275527234, 54835.17747233795, 68252.6587367065, 68329.65835170842, 8977.281817454588, 8995.730128096167, 9108.772280693036, 9185.173334399802, 9320.580573874191, 12276.017918566493, 12362.505499780087, 12376.257424554555, 12380.071494637757, 12405.565805196955, 7246.927530724731, 7343.6305398289005, 7407.592582407911, 7494.54074825478, 7610.429217808686, 7461.85076298471, 7466.0, 7507.8498430031095, 7524.811879703051, 7599.278068583476, 5587.832365029056, 5623.156526520973, 5761.769529218868, 5775.768969241183, 5820.330661973842, 1633.8202797692245, 1649.3237772513453, 1662.276909544352, 1666.033700453734, 1689.4678176374402, 793.7182300283316, 826.2233500509622, 828.1552816127517, 894.8120894612074, 939.3706216834601, 16280.348786048662, 16354.264058117174, 16653.3338666452, 16904.661906761798, 16981.386229501713, 823.5511646152861, 861.4056301152185, 907.9001309856043, 911.1435250864166, 966.4587830814626, 7778.377729781604, 9390.812183756287, 9713.61145554184, 9781.119699226962, 9913.405195688281, 297442.0, 302292.0, 303470.0, 401301.0, 411614.0, 85872.0, 87182.0, 87430.0, 88464.11535884559, 88641.55679221691, 901.1574178143456, 916.1067958740308, 924.1543987251724, 941.6327632223438, 951.7287573041511, 15061.472848450252, 15398.307076181374, 15401.614959626098, 15643.139627320606, 15756.60608484797, 37197.25605487929, 38187.42718859164, 38340.424893627176, 38414.807925960195, 38451.230975380895, 51797.48202517926, 52670.473295268086, 53289.73355133199, 53293.46706532885, 53650.09848916694, 6518.934810652023, 6988.231294557696, 7112.608806515691, 7230.481598864087, 7254.274572542704, 10681.145508359468, 11725.120615954009, 11787.057035437316, 11974.700632484257, 12036.277823330629, 10238.641647542301, 10271.589136434757, 10312.587496500057, 10446.164306855282, 10515.789684206426, 10255.128314093086, 10336.896631033593, 10412.167026637699, 10542.683719488428, 10577.735556611146, 10175.745606359604, 10230.795384092127, 10442.947785261025, 10500.632477863239, 10745.71051473828, 6844.384005439534, 7007.789766307019, 7008.859822803617, 7228.891566626399, 7248.275172482709, 9604.519774011133, 10184.134348580157, 10282.588696452058, 10288.434136122585, 10302.536385862804], "value": 8168.185169677323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1203.7712834561428, 1362.3528823808692, 1402.5652047865162, 1469.8456662050485, 1722.1733567887413, 6365.140706004688, 7082.114735658043, 7349.742759003436, 7362.042934418531, 7592.620368981557, 7404.66678999445, 7423.591702456362, 7481.850362992751, 7483.962580187105, 7511.136219334778, 2148.4628842789343, 2176.3035828534876, 2179.1610230061383, 2180.2042254577123, 2182.6289530779727, 2120.1943261560587, 2140.7538133114736, 2166.869987800737, 2171.2183613898983, 2175.260411460103, 2102.054075665949, 2138.4119367174076, 2145.206273678741, 2146.9033893474752, 2171.576542574199, 2060.495838038233, 2095.5913596848623, 2097.1820989813987, 2097.307888396833, 2097.3603051069417, 2024.8278896293832, 2104.053176070759, 2108.1778106538463, 2109.1141720477285, 2114.7779483154354, 7323.890141647879, 7419.443541734391, 7593.05086864142, 7605.087389513238, 7606.0, 4389.802458889358, 4593.40285762852, 4596.954030459686, 4601.125786100663, 4601.263797792337, 2674.3046807830106, 2758.434520923206, 2818.154553633922, 2819.84490853003, 2826.745592896649, 871.6992637540038, 874.6501399440211, 877.0265005843509, 880.7798050487378, 884.7920738626418, 10023.899761002369, 10108.494575271281, 10112.0, 10118.291719579633, 10140.645077422254, 3890.3580909149673, 3977.204559088194, 4017.1764788218347, 4048.27131116396, 4054.4729185205733, 8069.83860322802, 8088.474249173785, 8152.918470815334, 8168.836623267502, 8175.509469431853, 4425.35832304314, 4488.1248156609145, 4493.460784705856, 4498.1003799239625, 4504.0, 3543.7342199335153, 3949.4273330366937, 4088.1414902870138, 4202.453681021448, 4225.68307376948, 3248.7888287261253, 3270.1497610620936, 3343.5319055332275, 3347.7154441872713, 3360.479125735515, 951.509972364233, 989.5992123190119, 993.6820217530466, 997.8952210017843, 1002.1331548210891, 685.1230425055936, 686.1491750229779, 732.9816754581178, 735.5108852612952, 852.863541833316, 4790.520947905182, 4803.855884323406, 4920.237363208708, 4943.406791185009, 4947.134251505972, 392.0923063108951, 397.5845241722407, 397.622258854084, 403.5157810627259, 407.8225971702319, 3387.864485420605, 3413.3855905936675, 3433.2275238071734, 3445.0698311455767, 3449.913752156166, 8832.205101540763, 8950.86573701395, 9175.128362805523, 9183.219426348702, 9232.307576931622, 9013.0, 9093.954530227438, 9132.589033493508, 9141.222996045411, 9160.587773550205, 6765.323467653196, 7039.331263529956, 7050.224475307712, 7074.045003924404, 7151.213366529678, 5182.507661772125, 5230.215467679726, 5248.553872920845, 5319.468053194804, 5334.786608535615, 8436.367272454714, 8626.56867156627, 8643.611037503202, 8647.913520864711, 8648.221660050625, 8346.87479687793, 8607.870881936651, 8661.437006594551, 8665.220130188314, 8748.69379571712, 5725.2843394575675, 6324.335944725922, 6562.80311590653, 6566.212054553484, 6686.0, 7060.470464715064, 7230.204677485578, 7233.782986510413, 7241.0, 7241.058662374068, 6238.685005431183, 6500.544961852814, 6500.902486462802, 6575.835604109936, 6591.274959754519, 6124.8775024500155, 6380.553361264667, 6440.549161558646, 6471.029345598198, 6746.898796518154, 5802.071668533184, 5843.912341314799, 5885.970570147293, 5886.381929897299, 5887.61730487517, 4193.475815523059, 4223.514295856015, 4347.782610869507, 4381.3208952612395, 4402.647788176874, 4082.857100001486, 4260.44614200152, 4350.173467041379, 4361.694681372401, 4406.427164468727], "value": 3855.1335210675015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.951378925554, 288.69686828829725, 290.8967316602605, 292.83601183337333, 299.9925001874954, 4701.495521433147, 4771.665983381165, 4821.855344339667, 4827.082854257697, 4866.099771542263, 4778.71327720337, 4783.0, 4789.760511974405, 4807.668280883778, 4840.636952228588, 3550.6626870447267, 3590.2101537661674, 3757.830897609614, 3759.473673685686, 3790.654317717212, 3719.7210209234418, 3722.9441558376648, 3753.3244016077097, 3768.2651882882847, 3773.8867833964887, 3675.981620091909, 3684.613115622861, 3700.481932529448, 3709.8701545445915, 3740.532433445819, 3556.733245006622, 3620.5517792882924, 3708.406654935224, 3716.3682174030478, 3755.6056614055406, 3241.4003409369343, 3272.4927636216185, 3289.3585750778607, 3291.835408229579, 3300.5709257796575, 4607.516210797851, 4619.198512580097, 4620.237660785966, 4624.959384138576, 4630.467496237942, 3978.76127432355, 4195.601417865298, 4211.231282861206, 4216.683748718828, 4243.193793179286, 4935.481774413706, 4954.0, 4954.027583449937, 4959.528844759778, 4961.040389046307, 3588.6949609283242, 3593.838277277529, 3593.94609080864, 3599.190182209008, 3621.6740493355724, 5005.2492126181, 5229.633925625208, 5230.267762513213, 5236.413057055929, 5256.448072952323, 3651.1784848409425, 3675.209829886587, 3678.7240956928335, 3694.2057457646197, 3713.350163721338, 3713.3687273163087, 3720.1257704439436, 3726.087108658342, 3736.308782875177, 3763.040424691686, 4287.506936702257, 4289.356596510548, 4291.485021797342, 4302.333138363559, 4305.720128191658, 4822.541858523435, 4995.425526064477, 5014.724190169576, 5025.723585202849, 5043.470435604208, 1288.091895213872, 1288.7229245712028, 1291.5544137272625, 1299.4152631315947, 1304.4651692805903, 803.6624617660597, 820.3929092471528, 824.5423789796721, 842.4650347029681, 843.8227972125923, 4321.935170972438, 4449.087936972913, 4459.420275364247, 4464.709793863389, 4476.2837945928495, 5065.189569668835, 5088.135017047037, 5094.898102038012, 5101.0, 5120.873407850267, 4355.1725172217175, 4393.495707446725, 4405.140997505456, 4419.359192917009, 4452.465704115527, 2557.8465292082533, 2720.387912719662, 2731.945361092767, 2737.0, 2745.643066401355, 4816.975915120472, 4831.637627177975, 4861.669756217003, 4894.926576101361, 4917.213245880642, 4961.685153434343, 5239.458862451708, 5242.816501422432, 5244.449332820075, 5274.26160337553, 4863.148948933993, 4875.317455556225, 4880.219164933594, 4884.975575122173, 4897.975510122426, 5106.7701953412925, 5110.3356563646485, 5132.8460146195675, 5149.678457394884, 5168.147255702767, 4797.745059821878, 5038.420581633159, 5043.445221025758, 5063.949360506348, 5064.620153488429, 4897.167481528078, 4922.301805876967, 4923.852284431473, 4932.186189278729, 4936.531029552187, 3575.499430079843, 3583.9283214335046, 3594.676479116892, 3596.4964904913854, 3619.8371073301237, 5078.669886457368, 5158.819441319536, 5162.174052151638, 5205.895882082262, 5206.843794686166, 5026.84919452417, 5032.6225533084435, 5047.167217409086, 5061.670991385548, 5062.54437100648, 5026.195808670595, 5048.293238946624, 5057.0, 5058.595312374928, 5079.060373830853, 4711.976440117777, 4937.407511098691, 4938.802447902043, 4972.2790195422185, 4992.8751781205765, 4830.541098595628, 4962.528559786814, 5056.469070747518, 5057.418396884405, 5064.645474816727, 4948.292838970549, 4954.27514866829, 4956.281339205723, 4980.975095124501, 4989.101961646793], "value": 3770.267684519207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.30870016777175, 524.237234823332, 576.8875069361474, 604.2356419129799, 667.3060017581715, 12625.431855566498, 13087.411066502007, 13544.729105417886, 13593.45626174953, 13609.727805443898, 12813.295268760214, 12884.935575322133, 12985.220886746802, 12988.54540091097, 12996.220226786376, 632.0329895260236, 756.130449982519, 774.0517865614619, 782.7808213700145, 793.9682412703485, 787.0476723164977, 789.7235967411417, 790.1229635105029, 790.1663744749287, 791.1376599506566, 825.686239229092, 832.2094010689839, 832.4464231286188, 837.0750320895414, 838.2748922182299, 659.0213532903634, 663.8373598468357, 666.3136968922018, 668.5086461450829, 831.8835363049178, 576.2854060964415, 594.4441946779775, 601.485729701108, 601.7863658401292, 602.4939051197016, 12837.358132093357, 12852.421641026083, 12860.485580576766, 12860.971122310286, 12866.806997895039, 11733.589324373692, 12267.93866030673, 12306.446209920576, 12330.753384932252, 12671.429785659668, 9502.239820814388, 9543.665971690958, 9820.558074886647, 9839.311248212627, 10821.83767243492, 4131.5248746394245, 4142.357934520124, 4174.102567947906, 4227.32362821947, 4240.978795106004, 56705.71647141738, 57742.0, 58012.419875801126, 59301.40698593045, 59402.40597594012, 9971.0, 10159.847602285972, 10169.440680762482, 10215.540300686487, 10246.846297305547, 17772.733408998876, 17780.91109544444, 17788.644227115386, 17797.911010445125, 17804.109794510223, 6033.7586496540525, 6047.244094488189, 6104.603200791934, 6107.267127944675, 6246.906296405557, 3426.4860270959557, 3437.037629463754, 3469.809160496197, 3483.198864261234, 3614.2590768892314, 2723.891044358243, 2785.6935737068907, 2796.7762578993634, 2799.048323569996, 2799.580062990527, 481.1795888010933, 481.4511456939092, 485.46841208876225, 497.65164384930694, 498.51145877039977, 475.55060467857544, 527.9788808447696, 535.4886083790049, 551.657972057327, 573.9569532285095, 7113.181984071898, 7128.215896251409, 7374.188839227681, 7409.629518524052, 7488.513246638951, 512.423523536022, 520.4409373884721, 547.819219657512, 554.7808615596816, 567.4477769165056, 3691.8338674759702, 3701.0932321580844, 3704.981475092607, 3777.225668737957, 3816.656500914931, 78224.60887695524, 81168.0, 81767.59116204381, 90518.54740726254, 91244.54377728202, 49267.75366123218, 54246.45753542493, 77218.22781772223, 78591.0, 80504.5974770134, 70325.0, 72938.0, 73818.6309068462, 75565.24434755582, 76311.61844190855, 8571.61427735766, 8649.697260595849, 8849.469031858109, 8889.822203555763, 8904.154105359981, 38283.214598175226, 38736.61263387443, 39019.0, 39158.0, 43467.56532434635, 54372.72813635907, 55233.723831382194, 57302.7134864323, 60410.39589604048, 60767.39232607617, 10685.145188384755, 10782.784344313226, 11018.228723989243, 11563.942180289381, 11673.649790506299, 12427.378631068592, 13100.475980960655, 13245.735085298433, 13294.86705132975, 13530.391132398869, 11512.712182195177, 11602.593909213138, 12009.219400738924, 12243.938780306042, 12564.371781411075, 12034.713404453127, 12334.568290109803, 12390.938045309716, 12614.243145411305, 12809.935950320189, 11545.538178472767, 11576.768464630828, 11673.883261167279, 11714.0, 12002.939985300018, 9436.905630943877, 9688.9031109688, 9893.307468477138, 10082.949585252027, 10204.897951020395, 9580.329376943548, 9785.608575656894, 9912.918287159777, 10022.94300238405, 10025.147862431775], "value": 6322.017991454841, "unit": "score"}], "name": "dromaeo_css", "value": 6340.972790238608, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 113.0, 116.0, 115.0, 114.0, 113.0, 112.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 131.0, 145.0, 186.0, 130.0, 170.0, 158.0, 130.0, 188.0, 128.0], "value": 152.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 162.0, 162.0, 161.0, 163.0, 162.0, 163.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 100.0, 76.0, 77.0, 76.0, 75.0, 108.0, 74.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 112.0, 87.0, 86.0, 87.0, 120.0, 86.0, 88.0, 131.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 63.0, 64.0, 63.0, 63.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 64.0, 64.0, 64.0, 77.0, 64.0, 64.0, 64.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 90.0, 88.0, 94.0, 86.0, 87.0, 122.0, 86.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 131.0, 139.0, 131.0, 130.0, 130.0, 138.0, 131.0, 156.0, 132.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 175.0, 167.0, 169.0, 169.0, 170.0, 171.0, 172.0, 169.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 66.0, 71.0, 67.0, 70.0, 68.0, 69.0, 65.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1558.3999999999999, "alertThreshold": 2.0}]} 05:29:15 INFO - Return code: 0 05:29:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:15 ERROR - # TBPL SUCCESS # 05:29:15 INFO - Running post-action listener: _resource_record_post_action 05:29:15 INFO - ##### 05:29:15 INFO - ##### Finished run-tests step (success) 05:29:15 INFO - ##### 05:29:15 INFO - Running post-run listener: _resource_record_post_run 05:29:15 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 534609920; Read time: 28; Write time: 604432 05:29:15 INFO - TinderboxPrint: CPU usage
13.2% 05:29:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 28 05:29:15 INFO - TinderboxPrint: I/O write bytes / time
534,609,920 / 604,432 05:29:15 INFO - TinderboxPrint: CPU idle
8,322.4 (86.8%) 05:29:15 INFO - TinderboxPrint: CPU user
1,212.2 (12.6%) 05:29:15 INFO - TinderboxPrint: Swap in / out
0 / 0 05:29:15 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 237989888; Read time: 0; Write time: 358172 05:29:15 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296620032; Read time: 28; Write time: 246260 05:29:16 INFO - Running post-run listener: _upload_blobber_files 05:29:16 INFO - Blob upload gear active. 05:29:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:16 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:16 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:16 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:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:17 INFO - (blobuploader) - INFO - Done attempting. 05:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:18 INFO - (blobuploader) - INFO - Done attempting. 05:29:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:22 INFO - (blobuploader) - INFO - Done attempting. 05:29:22 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:22 INFO - Return code: 0 05:29:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f85b38ef60bb8b2653a3b5b5e15934ecaf51117df8f7e658a0477036da413e2cb8bdfe0f05a46f4ce8dc547c9c27ffb5a186d21e9d553b0af25227751acc9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f72c0534a6424c63ece1bdb789adf4d355fc19630112f715eab52440eda0ef52cb093ceb47b61aca745ba88602bca9cb6946cd480eb0ed5c91054cbb099dd1b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a645d128913cc7169eacc9c476c201b3cf5690cd866be1e8ee833950c00532595db71312deeeafc747f9d22fa0770867d550d8b34edd6682ec52bcf87bd0940"} 05:29:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:22 INFO - Contents: 05:29:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f85b38ef60bb8b2653a3b5b5e15934ecaf51117df8f7e658a0477036da413e2cb8bdfe0f05a46f4ce8dc547c9c27ffb5a186d21e9d553b0af25227751acc9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f72c0534a6424c63ece1bdb789adf4d355fc19630112f715eab52440eda0ef52cb093ceb47b61aca745ba88602bca9cb6946cd480eb0ed5c91054cbb099dd1b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a645d128913cc7169eacc9c476c201b3cf5690cd866be1e8ee833950c00532595db71312deeeafc747f9d22fa0770867d550d8b34edd6682ec52bcf87bd0940"} 05:29:22 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:22 INFO - Copying logs to upload dir... 05:29:22 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.598927 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-06-09 05:29:22.149545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 05:29:22.152702) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864 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/7f85b38ef60bb8b2653a3b5b5e15934ecaf51117df8f7e658a0477036da413e2cb8bdfe0f05a46f4ce8dc547c9c27ffb5a186d21e9d553b0af25227751acc9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f72c0534a6424c63ece1bdb789adf4d355fc19630112f715eab52440eda0ef52cb093ceb47b61aca745ba88602bca9cb6946cd480eb0ed5c91054cbb099dd1b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a645d128913cc7169eacc9c476c201b3cf5690cd866be1e8ee833950c00532595db71312deeeafc747f9d22fa0770867d550d8b34edd6682ec52bcf87bd0940"} build_url:https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011567 build_url: 'https://queue.taskcluster.net/v1/task/VihdZ2z7RSyseir6WMAZEA/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/7f85b38ef60bb8b2653a3b5b5e15934ecaf51117df8f7e658a0477036da413e2cb8bdfe0f05a46f4ce8dc547c9c27ffb5a186d21e9d553b0af25227751acc9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f72c0534a6424c63ece1bdb789adf4d355fc19630112f715eab52440eda0ef52cb093ceb47b61aca745ba88602bca9cb6946cd480eb0ed5c91054cbb099dd1b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a645d128913cc7169eacc9c476c201b3cf5690cd866be1e8ee833950c00532595db71312deeeafc747f9d22fa0770867d550d8b34edd6682ec52bcf87bd0940"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 05:29:22.180831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:29:22.181442) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465473458.219059-1002423864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004694 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:29:22.264072) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 05:29:22.264630) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 05:29:22.265150) ========= ========= Total master_lag: 0.18 =========