builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1466125645.39 results: success (0) buildid: 20160616163325 builduid: cfb02e49f05a42fab87c3dc582329388 revision: 5f95858f8ddf21ea2271a12810332efd09eff138 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.389084) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.389582) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.389873) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.405698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.405975) ========= 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=0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-16 18:07:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.3M=0s 2016-06-16 18:07:25 (47.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.536184) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.536513) ========= 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=0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016042 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.586208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:25.586573) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f95858f8ddf21ea2271a12810332efd09eff138 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5f95858f8ddf21ea2271a12810332efd09eff138 --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=0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-16 18:07:25,618 truncating revision to first 12 chars 2016-06-16 18:07:25,618 Setting DEBUG logging. 2016-06-16 18:07:25,618 attempt 1/10 2016-06-16 18:07:25,619 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f95858f8ddf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-16 18:07:26,044 unpacking tar archive at: mozilla-central-5f95858f8ddf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756682 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:26.355772) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:26.356078) ========= script_repo_revision: 5f95858f8ddf21ea2271a12810332efd09eff138 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:26.356473) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:26.356757) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 18:07:26.364107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-16 18:07:26.364376) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:07:26 INFO - MultiFileLogger online at 20160616 18:07:26 in /builds/slave/test 18:07:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:07:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:07:26 INFO - {'append_to_log': False, 18:07:26 INFO - 'base_work_dir': '/builds/slave/test', 18:07:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:07:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:07:26 INFO - 'branch': 'Firefox-Non-PGO', 18:07:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:07:26 INFO - 'config_files': ('talos/linux_config.py',), 18:07:26 INFO - 'default_actions': ('clobber', 18:07:26 INFO - 'read-buildbot-config', 18:07:26 INFO - 'download-and-extract', 18:07:26 INFO - 'populate-webroot', 18:07:26 INFO - 'create-virtualenv', 18:07:26 INFO - 'install', 18:07:26 INFO - 'run-tests'), 18:07:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:07:26 INFO - 'download_minidump_stackwalk': True, 18:07:26 INFO - 'download_symbols': 'ondemand', 18:07:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:07:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:07:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:07:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:07:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:07:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:07:26 INFO - 'installer_path': 'installer.exe', 18:07:26 INFO - 'log_level': 'info', 18:07:26 INFO - 'log_name': 'talos', 18:07:26 INFO - 'log_to_console': True, 18:07:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:07:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:07:26 INFO - 'opt_config_files': (), 18:07:26 INFO - 'pip_index': False, 18:07:26 INFO - 'sps_profile': False, 18:07:26 INFO - 'sps_profile_interval': 0, 18:07:26 INFO - 'suite': 'dromaeojs', 18:07:26 INFO - 'system_bits': '32', 18:07:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:07:26 INFO - 'title': 'talos-linux64-ix-017', 18:07:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:07:26 INFO - 'use_talos_json': True, 18:07:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:07:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:07:26 INFO - 'work_dir': 'build'} 18:07:26 INFO - ##### 18:07:26 INFO - ##### Running clobber step. 18:07:26 INFO - ##### 18:07:26 INFO - Running pre-action listener: _resource_record_pre_action 18:07:26 INFO - Running main action method: clobber 18:07:26 INFO - rmtree: /builds/slave/test/build 18:07:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:07:26 INFO - Running post-action listener: _resource_record_post_action 18:07:26 INFO - ##### 18:07:26 INFO - ##### Finished clobber step (success) 18:07:26 INFO - ##### 18:07:26 INFO - ##### 18:07:26 INFO - ##### Running read-buildbot-config step. 18:07:26 INFO - ##### 18:07:26 INFO - Running pre-action listener: _resource_record_pre_action 18:07:26 INFO - Running main action method: read_buildbot_config 18:07:26 INFO - Using buildbot properties: 18:07:26 INFO - { 18:07:26 INFO - "project": "", 18:07:26 INFO - "product": "firefox", 18:07:26 INFO - "script_repo_revision": "production", 18:07:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:07:26 INFO - "repository": "", 18:07:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:07:26 INFO - "buildid": "20160616163325", 18:07:26 INFO - "pgo_build": "False", 18:07:26 INFO - "basedir": "/builds/slave/test", 18:07:26 INFO - "buildnumber": 119, 18:07:26 INFO - "slavename": "talos-linux64-ix-017", 18:07:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:07:26 INFO - "platform": "ubuntu64_hw", 18:07:26 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:07:26 INFO - "branch": "mozilla-central", 18:07:26 INFO - "repo_path": "mozilla-central", 18:07:26 INFO - "revision": "5f95858f8ddf21ea2271a12810332efd09eff138", 18:07:26 INFO - "stage_platform": "linux64", 18:07:26 INFO - "builduid": "cfb02e49f05a42fab87c3dc582329388", 18:07:26 INFO - "slavebuilddir": "test" 18:07:26 INFO - } 18:07:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:07:26 INFO - Running post-action listener: _resource_record_post_action 18:07:26 INFO - ##### 18:07:26 INFO - ##### Finished read-buildbot-config step (success) 18:07:26 INFO - ##### 18:07:26 INFO - ##### 18:07:26 INFO - ##### Running download-and-extract step. 18:07:26 INFO - ##### 18:07:26 INFO - Running pre-action listener: _resource_record_pre_action 18:07:26 INFO - Running main action method: download_and_extract 18:07:26 INFO - mkdir: /builds/slave/test/build/tests 18:07: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')]} 18:07:26 INFO - https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:07:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:07:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:07:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:27 INFO - Downloaded 1361 bytes. 18:07:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:07:27 INFO - Using the following test package requirements: 18:07:27 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:07:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:07:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:07:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'jsshell-linux-x86_64.zip'], 18:07:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:07:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:07:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:07:27 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:07:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:07:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:07:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:27 INFO - https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:07:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:07:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:07:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:29 INFO - Downloaded 22654556 bytes. 18:07: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'] 18:07: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 18:07:30 INFO - Return code: 0 18:07:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:07: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')]} 18:07:30 INFO - https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:07:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:07:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:07:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:33 INFO - Downloaded 22654556 bytes. 18:07:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:07:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:07:34 INFO - Return code: 0 18:07:34 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')]} 18:07:34 INFO - https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:07:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:07:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:07:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:36 INFO - Downloaded 14405384 bytes. 18:07:36 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'] 18:07:36 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 18:07:36 INFO - Return code: 0 18:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:36 INFO - https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:07:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:07:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:07:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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 18:07:39 INFO - Downloaded 55449480 bytes. 18:07:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:07:39 INFO - mkdir: /builds/slave/test/properties 18:07:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:07:39 INFO - Writing to file /builds/slave/test/properties/build_url 18:07:39 INFO - Contents: 18:07:39 INFO - build_url:https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:07:40 INFO - Running post-action listener: _resource_record_post_action 18:07:40 INFO - Running post-action listener: set_extra_try_arguments 18:07:40 INFO - ##### 18:07:40 INFO - ##### Finished download-and-extract step (success) 18:07:40 INFO - ##### 18:07:40 INFO - ##### 18:07:40 INFO - ##### Running populate-webroot step. 18:07:40 INFO - ##### 18:07:40 INFO - Running pre-action listener: _resource_record_pre_action 18:07:40 INFO - Running main action method: populate_webroot 18:07:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:07:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:07:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:07:40 INFO - '--tppagecycles', 18:07:40 INFO - '7'], 18:07:40 INFO - 'tests': ['tsvgm']}}, 18:07:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:07:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:07:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:07:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:07:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:07:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:07:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['damp', 'tps']}, 18:07:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'tests': ['damp', 'tps']}, 18:07:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['dromaeo_dom']}, 18:07:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:07:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['basic_compositor_video']}, 18:07:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:07:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['a11yr', 18:07:40 INFO - 'ts_paint', 18:07:40 INFO - 'tpaint', 18:07:40 INFO - 'sessionrestore', 18:07:40 INFO - 'sessionrestore_no_auto_restore', 18:07:40 INFO - 'tabpaint']}, 18:07:40 INFO - 'other-e10s': {'tests': ['a11yr', 18:07:40 INFO - 'ts_paint', 18:07:40 INFO - 'tpaint', 18:07:40 INFO - 'sessionrestore', 18:07:40 INFO - 'sessionrestore_no_auto_restore', 18:07:40 INFO - 'tabpaint']}, 18:07:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:07:40 INFO - 'ts_paint', 18:07:40 INFO - 'tpaint', 18:07:40 INFO - 'sessionrestore', 18:07:40 INFO - 'sessionrestore_no_auto_restore', 18:07:40 INFO - 'tabpaint']}, 18:07:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:07:40 INFO - 'ts_paint', 18:07:40 INFO - 'tpaint', 18:07:40 INFO - 'sessionrestore', 18:07:40 INFO - 'sessionrestore_no_auto_restore', 18:07:40 INFO - 'tabpaint']}, 18:07:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['a11yr', 18:07:40 INFO - 'ts_paint', 18:07:40 INFO - 'tpaint', 18:07:40 INFO - 'sessionrestore', 18:07:40 INFO - 'sessionrestore_no_auto_restore', 18:07:40 INFO - 'tabpaint']}, 18:07:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['a11yr', 18:07:40 INFO - 'ts_paint', 18:07:40 INFO - 'tpaint', 18:07:40 INFO - 'sessionrestore', 18:07:40 INFO - 'sessionrestore_no_auto_restore', 18:07:40 INFO - 'tabpaint']}, 18:07:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['tsvgx', 18:07:40 INFO - 'tsvgr_opacity', 18:07:40 INFO - 'tart', 18:07:40 INFO - 'tscrollx', 18:07:40 INFO - 'cart']}, 18:07:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:07:40 INFO - 'tsvgr_opacity', 18:07:40 INFO - 'tart', 18:07:40 INFO - 'tscrollx', 18:07:40 INFO - 'cart']}, 18:07:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'talos_options': ['--disable-e10s'], 18:07:40 INFO - 'tests': ['tp5o']}, 18:07:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'tests': ['tp5o']}, 18:07:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'talos_options': ['--disable-e10s', 18:07:40 INFO - '--xperf_path', 18:07:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:07:40 INFO - 'tests': ['tp5n']}, 18:07:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:07:40 INFO - 'talos_options': ['--xperf_path', 18:07:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:07:40 INFO - 'tests': ['tp5n']}}, 18:07:40 INFO - 'talos.zip': {'path': '', 18:07:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:07:40 INFO - Running post-action listener: _resource_record_post_action 18:07:40 INFO - ##### 18:07:40 INFO - ##### Finished populate-webroot step (success) 18:07:40 INFO - ##### 18:07:40 INFO - ##### 18:07:40 INFO - ##### Running create-virtualenv step. 18:07:40 INFO - ##### 18:07:40 INFO - Running pre-action listener: _resource_record_pre_action 18:07:40 INFO - Running main action method: create_virtualenv 18:07:40 INFO - Creating virtualenv /builds/slave/test/build/venv 18:07:40 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 18:07:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:07:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:07:40 INFO - Using real prefix '/usr' 18:07:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:07:40 INFO - Installing distribute.............................................................................................................................................................................................done. 18:07:42 INFO - Installing pip.................done. 18:07:42 INFO - Return code: 0 18:07:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:07:42 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')]} 18:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:42 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')]} 18:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:42 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 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:42 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 18:07:42 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 18:07:42 INFO - Using env: {'DISPLAY': ':0', 18:07:42 INFO - 'HOME': '/home/cltbld', 18:07:42 INFO - 'LANG': 'en_US.UTF-8', 18:07:42 INFO - 'LANGUAGE': 'en_US:en', 18:07:42 INFO - 'LOGNAME': 'cltbld', 18:07:42 INFO - 'MAIL': '/var/mail/cltbld', 18:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:42 INFO - 'MOZ_NO_REMOTE': '1', 18:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:42 INFO - 'NO_EM_RESTART': '1', 18:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:42 INFO - 'PWD': '/builds/slave/test', 18:07:42 INFO - 'SHELL': '/bin/bash', 18:07:42 INFO - 'SHLVL': '1', 18:07:42 INFO - 'TERM': 'linux', 18:07:42 INFO - 'TMOUT': '86400', 18:07:42 INFO - 'USER': 'cltbld', 18:07:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:42 INFO - '_': '/tools/buildbot/bin/python'} 18:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:07:43 INFO - Cleaning up... 18:07:43 INFO - Return code: 0 18:07:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:07:43 INFO - Using env: {'DISPLAY': ':0', 18:07:43 INFO - 'HOME': '/home/cltbld', 18:07:43 INFO - 'LANG': 'en_US.UTF-8', 18:07:43 INFO - 'LANGUAGE': 'en_US:en', 18:07:43 INFO - 'LOGNAME': 'cltbld', 18:07:43 INFO - 'MAIL': '/var/mail/cltbld', 18:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:43 INFO - 'MOZ_NO_REMOTE': '1', 18:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:43 INFO - 'NO_EM_RESTART': '1', 18:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:43 INFO - 'PWD': '/builds/slave/test', 18:07:43 INFO - 'SHELL': '/bin/bash', 18:07:43 INFO - 'SHLVL': '1', 18:07:43 INFO - 'TERM': 'linux', 18:07:43 INFO - 'TMOUT': '86400', 18:07:43 INFO - 'USER': 'cltbld', 18:07:43 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:43 INFO - '_': '/tools/buildbot/bin/python'} 18:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:43 INFO - Downloading/unpacking psutil>=3.1.1 18:07:43 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 18:07:43 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 18:07:43 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 18:07:43 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 18:07:43 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 18:07:43 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 18:07:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:07:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:45 INFO - Installing collected packages: psutil 18:07:45 INFO - Running setup.py install for psutil 18:07:45 INFO - building 'psutil._psutil_linux' extension 18:07:45 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 18:07:45 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 18:07:45 INFO - building 'psutil._psutil_posix' extension 18:07:45 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 18:07:45 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 18:07:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:46 INFO - Successfully installed psutil 18:07:46 INFO - Cleaning up... 18:07:46 INFO - Return code: 0 18:07:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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')]} 18:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:07:46 INFO - Using env: {'DISPLAY': ':0', 18:07:46 INFO - 'HOME': '/home/cltbld', 18:07:46 INFO - 'LANG': 'en_US.UTF-8', 18:07:46 INFO - 'LANGUAGE': 'en_US:en', 18:07:46 INFO - 'LOGNAME': 'cltbld', 18:07:46 INFO - 'MAIL': '/var/mail/cltbld', 18:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:46 INFO - 'MOZ_NO_REMOTE': '1', 18:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:46 INFO - 'NO_EM_RESTART': '1', 18:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:46 INFO - 'PWD': '/builds/slave/test', 18:07:46 INFO - 'SHELL': '/bin/bash', 18:07:46 INFO - 'SHLVL': '1', 18:07:46 INFO - 'TERM': 'linux', 18:07:46 INFO - 'TMOUT': '86400', 18:07:46 INFO - 'USER': 'cltbld', 18:07:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:46 INFO - '_': '/tools/buildbot/bin/python'} 18:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:07:48 INFO - Installing collected packages: mozsystemmonitor 18:07:48 INFO - Running setup.py install for mozsystemmonitor 18:07:48 INFO - Successfully installed mozsystemmonitor 18:07:48 INFO - Cleaning up... 18:07:48 INFO - Return code: 0 18:07:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:07:48 INFO - Using env: {'DISPLAY': ':0', 18:07:48 INFO - 'HOME': '/home/cltbld', 18:07:48 INFO - 'LANG': 'en_US.UTF-8', 18:07:48 INFO - 'LANGUAGE': 'en_US:en', 18:07:48 INFO - 'LOGNAME': 'cltbld', 18:07:48 INFO - 'MAIL': '/var/mail/cltbld', 18:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:48 INFO - 'MOZ_NO_REMOTE': '1', 18:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:48 INFO - 'NO_EM_RESTART': '1', 18:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:48 INFO - 'PWD': '/builds/slave/test', 18:07:48 INFO - 'SHELL': '/bin/bash', 18:07:48 INFO - 'SHLVL': '1', 18:07:48 INFO - 'TERM': 'linux', 18:07:48 INFO - 'TMOUT': '86400', 18:07:48 INFO - 'USER': 'cltbld', 18:07:48 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:48 INFO - '_': '/tools/buildbot/bin/python'} 18:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:07:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:07:50 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 18:07:50 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 18:07:50 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 18:07:50 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 18:07:50 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 18:07:50 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 18:07:50 INFO - Downloading functools32-3.2.3-2.tar.gz 18:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:07:50 INFO - warning: no files found matching '*.txt' 18:07:50 INFO - no previously-included directories found matching 'build' 18:07:50 INFO - no previously-included directories found matching 'dist' 18:07:50 INFO - no previously-included directories found matching '.git*' 18:07:50 INFO - Installing collected packages: jsonschema, functools32 18:07:50 INFO - Running setup.py install for functools32 18:07:50 INFO - warning: no files found matching '*.txt' 18:07:50 INFO - no previously-included directories found matching 'build' 18:07:50 INFO - no previously-included directories found matching 'dist' 18:07:50 INFO - no previously-included directories found matching '.git*' 18:07:50 INFO - Successfully installed jsonschema functools32 18:07:50 INFO - Cleaning up... 18:07:51 INFO - Return code: 0 18:07:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:07:51 INFO - Using env: {'DISPLAY': ':0', 18:07:51 INFO - 'HOME': '/home/cltbld', 18:07:51 INFO - 'LANG': 'en_US.UTF-8', 18:07:51 INFO - 'LANGUAGE': 'en_US:en', 18:07:51 INFO - 'LOGNAME': 'cltbld', 18:07:51 INFO - 'MAIL': '/var/mail/cltbld', 18:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:51 INFO - 'MOZ_NO_REMOTE': '1', 18:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:51 INFO - 'NO_EM_RESTART': '1', 18:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:51 INFO - 'PWD': '/builds/slave/test', 18:07:51 INFO - 'SHELL': '/bin/bash', 18:07:51 INFO - 'SHLVL': '1', 18:07:51 INFO - 'TERM': 'linux', 18:07:51 INFO - 'TMOUT': '86400', 18:07:51 INFO - 'USER': 'cltbld', 18:07:51 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:51 INFO - '_': '/tools/buildbot/bin/python'} 18:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:51 INFO - Downloading/unpacking blobuploader==1.2.4 18:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:53 INFO - Downloading blobuploader-1.2.4.tar.gz 18:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:07:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:07:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:53 INFO - Downloading docopt-0.6.1.tar.gz 18:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:07:53 INFO - Installing collected packages: blobuploader, requests, docopt 18:07:53 INFO - Running setup.py install for blobuploader 18:07:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:07:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:07:53 INFO - Running setup.py install for requests 18:07:54 INFO - Running setup.py install for docopt 18:07:54 INFO - Successfully installed blobuploader requests docopt 18:07:54 INFO - Cleaning up... 18:07:54 INFO - Return code: 0 18:07:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:07:54 INFO - Using env: {'DISPLAY': ':0', 18:07:54 INFO - 'HOME': '/home/cltbld', 18:07:54 INFO - 'LANG': 'en_US.UTF-8', 18:07:54 INFO - 'LANGUAGE': 'en_US:en', 18:07:54 INFO - 'LOGNAME': 'cltbld', 18:07:54 INFO - 'MAIL': '/var/mail/cltbld', 18:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:54 INFO - 'MOZ_NO_REMOTE': '1', 18:07:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:54 INFO - 'NO_EM_RESTART': '1', 18:07:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:54 INFO - 'PWD': '/builds/slave/test', 18:07:54 INFO - 'SHELL': '/bin/bash', 18:07:54 INFO - 'SHLVL': '1', 18:07:54 INFO - 'TERM': 'linux', 18:07:54 INFO - 'TMOUT': '86400', 18:07:54 INFO - 'USER': 'cltbld', 18:07:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:54 INFO - '_': '/tools/buildbot/bin/python'} 18:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:54 INFO - Running setup.py (path:/tmp/pip-JFBK41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:54 INFO - Running setup.py (path:/tmp/pip-Ksp0XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:54 INFO - Running setup.py (path:/tmp/pip-tBwNXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:54 INFO - Running setup.py (path:/tmp/pip-1Uk3LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:55 INFO - Running setup.py (path:/tmp/pip-SaT2R6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:55 INFO - Running setup.py (path:/tmp/pip-2b2_2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:55 INFO - Running setup.py (path:/tmp/pip-heMibr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:55 INFO - Running setup.py (path:/tmp/pip-_94QXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:55 INFO - Running setup.py (path:/tmp/pip-pU0Xv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:55 INFO - Running setup.py (path:/tmp/pip-Ukfa7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:55 INFO - Running setup.py (path:/tmp/pip-hzQAnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:55 INFO - Running setup.py (path:/tmp/pip-eKXTU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:55 INFO - Running setup.py (path:/tmp/pip-ydodYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:55 INFO - Running setup.py (path:/tmp/pip-SztSgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:55 INFO - Running setup.py (path:/tmp/pip-0DytTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:55 INFO - Running setup.py (path:/tmp/pip-x9GluX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:55 INFO - Running setup.py (path:/tmp/pip-ae9KXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:07:55 INFO - Running setup.py install for manifestparser 18:07:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Running setup.py install for mozcrash 18:07:56 INFO - Running setup.py install for mozdebug 18:07:56 INFO - Running setup.py install for mozdevice 18:07:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Running setup.py install for mozfile 18:07:56 INFO - Running setup.py install for mozhttpd 18:07:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Running setup.py install for mozinfo 18:07:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Running setup.py install for mozInstall 18:07:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:07:56 INFO - Running setup.py install for mozleak 18:07:56 INFO - Running setup.py install for mozlog 18:07:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Running setup.py install for moznetwork 18:07:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Running setup.py install for mozprocess 18:07:57 INFO - Running setup.py install for mozprofile 18:07:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Running setup.py install for mozrunner 18:07:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Running setup.py install for mozscreenshot 18:07:57 INFO - Running setup.py install for moztest 18:07:57 INFO - Running setup.py install for mozversion 18:07:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:07:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:07:57 INFO - Cleaning up... 18:07:57 INFO - Return code: 0 18:07:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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')]} 18:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:57 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 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:07:57 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 18:07:57 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 18:07:57 INFO - Using env: {'DISPLAY': ':0', 18:07:57 INFO - 'HOME': '/home/cltbld', 18:07:57 INFO - 'LANG': 'en_US.UTF-8', 18:07:57 INFO - 'LANGUAGE': 'en_US:en', 18:07:57 INFO - 'LOGNAME': 'cltbld', 18:07:57 INFO - 'MAIL': '/var/mail/cltbld', 18:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:57 INFO - 'MOZ_NO_REMOTE': '1', 18:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:57 INFO - 'NO_EM_RESTART': '1', 18:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:57 INFO - 'PWD': '/builds/slave/test', 18:07:57 INFO - 'SHELL': '/bin/bash', 18:07:57 INFO - 'SHLVL': '1', 18:07:57 INFO - 'TERM': 'linux', 18:07:57 INFO - 'TMOUT': '86400', 18:07:57 INFO - 'USER': 'cltbld', 18:07:57 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:57 INFO - '_': '/tools/buildbot/bin/python'} 18:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:58 INFO - Running setup.py (path:/tmp/pip-XvHRtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:58 INFO - Running setup.py (path:/tmp/pip-WwA3OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:58 INFO - Running setup.py (path:/tmp/pip-4EQItV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:58 INFO - Running setup.py (path:/tmp/pip-0wmz51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:58 INFO - Running setup.py (path:/tmp/pip-8HoMaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:58 INFO - Running setup.py (path:/tmp/pip-iqVIFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:58 INFO - Running setup.py (path:/tmp/pip-KQAVga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:58 INFO - Running setup.py (path:/tmp/pip-Pet4Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:58 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)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:58 INFO - Running setup.py (path:/tmp/pip-YmbCw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:58 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)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:58 INFO - Running setup.py (path:/tmp/pip-KkXOtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:58 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)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:58 INFO - Running setup.py (path:/tmp/pip-8zKfTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:58 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)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:58 INFO - Running setup.py (path:/tmp/pip-3XSgPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:58 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)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:58 INFO - Running setup.py (path:/tmp/pip-nCZRtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:58 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)) 18:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:58 INFO - Running setup.py (path:/tmp/pip-Uw7C2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:59 INFO - Running setup.py (path:/tmp/pip-McBkBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:59 INFO - Running setup.py (path:/tmp/pip-E1dpUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:59 INFO - Running setup.py (path:/tmp/pip-HQPFMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:07:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:08:01 INFO - Downloading blessings-1.6.tar.gz 18:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:08:01 INFO - Installing collected packages: blessings 18:08:01 INFO - Running setup.py install for blessings 18:08:01 INFO - Successfully installed blessings 18:08:01 INFO - Cleaning up... 18:08:01 INFO - Return code: 0 18:08:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:01 INFO - Reading from file tmpfile_stdout 18:08:01 INFO - Current package versions: 18:08:01 INFO - argparse == 1.2.1 18:08:01 INFO - blessings == 1.6 18:08:01 INFO - blobuploader == 1.2.4 18:08:01 INFO - docopt == 0.6.1 18:08:01 INFO - functools32 == 3.2.3-2 18:08:01 INFO - jsonschema == 2.5.1 18:08:01 INFO - manifestparser == 1.1 18:08:01 INFO - mozInstall == 1.12 18:08:01 INFO - mozcrash == 0.17 18:08:01 INFO - mozdebug == 0.1 18:08:01 INFO - mozdevice == 0.48 18:08:01 INFO - mozfile == 1.2 18:08:01 INFO - mozhttpd == 0.7 18:08:01 INFO - mozinfo == 0.9 18:08:01 INFO - mozleak == 0.1 18:08:01 INFO - mozlog == 3.2 18:08:01 INFO - moznetwork == 0.27 18:08:01 INFO - mozprocess == 0.22 18:08:01 INFO - mozprofile == 0.28 18:08:01 INFO - mozrunner == 6.11 18:08:01 INFO - mozscreenshot == 0.1 18:08:01 INFO - mozsystemmonitor == 0.3 18:08:01 INFO - moztest == 0.7 18:08:01 INFO - mozversion == 1.4 18:08:01 INFO - psutil == 3.1.1 18:08:01 INFO - requests == 1.2.3 18:08:01 INFO - wsgiref == 0.1.2 18:08:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:01 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 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:08:01 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 18:08:01 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 18:08:01 INFO - Using env: {'DISPLAY': ':0', 18:08:01 INFO - 'HOME': '/home/cltbld', 18:08:01 INFO - 'LANG': 'en_US.UTF-8', 18:08:01 INFO - 'LANGUAGE': 'en_US:en', 18:08:01 INFO - 'LOGNAME': 'cltbld', 18:08:01 INFO - 'MAIL': '/var/mail/cltbld', 18:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:01 INFO - 'MOZ_NO_REMOTE': '1', 18:08:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:01 INFO - 'NO_EM_RESTART': '1', 18:08:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:01 INFO - 'PWD': '/builds/slave/test', 18:08:01 INFO - 'SHELL': '/bin/bash', 18:08:01 INFO - 'SHLVL': '1', 18:08:01 INFO - 'TERM': 'linux', 18:08:01 INFO - 'TMOUT': '86400', 18:08:01 INFO - 'USER': 'cltbld', 18:08:01 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:01 INFO - '_': '/tools/buildbot/bin/python'} 18:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 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)) 18:08:01 INFO - Cleaning up... 18:08:01 INFO - Return code: 0 18:08:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:01 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 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:08:01 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 18:08:01 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 18:08:01 INFO - Using env: {'DISPLAY': ':0', 18:08:01 INFO - 'HOME': '/home/cltbld', 18:08:01 INFO - 'LANG': 'en_US.UTF-8', 18:08:01 INFO - 'LANGUAGE': 'en_US:en', 18:08:01 INFO - 'LOGNAME': 'cltbld', 18:08:01 INFO - 'MAIL': '/var/mail/cltbld', 18:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:01 INFO - 'MOZ_NO_REMOTE': '1', 18:08:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:01 INFO - 'NO_EM_RESTART': '1', 18:08:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:01 INFO - 'PWD': '/builds/slave/test', 18:08:01 INFO - 'SHELL': '/bin/bash', 18:08:01 INFO - 'SHLVL': '1', 18:08:01 INFO - 'TERM': 'linux', 18:08:01 INFO - 'TMOUT': '86400', 18:08:01 INFO - 'USER': 'cltbld', 18:08:01 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:01 INFO - '_': '/tools/buildbot/bin/python'} 18:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:08:02 INFO - Cleaning up... 18:08:02 INFO - Return code: 0 18:08:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2859307f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216c350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, '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': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', '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 18:08:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:08:02 INFO - Using env: {'DISPLAY': ':0', 18:08:02 INFO - 'HOME': '/home/cltbld', 18:08:02 INFO - 'LANG': 'en_US.UTF-8', 18:08:02 INFO - 'LANGUAGE': 'en_US:en', 18:08:02 INFO - 'LOGNAME': 'cltbld', 18:08:02 INFO - 'MAIL': '/var/mail/cltbld', 18:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:02 INFO - 'MOZ_NO_REMOTE': '1', 18:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:02 INFO - 'NO_EM_RESTART': '1', 18:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:02 INFO - 'PWD': '/builds/slave/test', 18:08:02 INFO - 'SHELL': '/bin/bash', 18:08:02 INFO - 'SHLVL': '1', 18:08:02 INFO - 'TERM': 'linux', 18:08:02 INFO - 'TMOUT': '86400', 18:08:02 INFO - 'USER': 'cltbld', 18:08:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:02 INFO - '_': '/tools/buildbot/bin/python'} 18:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:02 INFO - Downloading/unpacking flake8 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:08:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:08:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:08:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:08:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:08:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:08:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:08:04 INFO - no previously-included directories found matching 'docs/_build' 18:08:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:08:04 INFO - Running setup.py install for pep8 18:08:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:08:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:08:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:08:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:08:04 INFO - no previously-included directories found matching 'docs/_build' 18:08:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:08:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:08:05 INFO - Cleaning up... 18:08:05 INFO - Return code: 0 18:08:05 INFO - Running post-action listener: _resource_record_post_action 18:08:05 INFO - Running post-action listener: _start_resource_monitoring 18:08:05 INFO - Starting resource monitoring. 18:08:05 INFO - ##### 18:08:05 INFO - ##### Finished create-virtualenv step (success) 18:08:05 INFO - ##### 18:08:05 INFO - ##### 18:08:05 INFO - ##### Running install step. 18:08:05 INFO - ##### 18:08:05 INFO - Running pre-action listener: _resource_record_pre_action 18:08:05 INFO - Running main action method: install 18:08:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:05 INFO - Reading from file tmpfile_stdout 18:08:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:08:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:08:05 INFO - Reading from file tmpfile_stdout 18:08:05 INFO - Output received: 18:08:05 INFO - Usage: mozinstall [options] installer 18:08:05 INFO - Options: 18:08:05 INFO - -h, --help show this help message and exit 18:08:05 INFO - -d DEST, --destination=DEST 18:08:05 INFO - Directory to install application into. [default: 18:08:05 INFO - "/builds/slave/test"] 18:08:05 INFO - --app=APP Application being installed. [default: firefox] 18:08:05 INFO - mkdir: /builds/slave/test/build/application 18:08:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:08:21 INFO - Reading from file tmpfile_stdout 18:08:21 INFO - Output received: 18:08:21 INFO - /builds/slave/test/build/application/firefox/firefox 18:08:21 INFO - Running post-action listener: _resource_record_post_action 18:08:21 INFO - ##### 18:08:21 INFO - ##### Finished install step (success) 18:08:21 INFO - ##### 18:08:21 INFO - ##### 18:08:21 INFO - ##### Running run-tests step. 18:08:21 INFO - ##### 18:08:21 INFO - Running pre-action listener: _resource_record_pre_action 18:08:21 INFO - Running main action method: run_tests 18:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:08:21 INFO - Python 2.7.3 18:08:21 INFO - Return code: 0 18:08:21 INFO - grabbing minidump binary from tooltool 18:08:21 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')]} 18:08:21 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 0x21fb1c0>, '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 0x21fc0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21fc550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:08:21 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 18:08:21 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 18:08:21 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 18:08:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:08:21 INFO - Return code: 0 18:08:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:08:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:08:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:08:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:08:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:08:22 INFO - Return code: 0 18:08:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 18:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 18:08:22 INFO - Using env: {'DISPLAY': ':0', 18:08:22 INFO - 'HOME': '/home/cltbld', 18:08:22 INFO - 'LANG': 'en_US.UTF-8', 18:08:22 INFO - 'LANGUAGE': 'en_US:en', 18:08:22 INFO - 'LOGNAME': 'cltbld', 18:08:22 INFO - 'MAIL': '/var/mail/cltbld', 18:08:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:08:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:22 INFO - 'MOZ_NO_REMOTE': '1', 18:08:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:08:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:22 INFO - 'NO_EM_RESTART': '1', 18:08:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:22 INFO - 'PWD': '/builds/slave/test', 18:08:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:08:22 INFO - 'SHELL': '/bin/bash', 18:08:22 INFO - 'SHLVL': '1', 18:08:22 INFO - 'TERM': 'linux', 18:08:22 INFO - 'TMOUT': '86400', 18:08:22 INFO - 'USER': 'cltbld', 18:08:22 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457', 18:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:22 INFO - '_': '/tools/buildbot/bin/python'} 18:08:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 18:08:22 INFO - mozversion application_buildid: 20160616163325 18:08:22 INFO - mozversion application_changeset: 5f95858f8ddf21ea2271a12810332efd09eff138 18:08:22 INFO - mozversion application_display_name: Nightly 18:08:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:08:22 INFO - mozversion application_name: Firefox 18:08:22 INFO - mozversion application_remotingname: firefox 18:08:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:08:22 INFO - mozversion application_vendor: Mozilla 18:08:22 INFO - mozversion application_version: 50.0a1 18:08:22 INFO - mozversion platform_buildid: 20160616163325 18:08:22 INFO - mozversion platform_changeset: 5f95858f8ddf21ea2271a12810332efd09eff138 18:08:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:08:22 INFO - mozversion platform_version: 50.0a1 18:08:22 INFO - using testdate: 1466125702 18:08:22 INFO - actual date: 1466125702 18:08:22 INFO - starting webserver on 'localhost:60059' 18:08:22 INFO - SUITE-START | Running 2 tests 18:08:22 INFO - TEST-START | dromaeo_css 18:08:22 INFO - operating with platform_type : linux_ 18:08:22 INFO - Initialising browser for dromaeo_css test... 18:08:22 INFO - TEST-INFO | started process 9614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLJmY1G/profile http://localhost:60059/getInfo.html) 18:08:26 INFO - PROCESS | 9614 | __metrics Screen width/height:1600/1200 18:08:26 INFO - PROCESS | 9614 | colorDepth:24 18:08:26 INFO - PROCESS | 9614 | Browser inner width/height: 1024/697 18:08:26 INFO - PROCESS | 9614 | __metrics 18:08:27 INFO - PROCESS | 9614 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:08:28 INFO - PROCESS | 9614 | console.error: PushService: 18:08:28 INFO - PROCESS | 9614 | stateChangeProcessEnqueue: Error transitioning state 18:08:28 INFO - PROCESS | 9614 | UnknownError 18:08:29 INFO - TEST-INFO | 9614: exit 0 18:08:29 INFO - Browser initialized. 18:08:29 INFO - Running cycle 1/1 for dromaeo_css test... 18:08:29 INFO - TEST-INFO | started process 9697 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLJmY1G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:08:41 INFO - PROCESS | 9697 | RSS: Main: 175439872 18:08:41 INFO - PROCESS | 9697 | 18:09:32 INFO - PROCESS | 9697 | 1466125772527 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] 18:11:45 INFO - PROCESS | 9697 | Cycle 1(1): loaded http://localhost:60059/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60059/tests/dromaeo/cssquery-ext.html) 18:11:46 INFO - PROCESS | 9697 | RSS: Main: 267939840 18:11:46 INFO - PROCESS | 9697 | 18:14:48 INFO - PROCESS | 9697 | Cycle 1(1): loaded http://localhost:60059/tests/dromaeo/cssquery-ext.html (next: http://localhost:60059/tests/dromaeo/cssquery-jquery.html) 18:14:49 INFO - PROCESS | 9697 | RSS: Main: 263081984 18:14:49 INFO - PROCESS | 9697 | 18:17:51 INFO - PROCESS | 9697 | Cycle 1(1): loaded http://localhost:60059/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60059/tests/dromaeo/cssquery-mootools.html) 18:17:52 INFO - PROCESS | 9697 | RSS: Main: 256847872 18:17:52 INFO - PROCESS | 9697 | 18:20:54 INFO - PROCESS | 9697 | Cycle 1(1): loaded http://localhost:60059/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60059/tests/dromaeo/cssquery-prototype.html) 18:20:54 INFO - PROCESS | 9697 | RSS: Main: 262238208 18:20:54 INFO - PROCESS | 9697 | 18:23:57 INFO - PROCESS | 9697 | Cycle 1(1): loaded http://localhost:60059/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60059/tests/dromaeo/cssquery-yui.html) 18:23:58 INFO - PROCESS | 9697 | RSS: Main: 229474304 18:23:58 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | Cycle 1(1): loaded http://localhost:60059/tests/dromaeo/cssquery-yui.html (next: http://localhost:60059/tests/dromaeo/cssquery-dojo.html) 18:27:01 INFO - PROCESS | 9697 | RSS: Main: 293343232 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | __start_tp_report 18:27:01 INFO - PROCESS | 9697 | _x_x_mozilla_page_load 18:27:01 INFO - PROCESS | 9697 | _x_x_mozilla_page_load_details 18:27:01 INFO - PROCESS | 9697 | |i|pagename|runs| 18:27:01 INFO - PROCESS | 9697 | |0;dojo.html;2621.3053540811684;2645.166772466672;2671.292107591488;2675.4916565852473;2681.06162843005;4587.243104887698;4679.368285281486;4709.835155769549;4756.07256584966;4810.374651295331;4672.579467847893;4704.294355846625;4712.03403302323;4716.787744551503;4788.712677239359;4273.5512771158965;4337.826486940518;4380.912381752379;4438.334249862514;4472.396226509412;4387.890302742441;4440.356148358486;4447.0661161156195;4457.754823484707;4461.330800379953;4257.084726783725;4288.721233119837;4291.463567054118;4319.611234988866;4353.847615333448;4349.456317960255;4352.804123814436;4364.585364390409;4369;4433.756143412111;4387.561243875619;4390.670699697535;4428.202923473774;4433.490148632916;4438.689291749579;4694.24892017279;4723.7638118093955;4747.382840230783;4801.231802911518;4819.44576373718;5074.949250507521;5120.667156634807;5167.09575824233;5172.741362931876;5175.974120129413;5131.769070391804;5168.638195326329;5169.017886601534;5178.430372659004;5188.429272779991;4687.226607609706;4704.529547045268;4739.691920025256;4846.176150054499;4847.175980083323;5183.2743415921805;5239.1093514102695;5254.816081437131;5260.658057226268;5262.1317482615705;4992.975035124874;4998.625103117281;5045.621578381562;5054.873628159252;5056.57019153376;4984.37695288089;5052.292679024939;5086.415062267886;5094.312267843794;5100.744962751847;5038.84883453497;5058.317127187783;5059.87350316245;5077.035363280965;5128.538431541179;4971.975140124348;5006.549410553068;5010.423801262828;5011.473795251519;5014;4171.165766846643;4196.055887425305;4199.91600167995;4249.128928569636;4249.936250956238;3748.381517049711;3769.3026790043928;3772.434134879735;3786.110264087937;3787.715921305913;4348.065165989265;4383.189110014658;4385.802638881258;4396.538363471789;4399.1641588098155;5101.2603172539775;5101.8724531886255;5107.61692873036;5111.233315002705;5162.380514338303;4398.758068306274;4406.228909940747;4407.449068866392;4412.161689279027;4457.242268814244;4439.134368798053;4507.2788353863225;4510.323451482238;4546.522615125365;4562.634989200856;4794.7362895040405;4803.087413391444;4810.302506136661;4810.518948105231;4850.442199147144;4954.752262386938;5049.671771334852;5063.1898896176435;5090.2364645303505;5162.6386152968935;4740.1230772307235;4798.928016079832;4812.639052071178;4823.204171311694;4831.9275210871165;5027.371578552681;5042.050123152204;5048.899022019613;5050.3434553507805;5097.515736005073;4919.704817710805;4998.2002879539095;5099.643024988215;5115.18157094863;5163.690178589297;4902.828401005948;4933.753312334441;4952.801887924443;4962.131626965339;5009.323741295024;4936.481669424658;4947.1342515059005;4948.950510494849;4949.628777841748;4994.750262486788;4722.5513576210205;4865.56209941107;4867.29424233477;4870.610351171968;4923.852284431473;5014.197728363444;5047.192449208109;5047.49525047507;5081.441041485507;5093.18509038552;4937.802487900443;4946.802127914987;4953.876153096201;4954;5005.849824505271 18:27:01 INFO - PROCESS | 9697 | |1;ext.html;549.1076999875203;584.3980699879125;589.2251689028926;606.4384210657555;608.0787606775734;29128.854355728217;31465.528017079763;31537.369252614935;31815.204619884487;38821.80589097058;38338.61661383385;42033.579664203346;42143;42151;42217.15565688685;7850.528968261909;7964.681412743512;7986.2013798620255;8015.799605009863;8018.719344822933;7850.960745196266;7953.045634523836;7953.363730901514;7958.840823183533;7960.761177164694;7963.084245311777;7986.520808751494;8001.7199398021085;8002.599870006506;8003.119656837744;7920;7963.124056353797;7964.442489025771;7968.920310796876;7969.362451003905;7906.960465197695;7994.120646728855;7998;8011.158828323;8015.198480151997;38801.80599097015;39109.21781564381;39158.21683566313;39168.21663566741;39172;3080.9383812323626;3172.74618030557;3195.6964088411564;3476.1830969722096;3813.4089216171537;29531.55702664462;29766.70233297661;29817.701822981926;30100.54849177242;30788.384232315228;3394.998475449748;3397.11674964511;3412.6246112927556;3452.085197422685;3462.5844898612077;258281;259040.9999999981;259267;260514;261482;45215.77392113018;50521.242181367306;53150.734246328524;53661.1950820738;53718.46281537135;55430.445695543334;56023.719881400335;56076.15885761717;56326.718366407906;56396.43603563912;13739.862601373858;14096.85903140976;14104.506342277971;14125.43498260058;14129.85870141306;10471;10571.04860562553;10573.154147668321;10574.52414641343;10575.524101415454;16633.584160396087;16707.41524046677;16712.41506547265;16729.330826767036;16799.076050817323;6226.439620434183;6371.553991220663;6377.17096777416;6482.351764823574;6490.902636460457;795.6181033104027;848.1137211613878;863.838564576662;901.7294811556507;975.8487434447673;27738.02916897899;27931.530955047096;28101.156965291073;28450;28626.713732862405;932.3566738950238;936.8266870628955;951.5622813505864;966.7728083900278;998.5257002923553;14681;15035;15047.699046019237;15285.847141528442;15296;371532;371964;376649;378714;379288;49723.75138124359;49886.50113498818;49906.251406228934;50164;50187.49812501828;1050.4747626186906;1079.1259080145107;1137.4824454873178;1171.5255321594768;1212.775636507249;28660;29303.413931721672;29414.852925735235;29740.85129574338;30071.398572028;42568.57431425646;42955;43033.7848310769;43067.13865722605;43151.35272970845;308808;311703;312931;312968;313013;36477.63522364743;36949.261014780095;37832.05419864525;38068.23863522659;38212;42220.36669449899;42807.35788963106;42971.78514107409;43335.56664433315;43747.56252437562;36238.4564231542;36568.26863462769;36771.26457470889;37460.250794984495;37530.62469375271;35990.100247494025;36294.8185259072;36612.633873660925;37058;37221.44167837431;35995.1001224961;36690.44964325591;36849;37187.44218836666;37651;32101.357972839945;33444.83277583597;33602.32795344129;33690.32619347648;33835;25149.622755659046;25491.617625735256;25546.361340966625;26112.34719132037;26173.083942061938 18:27:01 INFO - PROCESS | 9697 | |2;jquery.html;371.46694493401964;373.5909179448504;377.5563712637652;388.92610404023253;390.74601509019135;16136.19319034048;16612.335506579737;17310.740338894928;17318.740218896728;18069.81930180698;10630.681079567625;11171.329720216792;11177.608783692573;11186.888131118687;11218;4540.9091818163615;4583.702059366139;4770.329467698611;5262.947370526284;5273.1035723927025;5144.228365745131;5201.193814958688;5227.137522308814;5230.92153617696;5252.737363131847;4716.646251531131;4784.162771514971;4794.904101917959;5007.474215207405;5201.869953251161;4754.429468463789;4763.61891048717;4773.212419950722;4774.259989701602;4787.449443314029;4605.424321959755;4616.330632058332;4639.443266808004;4672.065586882637;4673.742944138071;12244.632661020094;12277.140600157993;12483.50065997359;12497.312647804367;12611.432485538173;1812.8187181281767;1923.5095050762106;1948.6005368899341;1963.7447131872907;2014.305064752658;9432.19826314764;9437.811243775086;9717.951410242975;9748.512574371252;9839.114479696862;1708.863290936722;1762.0749555737414;1795.6678014567347;1880.0505744599027;1896.2415033986379;47784.52215477835;61382.69308653473;69539.30460695429;70261.99999999949;72837.99999999948;15580.454684086002;15802.288896999662;15891.443799466962;15970.920145399432;16681.666366672598;23244.76755232426;24188.637170442456;28876.566851496824;29329.853350733538;29481.852590737337;8055.436119471698;8222.958885205655;8458.619362128718;8499.83000340002;8551.786205344915;7119.394851437686;7195.92804071963;7204.3155900189395;7213.422926165894;7410.147832999274;8418.368622353348;8468.745937621881;8698.304135669257;8745.737627871174;8854.468731876108;3135.7961732487315;3280.343931213719;3298.1846504103796;3394;3400.412564744219;766.8619648463218;815.384384789492;822.1655020154548;838.1325328285195;923.0354279777648;17252.65494690099;17332.30670773155;17648;17651.470455886345;17940.91029544844;831.5343407691712;863.4387648028832;874.956252187388;912.8630705394111;937.9765505862408;9550.140487356171;9953.402795832273;9978.650747223812;10054.296199266128;10091.596336146618;403453;406570;407449;420992;423024;76832;80758.19241807506;81436.1138331702;81908.59045704853;81994.18005819985;901.5582364641278;906.1210625693067;925.9907400926039;929.4469790474657;932.5057719408725;15523.22383880824;15824.129672868103;15858.841411585736;15958;16248;36053.81973090223;36404.453933190496;37497.25005499929;38249.23501529898;38430.61569384382;52188.478115218364;53146.4685353157;54404.45595543994;54432.45567544274;55497.72251138718;7764;7840.764777056697;7977.641006154853;8054.798630034296;8147.470414423044;11400.714982125513;12044.638660840188;12120.090993175723;12175.63473095773;12289.1397602167;10081.596736130472;10233.437160956219;10481.580736770444;10695.572177112828;10845.078168355778;9854.753631159278;10011.199104071802;10520.263581549218;10545.420001899662;10665.306755060896;9923.354981926152;10201.38791672502;10741;11067.335959842436;11331.830022549504;7593.202713715202;7745.573993430416;7866.842663146819;7978.202179781975;8102.189781021851;9322.300827437834;10299.279050466395;10317.535710892878;10434.37393756377;10465.529051192563 18:27:01 INFO - PROCESS | 9697 | |3;mootools.html;1210.7820592293394;1366.6993261482473;1422.9573112806615;1452.997431772077;1615.7657139714743;6609.272979972206;7542.358899493543;7822.843543129134;7856.646450909709;8011.399145064114;7666.463347565673;7893;7898.644560994755;7910.564918929456;7910.604469776517;2239.5744808486374;2277.9886100569556;2278.236790675126;2280.247518318951;2280.9201677941273;2173.587018466494;2226.3543572364074;2238.9776102238934;2241.686163937042;2242.439390152462;2211.7014203082617;2228.2089858100385;2236.4185311818883;2243.102758896438;2253.7746225377496;2144.270947877729;2185.5410363823617;2191.5069109450324;2192.725909261342;2195.484061245606;2174.206414658638;2180.498485348363;2181.8909054547366;2182.2798476502717;2184.104517147962;7875.054993400771;7878.763637090897;7974.282314591656;7986.440949133563;8130.30892374143;4719.174144524694;4853.466118726938;4854.029194161182;4858.319835223072;4859.465458799529;2848.473032488996;2963.5850980862697;3017.0948715385516;3020.501617233154;3023.9546406803915;920.093707697917;924.6809850601531;926.3422969691527;948.3788118782209;953.9618415263382;10742.624008159755;10885.401302928336;10891.67324980252;10908.018278354906;10951.99999999992;4058.837646494107;4131.586841315904;4163.10493243954;4198.916021679549;4225.133847561243;8622.439541429787;8670.95664521686;8692.348073894484;8695.217430431292;8699.826003479895;4594.402727645452;4640.79116439761;4645.326427668037;4702.3416721659;4707.670463067552;3665.9735274123286;3730.0488375464083;3940.6847452203756;4345.565443455629;4415.62467190286;3439.638837922032;3553.6091029986683;3554.253606742613;3589.335972845032;3603.4414665726854;1031.4533297352416;1049.2550289294534;1058.8782290036745;1061.9097376723066;1066.2056767708107;737.985240295191;808.2806302390877;817.4237162800212;824.3322908444179;844.3034496540379;5115.283860259567;5193.818216362485;5220.269162317279;5260.894782104337;5276.947230527646;382.20501357177005;386.6095243803778;390.2195608782435;406.0944094668905;412.5750477008686;3501.852515835994;3569.5359603251413;3625;3666.0834791302173;3689.0777305673582;9539;9920.057594528509;9928.453935033498;10030.197584193247;10047.698569042941;9800.117989380848;9865;9866.161376283097;9876.753081173028;9897.75255618601;7414.851702965911;7436.367908727819;7496.062992125984;7530.698772049166;7533.585652789039;5186.58507319406;5208.4270730220405;5240.973795131;5304.946950530445;5330.573554115739;8600.526971016403;8951;8982.281417486454;8982.55087245622;9264.675736349196;8991.28069754431;9153.17621414076;9171.40385874916;9317.068293170629;9451.385659932082;6028.8794224116145;6720.227173875067;6960.825979350557;6977.825554361181;7002.474814388839;7665.923340766744;7718.228177182237;7735.9613201933635;7749.341305988829;7780.144184139627;6876.518643694893;7009.754658586925;7078.362947334765;7176.354128128493;7183.964080179566;6578.76974305897;6634.668266586555;6745.865082698417;6764.3912047913955;6895.310468953065;5939.91090133657;5997.940020599738;6035.124906888389;6077.4834139098675;6081.604695694766;4531.886702832324;4712.3873896394625;4747.786349614206;4765.5710986010035;4783.593394561502;4724.385829842098;4821.927671084866;4906.484819093944;4947.777350019186;4993.925091123563 18:27:01 INFO - PROCESS | 9697 | |4;prototype.html;296.749246886381;303.8587057018487;307.44199278309867;311.040440241854;313.0639388229195;4829.710217386959;4840.443349014864;4846;4854.417469903612;4863.343448634433;4764.142454358214;4766.189747742884;4797.592204662599;4803.663743537953;4817.181079216532;3561.572611286649;3691.427828686558;3702.981485092584;3791.184895247521;3804.90487737806;3742.3637981543066;3760.8119594020327;3784.148566572513;3788.2802267569214;3794.753341032824;3726.813659317037;3753.887383378503;3781.1681430085337;3792.905177370574;3801.771893686374;3566.251087271676;3630.709543236535;3781.8487260509546;3796.6393192646656;3809.447630093621;3300.719438847701;3347.782394144373;3360.3615313090436;3372.6290108088338;3381.2561236527927;4666.160091183586;4669.556392142741;4676.368690226844;4711.552402521789;4750.738709370983;4223.894402639959;4299.97850010748;4325.740455572675;4357.542458041891;4358.65130789536;5011.949880501184;5025.346704928373;5029.535527591491;5045.864680446907;5051.924221136685;3655.232401195752;3670.210904655512;3678.0988657779017;3689.9852540551296;3693.132113953219;5120.795168193268;5243.86890327741;5267.55225805807;5280.3663560372615;5285.709285989269;3741.401375779862;3746.6957820939924;3750.9062273442837;3757.3612485877466;3758.3047136279874;3792.58281589025;3795.35478968576;3800.201957588882;3819.3698039823676;3827.655511004023;4297.78511074445;4302.612764851178;4315.1585440838735;4332.26709316276;4335.241332766816;4886.218205087169;5002.249662550574;5028.497150285016;5052.7726252318735;5053.84838454847;1308.5093090091216;1308.967275818112;1311.2329287366806;1316.2365827819842;1317.7101037771674;852.2542775071812;859.7919922508885;873.9599876147364;880.3485420788699;888.8844450221429;4389.561043895585;4402.9559704402545;4436.667249956202;4438.400815889813;4445.310781902856;5089.491050894881;5104;5118.97440512795;5143.819966301162;5144.61415393847;4279.486461624562;4416.908164285013;4419.248727716232;4436.64506839452;4441.622462090759;2703.3106557827628;2717.1984264641583;2728.2906444324617;2751.834889906612;2752.944941101167;4800.975995120072;4810.6151507879285;4827.42070951481;4834.589059929875;4855.6601037927;5083.87290317745;5205.6616319939085;5218.217267409843;5241.030409374278;5241.14357675068;4835.226363781778;4846.019697885846;4846.927296090562;4847.612191024709;4859.538343857328;5074.746262686925;5096.362954630671;5116.974415128049;5135.281060651587;5148.29714399943;4793.544613261734;5020.594233614594;5021.698698078127;5037.319961805256;5123.359580052494;4918.803247870045;4924.975375123245;4934.950650493593;4937.136001199848;4939.802407903644;3674.577423596321;3691.3171063353366;3705.203381273039;3716.368217402994;3724.6647801696895;5103.234514822806;5168.819091331786;5290.153575427913;5291.391489978548;5301.3373328333955;5009.223570346602;5037.848864534069;5039.765257511786;5039.949600504095;5044.9243261350375;5042.823501177441;5043.193089105872;5049.671771334852;5053.393592768891;5056.797728090983;4687.9296810547185;4961.255811628284;4968.801247950041;4978.751062446936;4991.401031876198;4846.733429661262;4981.900361992813;5021.5229553192385;5058.645894787477;5104.846854594368;4956.77694503741;4969.862958927696;4980.925286120639;4985.825496107619;4994.450610432922 18:27:01 INFO - PROCESS | 9697 | |5;yui.html;671.8924972004477;675.4562577125412;698.2807708060698;701.9789406317813;754.4266357568248;13103.73792524149;13723.382447789849;13923.582292531215;13931.582052538415;14051.578452646412;13242.470301187965;13404.396802143903;13418.530351437701;13420.66448338792;13421.194728316283;619.055939691971;753.9057617797775;775.4068137874531;791.6160662078887;793.9523628582275;773.6789232468519;782.1982467970347;786.1313248860013;786.4376970466116;786.5280831501111;825.558326295432;829.6805729794042;830.0744669693308;830.3274347778319;832.2592892325835;639.9648019358933;787.8424315136996;814.2386868278179;815.7593509914531;816.7753867686374;610.0970563565947;610.6946526736632;610.7831719739473;610.8381278961078;611.1779656362186;13660.52188173419;13700.862991370059;13706.862931370659;13727.313634318245;13739.450421983109;12653.493860245579;13069.346532673424;13154.802677959839;13159.955201839579;13186.208827470382;11263.887361126366;11444.771104577945;11688.41557922109;11842.585509507213;12018.51925922962;4205.789710514493;4217.430646862665;4231.87304380869;4256.5105012923605;4272.636825869805;56584.43415565833;57024;57030.714846425915;57530;57544.71227643835;10163.390196588229;10199.082082612453;10228.386296822215;10399.94800025995;10456.790864182674;18916.86498810076;18972.52568685766;18994.430167095008;19076;19159.233630654897;6850.520463567602;6851.07510486078;6854.828629284308;6866.793996180123;7125.5368401053765;3683.749277989127;3710.424884142962;3741.7567858089137;3791.1659434924272;3808.2002779416634;2748.4365705030423;2839.645044369454;2845.601615773793;2851.81463204891;2852.3867368515453;471.6627611257967;483.77704448061104;486.26573873451343;488.7043338779987;490.619769678502;408.7772164170534;458.8607594936722;473.21682615271783;479.7721082485861;515.5334422347706;6986.126734158231;7391.8152046199275;7440.14438339587;7494.250574942462;7978.880316795253;409.09793904440636;409.5679058593153;420.4478813601471;426.1264407963662;437.01889258613977;3543.6102028776813;3558.7508874379064;3568.803715795656;3642.7450078494253;3748.606396328401;88423;88849;89163;91808;93961;80279;80903;81549;82455.17544824474;82706;43032.56967430285;60743;62617;63200.09998750156;74526.99999999891;8281.503109813431;8518.23335899772;8636.611352489028;8700.651973920973;8806.295496360148;41764.791176043924;43454.56545434505;43749.78125109354;44314.335284970104;44451;37860.81069594635;49572.50427495823;59159;61898.38101618926;62453;10746.355218686904;11205.239695083059;11361.943190283995;11432.542698291974;11528.481218345363;12796.296203708886;13090.80363794563;13151.868481315065;13486.12340197884;13985.300734963415;12302.759260821971;12470.625881223577;12657.683557911127;12683.682907927376;13264.53574124901;11828.408579570814;11854.229475084092;12207.816882746944;12874.227546347249;12886.42011109521;12126.818097728717;12206.32865192423;12284.0786940978;12806.807897881725;12967.092303538662;8929.374943753875;9046.140616641409;9113.179813816789;9255.120763527455;9283.303752218475;9005.324600655109;9012.864807027769;9034.322425817958;9345;9391.20174785151 18:27:01 INFO - PROCESS | 9697 | __end_tp_report 18:27:01 INFO - PROCESS | 9697 | __start_cc_report 18:27:01 INFO - PROCESS | 9697 | _x_x_mozilla_cycle_collect,910 18:27:01 INFO - PROCESS | 9697 | __end_cc_report 18:27:01 INFO - PROCESS | 9697 | __startTimestamp1466126821713__endTimestamp 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | ------- Summary: start ------- 18:27:01 INFO - PROCESS | 9697 | Number of tests: 6 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | [#0] dojo.html Cycles:165 Average:4703.60 Median:4831.93 stddev:498.90 (10.3%) stddev-sans-first:472.93 18:27:01 INFO - PROCESS | 9697 | Values: 2621.3 2645.2 2671.3 2675.5 2681.1 4587.2 4679.4 4709.8 4756.1 4810.4 4672.6 4704.3 4712.0 4716.8 4788.7 4273.6 4337.8 4380.9 4438.3 4472.4 4387.9 4440.4 4447.1 4457.8 4461.3 4257.1 4288.7 4291.5 4319.6 4353.8 4349.5 4352.8 4364.6 4369.0 4433.8 4387.6 4390.7 4428.2 4433.5 4438.7 4694.2 4723.8 4747.4 4801.2 4819.4 5074.9 5120.7 5167.1 5172.7 5176.0 5131.8 5168.6 5169.0 5178.4 5188.4 4687.2 4704.5 4739.7 4846.2 4847.2 5183.3 5239.1 5254.8 5260.7 5262.1 4993.0 4998.6 5045.6 5054.9 5056.6 4984.4 5052.3 5086.4 5094.3 5100.7 5038.8 5058.3 5059.9 5077.0 5128.5 4972.0 5006.5 5010.4 5011.5 5014.0 4171.2 4196.1 4199.9 4249.1 4249.9 3748.4 3769.3 3772.4 3786.1 3787.7 4348.1 4383.2 4385.8 4396.5 4399.2 5101.3 5101.9 5107.6 5111.2 5162.4 4398.8 4406.2 4407.4 4412.2 4457.2 4439.1 4507.3 4510.3 4546.5 4562.6 4794.7 4803.1 4810.3 4810.5 4850.4 4954.8 5049.7 5063.2 5090.2 5162.6 4740.1 4798.9 4812.6 4823.2 4831.9 5027.4 5042.1 5048.9 5050.3 5097.5 4919.7 4998.2 5099.6 5115.2 5163.7 4902.8 4933.8 4952.8 4962.1 5009.3 4936.5 4947.1 4949.0 4949.6 4994.8 4722.6 4865.6 4867.3 4870.6 4923.9 5014.2 5047.2 5047.5 5081.4 5093.2 4937.8 4946.8 4953.9 4954.0 5005.8 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | [#1] ext.html Cycles:180 Average:47527.49 Median:37590.81 stddev:83867.35 (223.1%) stddev-sans-first:84028.45 18:27:01 INFO - PROCESS | 9697 | Values: 549.1 584.4 589.2 606.4 608.1 29128.9 31465.5 31537.4 31815.2 38821.8 38338.6 42033.6 42143.0 42151.0 42217.2 7850.5 7964.7 7986.2 8015.8 8018.7 7851.0 7953.0 7953.4 7958.8 7960.8 7963.1 7986.5 8001.7 8002.6 8003.1 7920.0 7963.1 7964.4 7968.9 7969.4 7907.0 7994.1 7998.0 8011.2 8015.2 38801.8 39109.2 39158.2 39168.2 39172.0 3080.9 3172.7 3195.7 3476.2 3813.4 29531.6 29766.7 29817.7 30100.5 30788.4 3395.0 3397.1 3412.6 3452.1 3462.6 258281.0 259041.0 259267.0 260514.0 261482.0 45215.8 50521.2 53150.7 53661.2 53718.5 55430.4 56023.7 56076.2 56326.7 56396.4 13739.9 14096.9 14104.5 14125.4 14129.9 10471.0 10571.0 10573.2 10574.5 10575.5 16633.6 16707.4 16712.4 16729.3 16799.1 6226.4 6371.6 6377.2 6482.4 6490.9 795.6 848.1 863.8 901.7 975.8 27738.0 27931.5 28101.2 28450.0 28626.7 932.4 936.8 951.6 966.8 998.5 14681.0 15035.0 15047.7 15285.8 15296.0 371532.0 371964.0 376649.0 378714.0 379288.0 49723.8 49886.5 49906.3 50164.0 50187.5 1050.5 1079.1 1137.5 1171.5 1212.8 28660.0 29303.4 29414.9 29740.9 30071.4 42568.6 42955.0 43033.8 43067.1 43151.4 308808.0 311703.0 312931.0 312968.0 313013.0 36477.6 36949.3 37832.1 38068.2 38212.0 42220.4 42807.4 42971.8 43335.6 43747.6 36238.5 36568.3 36771.3 37460.3 37530.6 35990.1 36294.8 36612.6 37058.0 37221.4 35995.1 36690.4 36849.0 37187.4 37651.0 32101.4 33444.8 33602.3 33690.3 33835.0 25149.6 25491.6 25546.4 26112.3 26173.1 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | [#2] jquery.html Cycles:180 Average:25367.62 Median:4594.56 stddev:67944.82 (1478.8%) stddev-sans-first:68109.50 18:27:01 INFO - PROCESS | 9697 | Values: 371.5 373.6 377.6 388.9 390.7 16136.2 16612.3 17310.7 17318.7 18069.8 10630.7 11171.3 11177.6 11186.9 11218.0 4540.9 4583.7 4770.3 5262.9 5273.1 5144.2 5201.2 5227.1 5230.9 5252.7 4716.6 4784.2 4794.9 5007.5 5201.9 4754.4 4763.6 4773.2 4774.3 4787.4 4605.4 4616.3 4639.4 4672.1 4673.7 12244.6 12277.1 12483.5 12497.3 12611.4 1812.8 1923.5 1948.6 1963.7 2014.3 9432.2 9437.8 9718.0 9748.5 9839.1 1708.9 1762.1 1795.7 1880.1 1896.2 47784.5 61382.7 69539.3 70262.0 72838.0 15580.5 15802.3 15891.4 15970.9 16681.7 23244.8 24188.6 28876.6 29329.9 29481.9 8055.4 8223.0 8458.6 8499.8 8551.8 7119.4 7195.9 7204.3 7213.4 7410.1 8418.4 8468.7 8698.3 8745.7 8854.5 3135.8 3280.3 3298.2 3394.0 3400.4 766.9 815.4 822.2 838.1 923.0 17252.7 17332.3 17648.0 17651.5 17940.9 831.5 863.4 875.0 912.9 938.0 9550.1 9953.4 9978.7 10054.3 10091.6 403453.0 406570.0 407449.0 420992.0 423024.0 76832.0 80758.2 81436.1 81908.6 81994.2 901.6 906.1 926.0 929.4 932.5 15523.2 15824.1 15858.8 15958.0 16248.0 36053.8 36404.5 37497.3 38249.2 38430.6 52188.5 53146.5 54404.5 54432.5 55497.7 7764.0 7840.8 7977.6 8054.8 8147.5 11400.7 12044.6 12120.1 12175.6 12289.1 10081.6 10233.4 10481.6 10695.6 10845.1 9854.8 10011.2 10520.3 10545.4 10665.3 9923.4 10201.4 10741.0 11067.3 11331.8 7593.2 7745.6 7866.8 7978.2 8102.2 9322.3 10299.3 10317.5 10434.4 10465.5 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | [#3] mootools.html Cycles:180 Average:5171.82 Median:4970.85 stddev:2941.67 (59.2%) stddev-sans-first:2934.86 18:27:01 INFO - PROCESS | 9697 | Values: 1210.8 1366.7 1423.0 1453.0 1615.8 6609.3 7542.4 7822.8 7856.6 8011.4 7666.5 7893.0 7898.6 7910.6 7910.6 2239.6 2278.0 2278.2 2280.2 2280.9 2173.6 2226.4 2239.0 2241.7 2242.4 2211.7 2228.2 2236.4 2243.1 2253.8 2144.3 2185.5 2191.5 2192.7 2195.5 2174.2 2180.5 2181.9 2182.3 2184.1 7875.1 7878.8 7974.3 7986.4 8130.3 4719.2 4853.5 4854.0 4858.3 4859.5 2848.5 2963.6 3017.1 3020.5 3024.0 920.1 924.7 926.3 948.4 954.0 10742.6 10885.4 10891.7 10908.0 10952.0 4058.8 4131.6 4163.1 4198.9 4225.1 8622.4 8671.0 8692.3 8695.2 8699.8 4594.4 4640.8 4645.3 4702.3 4707.7 3666.0 3730.0 3940.7 4345.6 4415.6 3439.6 3553.6 3554.3 3589.3 3603.4 1031.5 1049.3 1058.9 1061.9 1066.2 738.0 808.3 817.4 824.3 844.3 5115.3 5193.8 5220.3 5260.9 5276.9 382.2 386.6 390.2 406.1 412.6 3501.9 3569.5 3625.0 3666.1 3689.1 9539.0 9920.1 9928.5 10030.2 10047.7 9800.1 9865.0 9866.2 9876.8 9897.8 7414.9 7436.4 7496.1 7530.7 7533.6 5186.6 5208.4 5241.0 5304.9 5330.6 8600.5 8951.0 8982.3 8982.6 9264.7 8991.3 9153.2 9171.4 9317.1 9451.4 6028.9 6720.2 6960.8 6977.8 7002.5 7665.9 7718.2 7736.0 7749.3 7780.1 6876.5 7009.8 7078.4 7176.4 7184.0 6578.8 6634.7 6745.9 6764.4 6895.3 5939.9 5997.9 6035.1 6077.5 6081.6 4531.9 4712.4 4747.8 4765.6 4783.6 4724.4 4821.9 4906.5 4947.8 4993.9 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | [#4] prototype.html Cycles:180 Average:4188.66 Median:4802.32 stddev:1201.77 (25.0%) stddev-sans-first:1169.10 18:27:01 INFO - PROCESS | 9697 | Values: 296.7 303.9 307.4 311.0 313.1 4829.7 4840.4 4846.0 4854.4 4863.3 4764.1 4766.2 4797.6 4803.7 4817.2 3561.6 3691.4 3703.0 3791.2 3804.9 3742.4 3760.8 3784.1 3788.3 3794.8 3726.8 3753.9 3781.2 3792.9 3801.8 3566.3 3630.7 3781.8 3796.6 3809.4 3300.7 3347.8 3360.4 3372.6 3381.3 4666.2 4669.6 4676.4 4711.6 4750.7 4223.9 4300.0 4325.7 4357.5 4358.7 5011.9 5025.3 5029.5 5045.9 5051.9 3655.2 3670.2 3678.1 3690.0 3693.1 5120.8 5243.9 5267.6 5280.4 5285.7 3741.4 3746.7 3750.9 3757.4 3758.3 3792.6 3795.4 3800.2 3819.4 3827.7 4297.8 4302.6 4315.2 4332.3 4335.2 4886.2 5002.2 5028.5 5052.8 5053.8 1308.5 1309.0 1311.2 1316.2 1317.7 852.3 859.8 874.0 880.3 888.9 4389.6 4403.0 4436.7 4438.4 4445.3 5089.5 5104.0 5119.0 5143.8 5144.6 4279.5 4416.9 4419.2 4436.6 4441.6 2703.3 2717.2 2728.3 2751.8 2752.9 4801.0 4810.6 4827.4 4834.6 4855.7 5083.9 5205.7 5218.2 5241.0 5241.1 4835.2 4846.0 4846.9 4847.6 4859.5 5074.7 5096.4 5117.0 5135.3 5148.3 4793.5 5020.6 5021.7 5037.3 5123.4 4918.8 4925.0 4935.0 4937.1 4939.8 3674.6 3691.3 3705.2 3716.4 3724.7 5103.2 5168.8 5290.2 5291.4 5301.3 5009.2 5037.8 5039.8 5039.9 5044.9 5042.8 5043.2 5049.7 5053.4 5056.8 4687.9 4961.3 4968.8 4978.8 4991.4 4846.7 4981.9 5021.5 5058.6 5104.8 4956.8 4969.9 4980.9 4985.8 4994.5 18:27:01 INFO - PROCESS | 9697 | 18:27:01 INFO - PROCESS | 9697 | [#5] yui.html Cycles:180 Average:16835.80 Median:44032.06 stddev:23115.78 (52.5%) stddev-sans-first:23148.77 18:27:01 INFO - PROCESS | 9697 | Values: 671.9 675.5 698.3 702.0 754.4 13103.7 13723.4 13923.6 13931.6 14051.6 13242.5 13404.4 13418.5 13420.7 13421.2 619.1 753.9 775.4 791.6 794.0 773.7 782.2 786.1 786.4 786.5 825.6 829.7 830.1 830.3 832.3 640.0 787.8 814.2 815.8 816.8 610.1 610.7 610.8 610.8 611.2 13660.5 13700.9 13706.9 13727.3 13739.5 12653.5 13069.3 13154.8 13160.0 13186.2 11263.9 11444.8 11688.4 11842.6 12018.5 4205.8 4217.4 4231.9 4256.5 4272.6 56584.4 57024.0 57030.7 57530.0 57544.7 10163.4 10199.1 10228.4 10399.9 10456.8 18916.9 18972.5 18994.4 19076.0 19159.2 6850.5 6851.1 6854.8 6866.8 7125.5 3683.7 3710.4 3741.8 3791.2 3808.2 2748.4 2839.6 2845.6 2851.8 2852.4 471.7 483.8 486.3 488.7 490.6 408.8 458.9 473.2 479.8 515.5 6986.1 7391.8 7440.1 7494.3 7978.9 409.1 409.6 420.4 426.1 437.0 3543.6 3558.8 3568.8 3642.7 3748.6 88423.0 88849.0 89163.0 91808.0 93961.0 80279.0 80903.0 81549.0 82455.2 82706.0 43032.6 60743.0 62617.0 63200.1 74527.0 8281.5 8518.2 8636.6 8700.7 8806.3 41764.8 43454.6 43749.8 44314.3 44451.0 37860.8 49572.5 59159.0 61898.4 62453.0 10746.4 11205.2 11361.9 11432.5 11528.5 12796.3 13090.8 13151.9 13486.1 13985.3 12302.8 12470.6 12657.7 12683.7 13264.5 11828.4 11854.2 12207.8 12874.2 12886.4 12126.8 12206.3 12284.1 12806.8 12967.1 8929.4 9046.1 9113.2 9255.1 9283.3 9005.3 9012.9 9034.3 9345.0 9391.2 18:27:01 INFO - PROCESS | 9697 | -------- Summary: end -------- 18:27:01 INFO - PROCESS | 9697 | 18:27:02 INFO - TEST-INFO | 9697: exit 0 18:27:02 INFO - TEST-OK | dromaeo_css | took 1119830ms 18:27:02 INFO - TEST-START | kraken 18:27:02 INFO - operating with platform_type : linux_ 18:27:02 INFO - Initialising browser for kraken test... 18:27:02 INFO - TEST-INFO | started process 9883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKF5Vyb/profile http://localhost:60059/getInfo.html) 18:27:06 INFO - PROCESS | 9883 | __metrics Screen width/height:1600/1200 18:27:06 INFO - PROCESS | 9883 | colorDepth:24 18:27:06 INFO - PROCESS | 9883 | Browser inner width/height: 1024/697 18:27:06 INFO - PROCESS | 9883 | __metrics 18:27:07 INFO - PROCESS | 9883 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:27:08 INFO - TEST-INFO | 9883: exit 0 18:27:08 INFO - Browser initialized. 18:27:08 INFO - Running cycle 1/1 for kraken test... 18:27:08 INFO - TEST-INFO | started process 9965 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKF5Vyb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:27:20 INFO - PROCESS | 9965 | RSS: Main: 175276032 18:27:20 INFO - PROCESS | 9965 | 18:27:23 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60059/tests/kraken/driver.html?testName=audio-beat-detection) 18:27:24 INFO - PROCESS | 9965 | RSS: Main: 232611840 18:27:24 INFO - PROCESS | 9965 | 18:27:27 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60059/tests/kraken/driver.html?testName=audio-dft) 18:27:27 INFO - PROCESS | 9965 | RSS: Main: 220983296 18:27:27 INFO - PROCESS | 9965 | 18:27:31 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60059/tests/kraken/driver.html?testName=audio-fft) 18:27:31 INFO - PROCESS | 9965 | RSS: Main: 288854016 18:27:31 INFO - PROCESS | 9965 | 18:27:33 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60059/tests/kraken/driver.html?testName=audio-oscillator) 18:27:34 INFO - PROCESS | 9965 | RSS: Main: 198524928 18:27:34 INFO - PROCESS | 9965 | 18:27:36 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60059/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:27:37 INFO - PROCESS | 9965 | RSS: Main: 203427840 18:27:37 INFO - PROCESS | 9965 | 18:27:40 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60059/tests/kraken/driver.html?testName=imaging-darkroom) 18:27:41 INFO - PROCESS | 9965 | RSS: Main: 238804992 18:27:41 INFO - PROCESS | 9965 | 18:27:44 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60059/tests/kraken/driver.html?testName=imaging-desaturate) 18:27:45 INFO - PROCESS | 9965 | RSS: Main: 234856448 18:27:45 INFO - PROCESS | 9965 | 18:27:48 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60059/tests/kraken/driver.html?testName=json-parse-financial) 18:27:48 INFO - PROCESS | 9965 | RSS: Main: 237158400 18:27:48 INFO - PROCESS | 9965 | 18:27:50 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60059/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:27:50 INFO - PROCESS | 9965 | RSS: Main: 225923072 18:27:50 INFO - PROCESS | 9965 | 18:27:52 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:27:53 INFO - PROCESS | 9965 | RSS: Main: 231985152 18:27:53 INFO - PROCESS | 9965 | 18:27:55 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:27:56 INFO - PROCESS | 9965 | RSS: Main: 239505408 18:27:56 INFO - PROCESS | 9965 | 18:27:59 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:27:59 INFO - PROCESS | 9965 | RSS: Main: 239636480 18:27:59 INFO - PROCESS | 9965 | 18:28:02 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:28:03 INFO - PROCESS | 9965 | RSS: Main: 241180672 18:28:03 INFO - PROCESS | 9965 | 18:28:04 INFO - PROCESS | 9965 | Cycle 1(1): loaded http://localhost:60059/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60059/tests/kraken/driver.html?testName=ai-astar) 18:28:05 INFO - PROCESS | 9965 | RSS: Main: 283820032 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | __start_tp_report 18:28:05 INFO - PROCESS | 9965 | _x_x_mozilla_page_load 18:28:05 INFO - PROCESS | 9965 | _x_x_mozilla_page_load_details 18:28:05 INFO - PROCESS | 9965 | |i|pagename|runs| 18:28:05 INFO - PROCESS | 9965 | |0;ai-astar;115;107;114;116;112;113;111;112;113;114 18:28:05 INFO - PROCESS | 9965 | |1;audio-beat-detection;160;131;133;190;140;132;155;129;165;133 18:28:05 INFO - PROCESS | 9965 | |2;audio-dft;163;161;161;161;161;160;163;161;162;162 18:28:05 INFO - PROCESS | 9965 | |3;audio-fft;76;75;77;114;76;77;76;75;98;85 18:28:05 INFO - PROCESS | 9965 | |4;audio-oscillator;87;86;116;86;87;87;114;87;86;125 18:28:05 INFO - PROCESS | 9965 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;117;117 18:28:05 INFO - PROCESS | 9965 | |6;imaging-darkroom;147;146;146;147;146;146;146;146;147;147 18:28:05 INFO - PROCESS | 9965 | |7;imaging-desaturate;96;94;94;94;95;94;94;96;94;94 18:28:05 INFO - PROCESS | 9965 | |8;json-parse-financial;64;63;64;63;64;64;67;63;64;64 18:28:05 INFO - PROCESS | 9965 | |9;json-stringify-tinderbox;65;65;65;65;65;65;64;64;64;65 18:28:05 INFO - PROCESS | 9965 | |10;stanford-crypto-aes;86;89;84;83;83;84;92;85;117;84 18:28:05 INFO - PROCESS | 9965 | |11;stanford-crypto-ccm;131;140;131;128;131;139;141;133;136;142 18:28:05 INFO - PROCESS | 9965 | |12;stanford-crypto-pbkdf2;172;173;175;172;175;168;171;169;170;169 18:28:05 INFO - PROCESS | 9965 | |13;stanford-crypto-sha256-iterative;71;67;68;67;70;67;67;68;67;67 18:28:05 INFO - PROCESS | 9965 | __end_tp_report 18:28:05 INFO - PROCESS | 9965 | __start_cc_report 18:28:05 INFO - PROCESS | 9965 | _x_x_mozilla_cycle_collect,1878 18:28:05 INFO - PROCESS | 9965 | __end_cc_report 18:28:05 INFO - PROCESS | 9965 | __startTimestamp1466126885236__endTimestamp 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | ------- Summary: start ------- 18:28:05 INFO - PROCESS | 9965 | Number of tests: 14 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:2.50 (2.2%) stddev-sans-first:2.51 18:28:05 INFO - PROCESS | 9965 | Values: 115.0 107.0 114.0 116.0 112.0 113.0 111.0 112.0 113.0 114.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#1] audio-beat-detection Cycles:10 Average:146.80 Median:147.50 stddev:20.14 (13.7%) stddev-sans-first:20.79 18:28:05 INFO - PROCESS | 9965 | Values: 160.0 131.0 133.0 190.0 140.0 132.0 155.0 129.0 165.0 133.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.97 (0.6%) stddev-sans-first:0.87 18:28:05 INFO - PROCESS | 9965 | Values: 163.0 161.0 161.0 161.0 161.0 160.0 163.0 161.0 162.0 162.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#3] audio-fft Cycles:10 Average:82.90 Median:76.50 stddev:13.07 (17.1%) stddev-sans-first:13.62 18:28:05 INFO - PROCESS | 9965 | Values: 76.0 75.0 77.0 114.0 76.0 77.0 76.0 75.0 98.0 85.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:86.50 stddev:15.60 (18.0%) stddev-sans-first:16.19 18:28:05 INFO - PROCESS | 9965 | Values: 87.0 86.0 116.0 86.0 87.0 87.0 114.0 87.0 86.0 125.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:28:05 INFO - PROCESS | 9965 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 18:28:05 INFO - PROCESS | 9965 | Values: 147.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 147.0 147.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:0.85 (0.9%) stddev-sans-first:0.71 18:28:05 INFO - PROCESS | 9965 | Values: 96.0 94.0 94.0 94.0 95.0 94.0 94.0 96.0 94.0 94.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:1.15 (1.8%) stddev-sans-first:1.22 18:28:05 INFO - PROCESS | 9965 | Values: 64.0 63.0 64.0 63.0 64.0 64.0 67.0 63.0 64.0 64.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 18:28:05 INFO - PROCESS | 9965 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 64.0 64.0 64.0 65.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:84.50 stddev:10.35 (12.2%) stddev-sans-first:10.93 18:28:05 INFO - PROCESS | 9965 | Values: 86.0 89.0 84.0 83.0 83.0 84.0 92.0 85.0 117.0 84.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#11] stanford-crypto-ccm Cycles:10 Average:135.20 Median:137.50 stddev:5.03 (3.7%) stddev-sans-first:5.10 18:28:05 INFO - PROCESS | 9965 | Values: 131.0 140.0 131.0 128.0 131.0 139.0 141.0 133.0 136.0 142.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:172.00 stddev:2.46 (1.4%) stddev-sans-first:2.60 18:28:05 INFO - PROCESS | 9965 | Values: 172.0 173.0 175.0 172.0 175.0 168.0 171.0 169.0 170.0 169.0 18:28:05 INFO - PROCESS | 9965 | 18:28:05 INFO - PROCESS | 9965 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:67.50 stddev:1.45 (2.1%) stddev-sans-first:1.01 18:28:05 INFO - PROCESS | 9965 | Values: 71.0 67.0 68.0 67.0 70.0 67.0 67.0 68.0 67.0 67.0 18:28:05 INFO - PROCESS | 9965 | -------- Summary: end -------- 18:28:05 INFO - PROCESS | 9965 | 18:28:06 INFO - TEST-INFO | 9965: exit 0 18:28:06 INFO - TEST-OK | kraken | took 63948ms 18:28:06 INFO - SUITE-END | took 1183s 18:28:06 INFO - Completed test suite (00:19:43) 18:28:06 INFO - javascript benchmark 18:28:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2621.3053540811684, 2645.166772466672, 2671.292107591488, 2675.4916565852473, 2681.06162843005, 4587.243104887698, 4679.368285281486, 4709.835155769549, 4756.07256584966, 4810.374651295331, 4672.579467847893, 4704.294355846625, 4712.03403302323, 4716.787744551503, 4788.712677239359, 4273.5512771158965, 4337.826486940518, 4380.912381752379, 4438.334249862514, 4472.396226509412, 4387.890302742441, 4440.356148358486, 4447.0661161156195, 4457.754823484707, 4461.330800379953, 4257.084726783725, 4288.721233119837, 4291.463567054118, 4319.611234988866, 4353.847615333448, 4349.456317960255, 4352.804123814436, 4364.585364390409, 4369.0, 4433.756143412111, 4387.561243875619, 4390.670699697535, 4428.202923473774, 4433.490148632916, 4438.689291749579, 4694.24892017279, 4723.7638118093955, 4747.382840230783, 4801.231802911518, 4819.44576373718, 5074.949250507521, 5120.667156634807, 5167.09575824233, 5172.741362931876, 5175.974120129413, 5131.769070391804, 5168.638195326329, 5169.017886601534, 5178.430372659004, 5188.429272779991, 4687.226607609706, 4704.529547045268, 4739.691920025256, 4846.176150054499, 4847.175980083323, 5183.2743415921805, 5239.1093514102695, 5254.816081437131, 5260.658057226268, 5262.1317482615705, 4992.975035124874, 4998.625103117281, 5045.621578381562, 5054.873628159252, 5056.57019153376, 4984.37695288089, 5052.292679024939, 5086.415062267886, 5094.312267843794, 5100.744962751847, 5038.84883453497, 5058.317127187783, 5059.87350316245, 5077.035363280965, 5128.538431541179, 4971.975140124348, 5006.549410553068, 5010.423801262828, 5011.473795251519, 5014.0, 4171.165766846643, 4196.055887425305, 4199.91600167995, 4249.128928569636, 4249.936250956238, 3748.381517049711, 3769.3026790043928, 3772.434134879735, 3786.110264087937, 3787.715921305913, 4348.065165989265, 4383.189110014658, 4385.802638881258, 4396.538363471789, 4399.1641588098155, 5101.2603172539775, 5101.8724531886255, 5107.61692873036, 5111.233315002705, 5162.380514338303, 4398.758068306274, 4406.228909940747, 4407.449068866392, 4412.161689279027, 4457.242268814244, 4439.134368798053, 4507.2788353863225, 4510.323451482238, 4546.522615125365, 4562.634989200856, 4794.7362895040405, 4803.087413391444, 4810.302506136661, 4810.518948105231, 4850.442199147144, 4954.752262386938, 5049.671771334852, 5063.1898896176435, 5090.2364645303505, 5162.6386152968935, 4740.1230772307235, 4798.928016079832, 4812.639052071178, 4823.204171311694, 4831.9275210871165, 5027.371578552681, 5042.050123152204, 5048.899022019613, 5050.3434553507805, 5097.515736005073, 4919.704817710805, 4998.2002879539095, 5099.643024988215, 5115.18157094863, 5163.690178589297, 4902.828401005948, 4933.753312334441, 4952.801887924443, 4962.131626965339, 5009.323741295024, 4936.481669424658, 4947.1342515059005, 4948.950510494849, 4949.628777841748, 4994.750262486788, 4722.5513576210205, 4865.56209941107, 4867.29424233477, 4870.610351171968, 4923.852284431473, 5014.197728363444, 5047.192449208109, 5047.49525047507, 5081.441041485507, 5093.18509038552, 4937.802487900443, 4946.802127914987, 4953.876153096201, 4954.0, 5005.849824505271], "value": 4671.458785053811, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.1076999875203, 584.3980699879125, 589.2251689028926, 606.4384210657555, 608.0787606775734, 29128.854355728217, 31465.528017079763, 31537.369252614935, 31815.204619884487, 38821.80589097058, 38338.61661383385, 42033.579664203346, 42143.0, 42151.0, 42217.15565688685, 7850.528968261909, 7964.681412743512, 7986.2013798620255, 8015.799605009863, 8018.719344822933, 7850.960745196266, 7953.045634523836, 7953.363730901514, 7958.840823183533, 7960.761177164694, 7963.084245311777, 7986.520808751494, 8001.7199398021085, 8002.599870006506, 8003.119656837744, 7920.0, 7963.124056353797, 7964.442489025771, 7968.920310796876, 7969.362451003905, 7906.960465197695, 7994.120646728855, 7998.0, 8011.158828323, 8015.198480151997, 38801.80599097015, 39109.21781564381, 39158.21683566313, 39168.21663566741, 39172.0, 3080.9383812323626, 3172.74618030557, 3195.6964088411564, 3476.1830969722096, 3813.4089216171537, 29531.55702664462, 29766.70233297661, 29817.701822981926, 30100.54849177242, 30788.384232315228, 3394.998475449748, 3397.11674964511, 3412.6246112927556, 3452.085197422685, 3462.5844898612077, 258281.0, 259040.9999999981, 259267.0, 260514.0, 261482.0, 45215.77392113018, 50521.242181367306, 53150.734246328524, 53661.1950820738, 53718.46281537135, 55430.445695543334, 56023.719881400335, 56076.15885761717, 56326.718366407906, 56396.43603563912, 13739.862601373858, 14096.85903140976, 14104.506342277971, 14125.43498260058, 14129.85870141306, 10471.0, 10571.04860562553, 10573.154147668321, 10574.52414641343, 10575.524101415454, 16633.584160396087, 16707.41524046677, 16712.41506547265, 16729.330826767036, 16799.076050817323, 6226.439620434183, 6371.553991220663, 6377.17096777416, 6482.351764823574, 6490.902636460457, 795.6181033104027, 848.1137211613878, 863.838564576662, 901.7294811556507, 975.8487434447673, 27738.02916897899, 27931.530955047096, 28101.156965291073, 28450.0, 28626.713732862405, 932.3566738950238, 936.8266870628955, 951.5622813505864, 966.7728083900278, 998.5257002923553, 14681.0, 15035.0, 15047.699046019237, 15285.847141528442, 15296.0, 371532.0, 371964.0, 376649.0, 378714.0, 379288.0, 49723.75138124359, 49886.50113498818, 49906.251406228934, 50164.0, 50187.49812501828, 1050.4747626186906, 1079.1259080145107, 1137.4824454873178, 1171.5255321594768, 1212.775636507249, 28660.0, 29303.413931721672, 29414.852925735235, 29740.85129574338, 30071.398572028, 42568.57431425646, 42955.0, 43033.7848310769, 43067.13865722605, 43151.35272970845, 308808.0, 311703.0, 312931.0, 312968.0, 313013.0, 36477.63522364743, 36949.261014780095, 37832.05419864525, 38068.23863522659, 38212.0, 42220.36669449899, 42807.35788963106, 42971.78514107409, 43335.56664433315, 43747.56252437562, 36238.4564231542, 36568.26863462769, 36771.26457470889, 37460.250794984495, 37530.62469375271, 35990.100247494025, 36294.8185259072, 36612.633873660925, 37058.0, 37221.44167837431, 35995.1001224961, 36690.44964325591, 36849.0, 37187.44218836666, 37651.0, 32101.357972839945, 33444.83277583597, 33602.32795344129, 33690.32619347648, 33835.0, 25149.622755659046, 25491.617625735256, 25546.361340966625, 26112.34719132037, 26173.083942061938], "value": 17692.399928106548, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.46694493401964, 373.5909179448504, 377.5563712637652, 388.92610404023253, 390.74601509019135, 16136.19319034048, 16612.335506579737, 17310.740338894928, 17318.740218896728, 18069.81930180698, 10630.681079567625, 11171.329720216792, 11177.608783692573, 11186.888131118687, 11218.0, 4540.9091818163615, 4583.702059366139, 4770.329467698611, 5262.947370526284, 5273.1035723927025, 5144.228365745131, 5201.193814958688, 5227.137522308814, 5230.92153617696, 5252.737363131847, 4716.646251531131, 4784.162771514971, 4794.904101917959, 5007.474215207405, 5201.869953251161, 4754.429468463789, 4763.61891048717, 4773.212419950722, 4774.259989701602, 4787.449443314029, 4605.424321959755, 4616.330632058332, 4639.443266808004, 4672.065586882637, 4673.742944138071, 12244.632661020094, 12277.140600157993, 12483.50065997359, 12497.312647804367, 12611.432485538173, 1812.8187181281767, 1923.5095050762106, 1948.6005368899341, 1963.7447131872907, 2014.305064752658, 9432.19826314764, 9437.811243775086, 9717.951410242975, 9748.512574371252, 9839.114479696862, 1708.863290936722, 1762.0749555737414, 1795.6678014567347, 1880.0505744599027, 1896.2415033986379, 47784.52215477835, 61382.69308653473, 69539.30460695429, 70261.99999999949, 72837.99999999948, 15580.454684086002, 15802.288896999662, 15891.443799466962, 15970.920145399432, 16681.666366672598, 23244.76755232426, 24188.637170442456, 28876.566851496824, 29329.853350733538, 29481.852590737337, 8055.436119471698, 8222.958885205655, 8458.619362128718, 8499.83000340002, 8551.786205344915, 7119.394851437686, 7195.92804071963, 7204.3155900189395, 7213.422926165894, 7410.147832999274, 8418.368622353348, 8468.745937621881, 8698.304135669257, 8745.737627871174, 8854.468731876108, 3135.7961732487315, 3280.343931213719, 3298.1846504103796, 3394.0, 3400.412564744219, 766.8619648463218, 815.384384789492, 822.1655020154548, 838.1325328285195, 923.0354279777648, 17252.65494690099, 17332.30670773155, 17648.0, 17651.470455886345, 17940.91029544844, 831.5343407691712, 863.4387648028832, 874.956252187388, 912.8630705394111, 937.9765505862408, 9550.140487356171, 9953.402795832273, 9978.650747223812, 10054.296199266128, 10091.596336146618, 403453.0, 406570.0, 407449.0, 420992.0, 423024.0, 76832.0, 80758.19241807506, 81436.1138331702, 81908.59045704853, 81994.18005819985, 901.5582364641278, 906.1210625693067, 925.9907400926039, 929.4469790474657, 932.5057719408725, 15523.22383880824, 15824.129672868103, 15858.841411585736, 15958.0, 16248.0, 36053.81973090223, 36404.453933190496, 37497.25005499929, 38249.23501529898, 38430.61569384382, 52188.478115218364, 53146.4685353157, 54404.45595543994, 54432.45567544274, 55497.72251138718, 7764.0, 7840.764777056697, 7977.641006154853, 8054.798630034296, 8147.470414423044, 11400.714982125513, 12044.638660840188, 12120.090993175723, 12175.63473095773, 12289.1397602167, 10081.596736130472, 10233.437160956219, 10481.580736770444, 10695.572177112828, 10845.078168355778, 9854.753631159278, 10011.199104071802, 10520.263581549218, 10545.420001899662, 10665.306755060896, 9923.354981926152, 10201.38791672502, 10741.0, 11067.335959842436, 11331.830022549504, 7593.202713715202, 7745.573993430416, 7866.842663146819, 7978.202179781975, 8102.189781021851, 9322.300827437834, 10299.279050466395, 10317.535710892878, 10434.37393756377, 10465.529051192563], "value": 8601.859894135614, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1210.7820592293394, 1366.6993261482473, 1422.9573112806615, 1452.997431772077, 1615.7657139714743, 6609.272979972206, 7542.358899493543, 7822.843543129134, 7856.646450909709, 8011.399145064114, 7666.463347565673, 7893.0, 7898.644560994755, 7910.564918929456, 7910.604469776517, 2239.5744808486374, 2277.9886100569556, 2278.236790675126, 2280.247518318951, 2280.9201677941273, 2173.587018466494, 2226.3543572364074, 2238.9776102238934, 2241.686163937042, 2242.439390152462, 2211.7014203082617, 2228.2089858100385, 2236.4185311818883, 2243.102758896438, 2253.7746225377496, 2144.270947877729, 2185.5410363823617, 2191.5069109450324, 2192.725909261342, 2195.484061245606, 2174.206414658638, 2180.498485348363, 2181.8909054547366, 2182.2798476502717, 2184.104517147962, 7875.054993400771, 7878.763637090897, 7974.282314591656, 7986.440949133563, 8130.30892374143, 4719.174144524694, 4853.466118726938, 4854.029194161182, 4858.319835223072, 4859.465458799529, 2848.473032488996, 2963.5850980862697, 3017.0948715385516, 3020.501617233154, 3023.9546406803915, 920.093707697917, 924.6809850601531, 926.3422969691527, 948.3788118782209, 953.9618415263382, 10742.624008159755, 10885.401302928336, 10891.67324980252, 10908.018278354906, 10951.99999999992, 4058.837646494107, 4131.586841315904, 4163.10493243954, 4198.916021679549, 4225.133847561243, 8622.439541429787, 8670.95664521686, 8692.348073894484, 8695.217430431292, 8699.826003479895, 4594.402727645452, 4640.79116439761, 4645.326427668037, 4702.3416721659, 4707.670463067552, 3665.9735274123286, 3730.0488375464083, 3940.6847452203756, 4345.565443455629, 4415.62467190286, 3439.638837922032, 3553.6091029986683, 3554.253606742613, 3589.335972845032, 3603.4414665726854, 1031.4533297352416, 1049.2550289294534, 1058.8782290036745, 1061.9097376723066, 1066.2056767708107, 737.985240295191, 808.2806302390877, 817.4237162800212, 824.3322908444179, 844.3034496540379, 5115.283860259567, 5193.818216362485, 5220.269162317279, 5260.894782104337, 5276.947230527646, 382.20501357177005, 386.6095243803778, 390.2195608782435, 406.0944094668905, 412.5750477008686, 3501.852515835994, 3569.5359603251413, 3625.0, 3666.0834791302173, 3689.0777305673582, 9539.0, 9920.057594528509, 9928.453935033498, 10030.197584193247, 10047.698569042941, 9800.117989380848, 9865.0, 9866.161376283097, 9876.753081173028, 9897.75255618601, 7414.851702965911, 7436.367908727819, 7496.062992125984, 7530.698772049166, 7533.585652789039, 5186.58507319406, 5208.4270730220405, 5240.973795131, 5304.946950530445, 5330.573554115739, 8600.526971016403, 8951.0, 8982.281417486454, 8982.55087245622, 9264.675736349196, 8991.28069754431, 9153.17621414076, 9171.40385874916, 9317.068293170629, 9451.385659932082, 6028.8794224116145, 6720.227173875067, 6960.825979350557, 6977.825554361181, 7002.474814388839, 7665.923340766744, 7718.228177182237, 7735.9613201933635, 7749.341305988829, 7780.144184139627, 6876.518643694893, 7009.754658586925, 7078.362947334765, 7176.354128128493, 7183.964080179566, 6578.76974305897, 6634.668266586555, 6745.865082698417, 6764.3912047913955, 6895.310468953065, 5939.91090133657, 5997.940020599738, 6035.124906888389, 6077.4834139098675, 6081.604695694766, 4531.886702832324, 4712.3873896394625, 4747.786349614206, 4765.5710986010035, 4783.593394561502, 4724.385829842098, 4821.927671084866, 4906.484819093944, 4947.777350019186, 4993.925091123563], "value": 4068.107250347873, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.749246886381, 303.8587057018487, 307.44199278309867, 311.040440241854, 313.0639388229195, 4829.710217386959, 4840.443349014864, 4846.0, 4854.417469903612, 4863.343448634433, 4764.142454358214, 4766.189747742884, 4797.592204662599, 4803.663743537953, 4817.181079216532, 3561.572611286649, 3691.427828686558, 3702.981485092584, 3791.184895247521, 3804.90487737806, 3742.3637981543066, 3760.8119594020327, 3784.148566572513, 3788.2802267569214, 3794.753341032824, 3726.813659317037, 3753.887383378503, 3781.1681430085337, 3792.905177370574, 3801.771893686374, 3566.251087271676, 3630.709543236535, 3781.8487260509546, 3796.6393192646656, 3809.447630093621, 3300.719438847701, 3347.782394144373, 3360.3615313090436, 3372.6290108088338, 3381.2561236527927, 4666.160091183586, 4669.556392142741, 4676.368690226844, 4711.552402521789, 4750.738709370983, 4223.894402639959, 4299.97850010748, 4325.740455572675, 4357.542458041891, 4358.65130789536, 5011.949880501184, 5025.346704928373, 5029.535527591491, 5045.864680446907, 5051.924221136685, 3655.232401195752, 3670.210904655512, 3678.0988657779017, 3689.9852540551296, 3693.132113953219, 5120.795168193268, 5243.86890327741, 5267.55225805807, 5280.3663560372615, 5285.709285989269, 3741.401375779862, 3746.6957820939924, 3750.9062273442837, 3757.3612485877466, 3758.3047136279874, 3792.58281589025, 3795.35478968576, 3800.201957588882, 3819.3698039823676, 3827.655511004023, 4297.78511074445, 4302.612764851178, 4315.1585440838735, 4332.26709316276, 4335.241332766816, 4886.218205087169, 5002.249662550574, 5028.497150285016, 5052.7726252318735, 5053.84838454847, 1308.5093090091216, 1308.967275818112, 1311.2329287366806, 1316.2365827819842, 1317.7101037771674, 852.2542775071812, 859.7919922508885, 873.9599876147364, 880.3485420788699, 888.8844450221429, 4389.561043895585, 4402.9559704402545, 4436.667249956202, 4438.400815889813, 4445.310781902856, 5089.491050894881, 5104.0, 5118.97440512795, 5143.819966301162, 5144.61415393847, 4279.486461624562, 4416.908164285013, 4419.248727716232, 4436.64506839452, 4441.622462090759, 2703.3106557827628, 2717.1984264641583, 2728.2906444324617, 2751.834889906612, 2752.944941101167, 4800.975995120072, 4810.6151507879285, 4827.42070951481, 4834.589059929875, 4855.6601037927, 5083.87290317745, 5205.6616319939085, 5218.217267409843, 5241.030409374278, 5241.14357675068, 4835.226363781778, 4846.019697885846, 4846.927296090562, 4847.612191024709, 4859.538343857328, 5074.746262686925, 5096.362954630671, 5116.974415128049, 5135.281060651587, 5148.29714399943, 4793.544613261734, 5020.594233614594, 5021.698698078127, 5037.319961805256, 5123.359580052494, 4918.803247870045, 4924.975375123245, 4934.950650493593, 4937.136001199848, 4939.802407903644, 3674.577423596321, 3691.3171063353366, 3705.203381273039, 3716.368217402994, 3724.6647801696895, 5103.234514822806, 5168.819091331786, 5290.153575427913, 5291.391489978548, 5301.3373328333955, 5009.223570346602, 5037.848864534069, 5039.765257511786, 5039.949600504095, 5044.9243261350375, 5042.823501177441, 5043.193089105872, 5049.671771334852, 5053.393592768891, 5056.797728090983, 4687.9296810547185, 4961.255811628284, 4968.801247950041, 4978.751062446936, 4991.401031876198, 4846.733429661262, 4981.900361992813, 5021.5229553192385, 5058.645894787477, 5104.846854594368, 4956.77694503741, 4969.862958927696, 4980.925286120639, 4985.825496107619, 4994.450610432922], "value": 3809.8278394135623, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.8924972004477, 675.4562577125412, 698.2807708060698, 701.9789406317813, 754.4266357568248, 13103.73792524149, 13723.382447789849, 13923.582292531215, 13931.582052538415, 14051.578452646412, 13242.470301187965, 13404.396802143903, 13418.530351437701, 13420.66448338792, 13421.194728316283, 619.055939691971, 753.9057617797775, 775.4068137874531, 791.6160662078887, 793.9523628582275, 773.6789232468519, 782.1982467970347, 786.1313248860013, 786.4376970466116, 786.5280831501111, 825.558326295432, 829.6805729794042, 830.0744669693308, 830.3274347778319, 832.2592892325835, 639.9648019358933, 787.8424315136996, 814.2386868278179, 815.7593509914531, 816.7753867686374, 610.0970563565947, 610.6946526736632, 610.7831719739473, 610.8381278961078, 611.1779656362186, 13660.52188173419, 13700.862991370059, 13706.862931370659, 13727.313634318245, 13739.450421983109, 12653.493860245579, 13069.346532673424, 13154.802677959839, 13159.955201839579, 13186.208827470382, 11263.887361126366, 11444.771104577945, 11688.41557922109, 11842.585509507213, 12018.51925922962, 4205.789710514493, 4217.430646862665, 4231.87304380869, 4256.5105012923605, 4272.636825869805, 56584.43415565833, 57024.0, 57030.714846425915, 57530.0, 57544.71227643835, 10163.390196588229, 10199.082082612453, 10228.386296822215, 10399.94800025995, 10456.790864182674, 18916.86498810076, 18972.52568685766, 18994.430167095008, 19076.0, 19159.233630654897, 6850.520463567602, 6851.07510486078, 6854.828629284308, 6866.793996180123, 7125.5368401053765, 3683.749277989127, 3710.424884142962, 3741.7567858089137, 3791.1659434924272, 3808.2002779416634, 2748.4365705030423, 2839.645044369454, 2845.601615773793, 2851.81463204891, 2852.3867368515453, 471.6627611257967, 483.77704448061104, 486.26573873451343, 488.7043338779987, 490.619769678502, 408.7772164170534, 458.8607594936722, 473.21682615271783, 479.7721082485861, 515.5334422347706, 6986.126734158231, 7391.8152046199275, 7440.14438339587, 7494.250574942462, 7978.880316795253, 409.09793904440636, 409.5679058593153, 420.4478813601471, 426.1264407963662, 437.01889258613977, 3543.6102028776813, 3558.7508874379064, 3568.803715795656, 3642.7450078494253, 3748.606396328401, 88423.0, 88849.0, 89163.0, 91808.0, 93961.0, 80279.0, 80903.0, 81549.0, 82455.17544824474, 82706.0, 43032.56967430285, 60743.0, 62617.0, 63200.09998750156, 74526.99999999891, 8281.503109813431, 8518.23335899772, 8636.611352489028, 8700.651973920973, 8806.295496360148, 41764.791176043924, 43454.56545434505, 43749.78125109354, 44314.335284970104, 44451.0, 37860.81069594635, 49572.50427495823, 59159.0, 61898.38101618926, 62453.0, 10746.355218686904, 11205.239695083059, 11361.943190283995, 11432.542698291974, 11528.481218345363, 12796.296203708886, 13090.80363794563, 13151.868481315065, 13486.12340197884, 13985.300734963415, 12302.759260821971, 12470.625881223577, 12657.683557911127, 12683.682907927376, 13264.53574124901, 11828.408579570814, 11854.229475084092, 12207.816882746944, 12874.227546347249, 12886.42011109521, 12126.818097728717, 12206.32865192423, 12284.0786940978, 12806.807897881725, 12967.092303538662, 8929.374943753875, 9046.140616641409, 9113.179813816789, 9255.120763527455, 9283.303752218475, 9005.324600655109, 9012.864807027769, 9034.322425817958, 9345.0, 9391.20174785151], "value": 6427.744227243786, "unit": "score"}], "name": "dromaeo_css", "value": 6432.402242733425, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 107.0, 114.0, 116.0, 112.0, 113.0, 111.0, 112.0, 113.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 131.0, 133.0, 190.0, 140.0, 132.0, 155.0, 129.0, 165.0, 133.0], "value": 146.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 161.0, 161.0, 160.0, 163.0, 161.0, 162.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 114.0, 76.0, 77.0, 76.0, 75.0, 98.0, 85.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 116.0, 86.0, 87.0, 87.0, 114.0, 87.0, 86.0, 125.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 64.0, 64.0, 67.0, 63.0, 64.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 84.0, 83.0, 83.0, 84.0, 92.0, 85.0, 117.0, 84.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 140.0, 131.0, 128.0, 131.0, 139.0, 141.0, 133.0, 136.0, 142.0], "value": 135.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 175.0, 172.0, 175.0, 168.0, 171.0, 169.0, 170.0, 169.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 68.0, 67.0, 70.0, 67.0, 67.0, 68.0, 67.0, 67.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1549.7000000000003, "alertThreshold": 2.0}]} 18:28:06 INFO - Return code: 0 18:28:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:28:06 INFO - # TBPL SUCCESS # 18:28:06 INFO - Running post-action listener: _resource_record_post_action 18:28:06 INFO - ##### 18:28:06 INFO - ##### Finished run-tests step (success) 18:28:06 INFO - ##### 18:28:06 INFO - Running post-run listener: _resource_record_post_run 18:28:06 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 184320; Write bytes: 287707136; Read time: 60; Write time: 301384 18:28:06 INFO - TinderboxPrint: CPU usage
13.1% 18:28:06 INFO - TinderboxPrint: I/O read bytes / time
184,320 / 60 18:28:06 INFO - TinderboxPrint: I/O write bytes / time
287,707,136 / 301,384 18:28:06 INFO - TinderboxPrint: CPU idle
8,324.7 (86.9%) 18:28:06 INFO - TinderboxPrint: CPU user
1,212.6 (12.7%) 18:28:06 INFO - TinderboxPrint: Swap in / out
0 / 0 18:28:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1351680; Read time: 0; Write time: 68 18:28:06 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 184320; Write bytes: 286355456; Read time: 60; Write time: 301316 18:28:07 INFO - Running post-run listener: _upload_blobber_files 18:28:07 INFO - Blob upload gear active. 18:28:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:28:07 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 18:28:07 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:28:07 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:28:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:28:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:28:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:28:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:08 INFO - (blobuploader) - INFO - Done attempting. 18:28:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:28:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:28:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:09 INFO - (blobuploader) - INFO - Done attempting. 18:28:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:28:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:28:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:13 INFO - (blobuploader) - INFO - Done attempting. 18:28:13 INFO - (blobuploader) - INFO - Iteration through files over. 18:28:13 INFO - Return code: 0 18:28:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:28:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:28:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c69de0445169ce47ead1fc602be897103ac0a70e2f757416014ee09aabb4890be835532380254178b195963e9fda8b676bf953fb4d95055c4a1e1f720add532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c07446f7db9ccdc6030e67266c45c61476775adc06760973c4ec29d511bea14548a5db531f47237323be900d87e3d701890de8ff07ac3b879820a6dc1c110ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b517f14b1d1549beb8f890b396b70bd58856fcf752c9674a81847cbe8000f562145da33b27c5f6aba15e4bdda90e7305b0138490a7d3170f1a103c3071272510"} 18:28:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:28:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:28:13 INFO - Contents: 18:28:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c69de0445169ce47ead1fc602be897103ac0a70e2f757416014ee09aabb4890be835532380254178b195963e9fda8b676bf953fb4d95055c4a1e1f720add532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c07446f7db9ccdc6030e67266c45c61476775adc06760973c4ec29d511bea14548a5db531f47237323be900d87e3d701890de8ff07ac3b879820a6dc1c110ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b517f14b1d1549beb8f890b396b70bd58856fcf752c9674a81847cbe8000f562145da33b27c5f6aba15e4bdda90e7305b0138490a7d3170f1a103c3071272510"} 18:28:13 INFO - Running post-run listener: copy_logs_to_upload_dir 18:28:13 INFO - Copying logs to upload dir... 18:28:13 INFO - mkdir: /builds/slave/test/build/upload/logs 18:28:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.461382 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-16 18:28:13.843167) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 18:28:13.847123) ========= 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=0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c69de0445169ce47ead1fc602be897103ac0a70e2f757416014ee09aabb4890be835532380254178b195963e9fda8b676bf953fb4d95055c4a1e1f720add532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c07446f7db9ccdc6030e67266c45c61476775adc06760973c4ec29d511bea14548a5db531f47237323be900d87e3d701890de8ff07ac3b879820a6dc1c110ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b517f14b1d1549beb8f890b396b70bd58856fcf752c9674a81847cbe8000f562145da33b27c5f6aba15e4bdda90e7305b0138490a7d3170f1a103c3071272510"} build_url:https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/WGuH-sdpS7Ca99-EjDi0qg/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/Firefox-Non-PGO/sha512/0c69de0445169ce47ead1fc602be897103ac0a70e2f757416014ee09aabb4890be835532380254178b195963e9fda8b676bf953fb4d95055c4a1e1f720add532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c07446f7db9ccdc6030e67266c45c61476775adc06760973c4ec29d511bea14548a5db531f47237323be900d87e3d701890de8ff07ac3b879820a6dc1c110ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b517f14b1d1549beb8f890b396b70bd58856fcf752c9674a81847cbe8000f562145da33b27c5f6aba15e4bdda90e7305b0138490a7d3170f1a103c3071272510"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 18:28:13.874029) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:28:13.874552) ========= 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=0333a256a5e4f052382dd180000001b1-1466125559.516617-1577144457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:28:13.957024) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 18:28:13.957451) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 18:28:13.958106) ========= ========= Total master_lag: 0.17 =========