builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1466691365.75 results: success (0) buildid: 20160623055826 builduid: 87d20ab26b314da48b89a453e71b31de revision: d1102663db10b3d4b9358f3cf4e16b7c56902352 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:05.753064) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:05.753565) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:05.753864) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:05.804661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:05.804966) ========= 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=62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-23 07:16:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-06-23 07:16:06 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.183027 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:06.013334) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:06.013788) ========= 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=62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.060941 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:06.090775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 07:16:06.091183) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d1102663db10b3d4b9358f3cf4e16b7c56902352 --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 d1102663db10b3d4b9358f3cf4e16b7c56902352 --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=62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-23 07:16:06,147 truncating revision to first 12 chars 2016-06-23 07:16:06,147 Setting DEBUG logging. 2016-06-23 07:16:06,147 attempt 1/10 2016-06-23 07:16:06,147 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d1102663db10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-23 07:16:07,348 unpacking tar archive at: mozilla-central-d1102663db10/testing/mozharness/ program finished with exit code 0 elapsedTime=1.583085 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 07:16:07.687693) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:07.687986) ========= script_repo_revision: d1102663db10b3d4b9358f3cf4e16b7c56902352 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:07.688399) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:07.688667) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 07:16:07.700085) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-06-23 07:16:07.700629) ========= /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=62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:16:07 INFO - MultiFileLogger online at 20160623 07:16:07 in /builds/slave/test 07:16:07 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 07:16:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:16:07 INFO - {'append_to_log': False, 07:16:07 INFO - 'base_work_dir': '/builds/slave/test', 07:16:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:16:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:16:07 INFO - 'branch': 'Firefox-Non-PGO', 07:16:07 INFO - 'buildbot_json_path': 'buildprops.json', 07:16:07 INFO - 'config_files': ('talos/linux_config.py',), 07:16:07 INFO - 'default_actions': ('clobber', 07:16:07 INFO - 'read-buildbot-config', 07:16:07 INFO - 'download-and-extract', 07:16:07 INFO - 'populate-webroot', 07:16:07 INFO - 'create-virtualenv', 07:16:07 INFO - 'install', 07:16:07 INFO - 'run-tests'), 07:16:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:16:07 INFO - 'download_minidump_stackwalk': True, 07:16:07 INFO - 'download_symbols': 'ondemand', 07:16:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:16:07 INFO - 'tooltool.py': '/tools/tooltool.py', 07:16:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:16:07 INFO - '/tools/misc-python/virtualenv.py')}, 07:16:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:16:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:16:07 INFO - 'installer_path': 'installer.exe', 07:16:07 INFO - 'log_level': 'info', 07:16:07 INFO - 'log_name': 'talos', 07:16:07 INFO - 'log_to_console': True, 07:16:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:16:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:16:07 INFO - 'opt_config_files': (), 07:16:07 INFO - 'pip_index': False, 07:16:07 INFO - 'sps_profile': False, 07:16:07 INFO - 'sps_profile_interval': 0, 07:16:07 INFO - 'suite': 'dromaeojs', 07:16:07 INFO - 'system_bits': '32', 07:16:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:16:07 INFO - 'title': 'talos-linux64-ix-018', 07:16:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:16:07 INFO - 'use_talos_json': True, 07:16:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:16:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:16:07 INFO - 'work_dir': 'build'} 07:16:07 INFO - ##### 07:16:07 INFO - ##### Running clobber step. 07:16:07 INFO - ##### 07:16:07 INFO - Running pre-action listener: _resource_record_pre_action 07:16:07 INFO - Running main action method: clobber 07:16:07 INFO - rmtree: /builds/slave/test/build 07:16:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:16:12 INFO - Running post-action listener: _resource_record_post_action 07:16:12 INFO - ##### 07:16:12 INFO - ##### Finished clobber step (success) 07:16:12 INFO - ##### 07:16:12 INFO - ##### 07:16:12 INFO - ##### Running read-buildbot-config step. 07:16:12 INFO - ##### 07:16:12 INFO - Running pre-action listener: _resource_record_pre_action 07:16:12 INFO - Running main action method: read_buildbot_config 07:16:12 INFO - Using buildbot properties: 07:16:12 INFO - { 07:16:12 INFO - "project": "", 07:16:12 INFO - "product": "firefox", 07:16:12 INFO - "script_repo_revision": "production", 07:16:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:16:12 INFO - "repository": "", 07:16:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:16:12 INFO - "buildid": "20160623055826", 07:16:12 INFO - "pgo_build": "False", 07:16:12 INFO - "basedir": "/builds/slave/test", 07:16:12 INFO - "buildnumber": 532, 07:16:12 INFO - "slavename": "talos-linux64-ix-018", 07:16:12 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:16:12 INFO - "platform": "ubuntu64_hw", 07:16:12 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:16:12 INFO - "branch": "mozilla-central", 07:16:12 INFO - "repo_path": "mozilla-central", 07:16:12 INFO - "revision": "d1102663db10b3d4b9358f3cf4e16b7c56902352", 07:16:12 INFO - "stage_platform": "linux64", 07:16:12 INFO - "builduid": "87d20ab26b314da48b89a453e71b31de", 07:16:12 INFO - "slavebuilddir": "test" 07:16:12 INFO - } 07:16:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:16:12 INFO - Running post-action listener: _resource_record_post_action 07:16:12 INFO - ##### 07:16:12 INFO - ##### Finished read-buildbot-config step (success) 07:16:12 INFO - ##### 07:16:12 INFO - ##### 07:16:12 INFO - ##### Running download-and-extract step. 07:16:12 INFO - ##### 07:16:12 INFO - Running pre-action listener: _resource_record_pre_action 07:16:12 INFO - Running main action method: download_and_extract 07:16:12 INFO - mkdir: /builds/slave/test/build/tests 07:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:12 INFO - https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:14 INFO - Downloaded 1361 bytes. 07:16:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:16:14 INFO - Using the following test package requirements: 07:16:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:16:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:16:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:16:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'jsshell-linux-x86_64.zip'], 07:16:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:16:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:16:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:16:14 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:16:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:16:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:16:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:16:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:14 INFO - https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:16:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:16:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:16:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:17 INFO - Downloaded 22703282 bytes. 07:16:17 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'] 07:16:17 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 07:16:19 INFO - Return code: 0 07:16:19 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 07:16:19 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')]} 07:16:19 INFO - https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:16:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:16:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:16:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:20 INFO - Downloaded 22703282 bytes. 07:16:20 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'] 07:16:20 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 07:16:22 INFO - Return code: 0 07:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:22 INFO - https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:16:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:16:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:16:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:24 INFO - Downloaded 14405384 bytes. 07:16:24 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'] 07:16:24 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 07:16:24 INFO - Return code: 0 07:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:24 INFO - https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:16:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:16:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:16:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N_vbBqUxSaqHsPWycMlA4w/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 07:16:27 INFO - Downloaded 56279442 bytes. 07:16:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:16:27 INFO - mkdir: /builds/slave/test/properties 07:16:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:16:27 INFO - Writing to file /builds/slave/test/properties/build_url 07:16:27 INFO - Contents: 07:16:27 INFO - build_url:https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:16:27 INFO - Running post-action listener: _resource_record_post_action 07:16:28 INFO - Running post-action listener: set_extra_try_arguments 07:16:28 INFO - ##### 07:16:28 INFO - ##### Finished download-and-extract step (success) 07:16:28 INFO - ##### 07:16:28 INFO - ##### 07:16:28 INFO - ##### Running populate-webroot step. 07:16:28 INFO - ##### 07:16:28 INFO - Running pre-action listener: _resource_record_pre_action 07:16:28 INFO - Running main action method: populate_webroot 07:16:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:16:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:16:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:16:28 INFO - '--tppagecycles', 07:16:28 INFO - '7'], 07:16:28 INFO - 'tests': ['tsvgm']}}, 07:16:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:16:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:16:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:16:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:16:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['damp', 'tps']}, 07:16:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'tests': ['damp', 'tps']}, 07:16:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['dromaeo_dom']}, 07:16:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:16:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['basic_compositor_video']}, 07:16:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:16:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['a11yr', 07:16:28 INFO - 'ts_paint', 07:16:28 INFO - 'tpaint', 07:16:28 INFO - 'sessionrestore', 07:16:28 INFO - 'sessionrestore_no_auto_restore', 07:16:28 INFO - 'tabpaint']}, 07:16:28 INFO - 'other-e10s': {'tests': ['a11yr', 07:16:28 INFO - 'ts_paint', 07:16:28 INFO - 'tpaint', 07:16:28 INFO - 'sessionrestore', 07:16:28 INFO - 'sessionrestore_no_auto_restore', 07:16:28 INFO - 'tabpaint']}, 07:16:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:16:28 INFO - 'ts_paint', 07:16:28 INFO - 'tpaint', 07:16:28 INFO - 'sessionrestore', 07:16:28 INFO - 'sessionrestore_no_auto_restore', 07:16:28 INFO - 'tabpaint']}, 07:16:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:16:28 INFO - 'ts_paint', 07:16:28 INFO - 'tpaint', 07:16:28 INFO - 'sessionrestore', 07:16:28 INFO - 'sessionrestore_no_auto_restore', 07:16:28 INFO - 'tabpaint']}, 07:16:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['a11yr', 07:16:28 INFO - 'ts_paint', 07:16:28 INFO - 'tpaint', 07:16:28 INFO - 'sessionrestore', 07:16:28 INFO - 'sessionrestore_no_auto_restore', 07:16:28 INFO - 'tabpaint']}, 07:16:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['a11yr', 07:16:28 INFO - 'ts_paint', 07:16:28 INFO - 'tpaint', 07:16:28 INFO - 'sessionrestore', 07:16:28 INFO - 'sessionrestore_no_auto_restore', 07:16:28 INFO - 'tabpaint']}, 07:16:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['tsvgx', 07:16:28 INFO - 'tsvgr_opacity', 07:16:28 INFO - 'tart', 07:16:28 INFO - 'tscrollx', 07:16:28 INFO - 'cart']}, 07:16:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:16:28 INFO - 'tsvgr_opacity', 07:16:28 INFO - 'tart', 07:16:28 INFO - 'tscrollx', 07:16:28 INFO - 'cart']}, 07:16:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'talos_options': ['--disable-e10s'], 07:16:28 INFO - 'tests': ['tp5o']}, 07:16:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'tests': ['tp5o']}, 07:16:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'talos_options': ['--disable-e10s', 07:16:28 INFO - '--xperf_path', 07:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:28 INFO - 'tests': ['tp5n']}, 07:16:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:28 INFO - 'talos_options': ['--xperf_path', 07:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:28 INFO - 'tests': ['tp5n']}}, 07:16:28 INFO - 'talos.zip': {'path': '', 07:16:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:16:28 INFO - Running post-action listener: _resource_record_post_action 07:16:28 INFO - ##### 07:16:28 INFO - ##### Finished populate-webroot step (success) 07:16:28 INFO - ##### 07:16:28 INFO - ##### 07:16:28 INFO - ##### Running create-virtualenv step. 07:16:28 INFO - ##### 07:16:28 INFO - Running pre-action listener: _resource_record_pre_action 07:16:28 INFO - Running main action method: create_virtualenv 07:16:28 INFO - Creating virtualenv /builds/slave/test/build/venv 07:16:28 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 07:16:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:16:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:16:28 INFO - Using real prefix '/usr' 07:16:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:16:29 INFO - Installing distribute.............................................................................................................................................................................................done. 07:16:31 INFO - Installing pip.................done. 07:16:31 INFO - Return code: 0 07:16:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:16:31 INFO - Using env: {'DISPLAY': ':0', 07:16:31 INFO - 'HOME': '/home/cltbld', 07:16:31 INFO - 'LANG': 'en_US.UTF-8', 07:16:31 INFO - 'LANGUAGE': 'en_US:en', 07:16:31 INFO - 'LOGNAME': 'cltbld', 07:16:31 INFO - 'MAIL': '/var/mail/cltbld', 07:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:31 INFO - 'MOZ_NO_REMOTE': '1', 07:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:31 INFO - 'NO_EM_RESTART': '1', 07:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:31 INFO - 'PWD': '/builds/slave/test', 07:16:31 INFO - 'SHELL': '/bin/bash', 07:16:31 INFO - 'SHLVL': '1', 07:16:31 INFO - 'TERM': 'linux', 07:16:31 INFO - 'TMOUT': '86400', 07:16:31 INFO - 'USER': 'cltbld', 07:16:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:31 INFO - '_': '/tools/buildbot/bin/python'} 07:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:32 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 07:16:32 INFO - Cleaning up... 07:16:32 INFO - Return code: 0 07:16:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:16:32 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')]} 07:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:32 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')]} 07:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:32 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:32 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 07:16:32 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 07:16:32 INFO - Using env: {'DISPLAY': ':0', 07:16:32 INFO - 'HOME': '/home/cltbld', 07:16:32 INFO - 'LANG': 'en_US.UTF-8', 07:16:32 INFO - 'LANGUAGE': 'en_US:en', 07:16:32 INFO - 'LOGNAME': 'cltbld', 07:16:32 INFO - 'MAIL': '/var/mail/cltbld', 07:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:32 INFO - 'MOZ_NO_REMOTE': '1', 07:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:32 INFO - 'NO_EM_RESTART': '1', 07:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:32 INFO - 'PWD': '/builds/slave/test', 07:16:32 INFO - 'SHELL': '/bin/bash', 07:16:32 INFO - 'SHLVL': '1', 07:16:32 INFO - 'TERM': 'linux', 07:16:32 INFO - 'TMOUT': '86400', 07:16:32 INFO - 'USER': 'cltbld', 07:16:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:32 INFO - '_': '/tools/buildbot/bin/python'} 07:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:32 INFO - Downloading/unpacking psutil>=3.1.1 07:16:32 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 07:16:32 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 07:16:32 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 07:16:32 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 07:16:32 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 07:16:32 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 07:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:16:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:34 INFO - Installing collected packages: psutil 07:16:34 INFO - Running setup.py install for psutil 07:16:34 INFO - building 'psutil._psutil_linux' extension 07:16:34 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 07:16:35 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 07:16:35 INFO - building 'psutil._psutil_posix' extension 07:16:35 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 07:16:36 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 07:16:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:36 INFO - Successfully installed psutil 07:16:36 INFO - Cleaning up... 07:16:36 INFO - Return code: 0 07:16:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:36 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:36 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 07:16:36 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 07:16:36 INFO - Using env: {'DISPLAY': ':0', 07:16:36 INFO - 'HOME': '/home/cltbld', 07:16:36 INFO - 'LANG': 'en_US.UTF-8', 07:16:36 INFO - 'LANGUAGE': 'en_US:en', 07:16:36 INFO - 'LOGNAME': 'cltbld', 07:16:36 INFO - 'MAIL': '/var/mail/cltbld', 07:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:36 INFO - 'MOZ_NO_REMOTE': '1', 07:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:36 INFO - 'NO_EM_RESTART': '1', 07:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:36 INFO - 'PWD': '/builds/slave/test', 07:16:36 INFO - 'SHELL': '/bin/bash', 07:16:36 INFO - 'SHLVL': '1', 07:16:36 INFO - 'TERM': 'linux', 07:16:36 INFO - 'TMOUT': '86400', 07:16:36 INFO - 'USER': 'cltbld', 07:16:36 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:36 INFO - '_': '/tools/buildbot/bin/python'} 07:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:16:36 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 07:16:36 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 07:16:36 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 07:16:36 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 07:16:36 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 07:16:36 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 07:16:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:16:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:16:38 INFO - Installing collected packages: mozsystemmonitor 07:16:38 INFO - Running setup.py install for mozsystemmonitor 07:16:38 INFO - Successfully installed mozsystemmonitor 07:16:38 INFO - Cleaning up... 07:16:38 INFO - Return code: 0 07:16:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:38 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:38 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 07:16:38 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 07:16:38 INFO - Using env: {'DISPLAY': ':0', 07:16:38 INFO - 'HOME': '/home/cltbld', 07:16:38 INFO - 'LANG': 'en_US.UTF-8', 07:16:38 INFO - 'LANGUAGE': 'en_US:en', 07:16:38 INFO - 'LOGNAME': 'cltbld', 07:16:38 INFO - 'MAIL': '/var/mail/cltbld', 07:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:38 INFO - 'MOZ_NO_REMOTE': '1', 07:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:38 INFO - 'NO_EM_RESTART': '1', 07:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:38 INFO - 'PWD': '/builds/slave/test', 07:16:38 INFO - 'SHELL': '/bin/bash', 07:16:38 INFO - 'SHLVL': '1', 07:16:38 INFO - 'TERM': 'linux', 07:16:38 INFO - 'TMOUT': '86400', 07:16:38 INFO - 'USER': 'cltbld', 07:16:38 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:38 INFO - '_': '/tools/buildbot/bin/python'} 07:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:38 INFO - Downloading/unpacking jsonschema==2.5.1 07:16:38 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 07:16:38 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 07:16:38 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 07:16:38 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 07:16:38 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 07:16:38 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 07:16:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:16:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:16:40 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 07:16:40 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 07:16:40 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 07:16:40 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 07:16:40 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 07:16:40 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 07:16:40 INFO - Downloading functools32-3.2.3-2.tar.gz 07:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:16:41 INFO - warning: no files found matching '*.txt' 07:16:41 INFO - no previously-included directories found matching 'build' 07:16:41 INFO - no previously-included directories found matching 'dist' 07:16:41 INFO - no previously-included directories found matching '.git*' 07:16:41 INFO - Installing collected packages: jsonschema, functools32 07:16:41 INFO - Running setup.py install for functools32 07:16:41 INFO - warning: no files found matching '*.txt' 07:16:41 INFO - no previously-included directories found matching 'build' 07:16:41 INFO - no previously-included directories found matching 'dist' 07:16:41 INFO - no previously-included directories found matching '.git*' 07:16:41 INFO - Successfully installed jsonschema functools32 07:16:41 INFO - Cleaning up... 07:16:41 INFO - Return code: 0 07:16:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:41 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:41 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 07:16:41 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 07:16:41 INFO - Using env: {'DISPLAY': ':0', 07:16:41 INFO - 'HOME': '/home/cltbld', 07:16:41 INFO - 'LANG': 'en_US.UTF-8', 07:16:41 INFO - 'LANGUAGE': 'en_US:en', 07:16:41 INFO - 'LOGNAME': 'cltbld', 07:16:41 INFO - 'MAIL': '/var/mail/cltbld', 07:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:41 INFO - 'MOZ_NO_REMOTE': '1', 07:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:41 INFO - 'NO_EM_RESTART': '1', 07:16:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:41 INFO - 'PWD': '/builds/slave/test', 07:16:41 INFO - 'SHELL': '/bin/bash', 07:16:41 INFO - 'SHLVL': '1', 07:16:41 INFO - 'TERM': 'linux', 07:16:41 INFO - 'TMOUT': '86400', 07:16:41 INFO - 'USER': 'cltbld', 07:16:41 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:41 INFO - '_': '/tools/buildbot/bin/python'} 07:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:41 INFO - Downloading/unpacking blobuploader==1.2.4 07:16:41 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 07:16:41 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 07:16:41 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 07:16:41 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 07:16:41 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 07:16:41 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 07:16:43 INFO - Downloading blobuploader-1.2.4.tar.gz 07:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:16:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:16:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:44 INFO - Downloading docopt-0.6.1.tar.gz 07:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:16:44 INFO - Installing collected packages: blobuploader, requests, docopt 07:16:44 INFO - Running setup.py install for blobuploader 07:16:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:16:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:16:44 INFO - Running setup.py install for requests 07:16:44 INFO - Running setup.py install for docopt 07:16:44 INFO - Successfully installed blobuploader requests docopt 07:16:44 INFO - Cleaning up... 07:16:44 INFO - Return code: 0 07:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:44 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:44 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 07:16:44 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 07:16:44 INFO - Using env: {'DISPLAY': ':0', 07:16:44 INFO - 'HOME': '/home/cltbld', 07:16:44 INFO - 'LANG': 'en_US.UTF-8', 07:16:44 INFO - 'LANGUAGE': 'en_US:en', 07:16:44 INFO - 'LOGNAME': 'cltbld', 07:16:44 INFO - 'MAIL': '/var/mail/cltbld', 07:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:44 INFO - 'MOZ_NO_REMOTE': '1', 07:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:44 INFO - 'NO_EM_RESTART': '1', 07:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:44 INFO - 'PWD': '/builds/slave/test', 07:16:44 INFO - 'SHELL': '/bin/bash', 07:16:44 INFO - 'SHLVL': '1', 07:16:44 INFO - 'TERM': 'linux', 07:16:44 INFO - 'TMOUT': '86400', 07:16:44 INFO - 'USER': 'cltbld', 07:16:44 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:44 INFO - '_': '/tools/buildbot/bin/python'} 07:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:44 INFO - Running setup.py (path:/tmp/pip-_MgdKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:45 INFO - Running setup.py (path:/tmp/pip-s1A1ZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:45 INFO - Running setup.py (path:/tmp/pip-SwK2F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:45 INFO - Running setup.py (path:/tmp/pip-MEGL4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:45 INFO - Running setup.py (path:/tmp/pip-KFDlCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:45 INFO - Running setup.py (path:/tmp/pip-V4RI1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:45 INFO - Running setup.py (path:/tmp/pip-J4kiRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:45 INFO - Running setup.py (path:/tmp/pip-7KjdD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:45 INFO - Running setup.py (path:/tmp/pip-kMoW6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:45 INFO - Running setup.py (path:/tmp/pip-Vc0pTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:45 INFO - Running setup.py (path:/tmp/pip-5K5JEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:45 INFO - Running setup.py (path:/tmp/pip-DXvJ_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:45 INFO - Running setup.py (path:/tmp/pip-tFWOWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:45 INFO - Running setup.py (path:/tmp/pip-pOEQsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:45 INFO - Running setup.py (path:/tmp/pip-3GhcRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:46 INFO - Running setup.py (path:/tmp/pip-GL53mV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:46 INFO - Running setup.py (path:/tmp/pip-9241Yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:16:46 INFO - Running setup.py install for manifestparser 07:16:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Running setup.py install for mozcrash 07:16:46 INFO - Running setup.py install for mozdebug 07:16:46 INFO - Running setup.py install for mozdevice 07:16:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Running setup.py install for mozfile 07:16:46 INFO - Running setup.py install for mozhttpd 07:16:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Running setup.py install for mozinfo 07:16:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Running setup.py install for mozInstall 07:16:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Running setup.py install for mozleak 07:16:47 INFO - Running setup.py install for mozlog 07:16:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Running setup.py install for moznetwork 07:16:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Running setup.py install for mozprocess 07:16:47 INFO - Running setup.py install for mozprofile 07:16:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Running setup.py install for mozrunner 07:16:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Running setup.py install for mozscreenshot 07:16:47 INFO - Running setup.py install for moztest 07:16:47 INFO - Running setup.py install for mozversion 07:16:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:16:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:16:47 INFO - Cleaning up... 07:16:47 INFO - Return code: 0 07:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:47 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:47 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 07:16:47 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 07:16:47 INFO - Using env: {'DISPLAY': ':0', 07:16:47 INFO - 'HOME': '/home/cltbld', 07:16:47 INFO - 'LANG': 'en_US.UTF-8', 07:16:47 INFO - 'LANGUAGE': 'en_US:en', 07:16:47 INFO - 'LOGNAME': 'cltbld', 07:16:47 INFO - 'MAIL': '/var/mail/cltbld', 07:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:47 INFO - 'MOZ_NO_REMOTE': '1', 07:16:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:47 INFO - 'NO_EM_RESTART': '1', 07:16:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:47 INFO - 'PWD': '/builds/slave/test', 07:16:47 INFO - 'SHELL': '/bin/bash', 07:16:47 INFO - 'SHLVL': '1', 07:16:47 INFO - 'TERM': 'linux', 07:16:47 INFO - 'TMOUT': '86400', 07:16:47 INFO - 'USER': 'cltbld', 07:16:47 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:47 INFO - '_': '/tools/buildbot/bin/python'} 07:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:48 INFO - Running setup.py (path:/tmp/pip-Xp6Jer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:48 INFO - Running setup.py (path:/tmp/pip-4abrxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:48 INFO - Running setup.py (path:/tmp/pip-PKIliK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:48 INFO - Running setup.py (path:/tmp/pip-oyXEA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:48 INFO - Running setup.py (path:/tmp/pip-pal4Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:48 INFO - Running setup.py (path:/tmp/pip-IJkffE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:48 INFO - Running setup.py (path:/tmp/pip-hyk9Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:48 INFO - Running setup.py (path:/tmp/pip-xe8a_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:48 INFO - Running setup.py (path:/tmp/pip-w_ckGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:48 INFO - Running setup.py (path:/tmp/pip-asSbsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:48 INFO - Running setup.py (path:/tmp/pip-DDBACr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:49 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)) 07:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:49 INFO - Running setup.py (path:/tmp/pip-O6ob8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:49 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)) 07:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:49 INFO - Running setup.py (path:/tmp/pip-kDCADT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:49 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)) 07:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:49 INFO - Running setup.py (path:/tmp/pip-tjj6Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:49 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)) 07:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:49 INFO - Running setup.py (path:/tmp/pip-V4PQW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:49 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)) 07:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:49 INFO - Running setup.py (path:/tmp/pip-YJh1s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:49 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)) 07:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:49 INFO - Running setup.py (path:/tmp/pip-mPd2jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:49 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)) 07:16:49 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)) 07:16:49 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)) 07:16:49 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)) 07:16:49 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)) 07:16:49 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)) 07:16:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:51 INFO - Downloading blessings-1.6.tar.gz 07:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:16:51 INFO - Installing collected packages: blessings 07:16:51 INFO - Running setup.py install for blessings 07:16:51 INFO - Successfully installed blessings 07:16:51 INFO - Cleaning up... 07:16:51 INFO - Return code: 0 07:16:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:51 INFO - Reading from file tmpfile_stdout 07:16:51 INFO - Current package versions: 07:16:51 INFO - argparse == 1.2.1 07:16:51 INFO - blessings == 1.6 07:16:51 INFO - blobuploader == 1.2.4 07:16:51 INFO - docopt == 0.6.1 07:16:51 INFO - functools32 == 3.2.3-2 07:16:51 INFO - jsonschema == 2.5.1 07:16:51 INFO - manifestparser == 1.1 07:16:51 INFO - mozInstall == 1.12 07:16:51 INFO - mozcrash == 0.17 07:16:51 INFO - mozdebug == 0.1 07:16:51 INFO - mozdevice == 0.48 07:16:51 INFO - mozfile == 1.2 07:16:51 INFO - mozhttpd == 0.7 07:16:51 INFO - mozinfo == 0.9 07:16:51 INFO - mozleak == 0.1 07:16:51 INFO - mozlog == 3.2 07:16:51 INFO - moznetwork == 0.27 07:16:51 INFO - mozprocess == 0.22 07:16:51 INFO - mozprofile == 0.28 07:16:51 INFO - mozrunner == 6.11 07:16:51 INFO - mozscreenshot == 0.1 07:16:51 INFO - mozsystemmonitor == 0.3 07:16:51 INFO - moztest == 0.7 07:16:51 INFO - mozversion == 1.4 07:16:51 INFO - psutil == 3.1.1 07:16:51 INFO - requests == 1.2.3 07:16:51 INFO - wsgiref == 0.1.2 07:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:51 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 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:51 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 07:16:51 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 07:16:51 INFO - Using env: {'DISPLAY': ':0', 07:16:51 INFO - 'HOME': '/home/cltbld', 07:16:51 INFO - 'LANG': 'en_US.UTF-8', 07:16:51 INFO - 'LANGUAGE': 'en_US:en', 07:16:51 INFO - 'LOGNAME': 'cltbld', 07:16:51 INFO - 'MAIL': '/var/mail/cltbld', 07:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:51 INFO - 'MOZ_NO_REMOTE': '1', 07:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:51 INFO - 'NO_EM_RESTART': '1', 07:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:51 INFO - 'PWD': '/builds/slave/test', 07:16:51 INFO - 'SHELL': '/bin/bash', 07:16:51 INFO - 'SHLVL': '1', 07:16:51 INFO - 'TERM': 'linux', 07:16:51 INFO - 'TMOUT': '86400', 07:16:51 INFO - 'USER': 'cltbld', 07:16:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:51 INFO - '_': '/tools/buildbot/bin/python'} 07:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 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)) 07:16:52 INFO - Cleaning up... 07:16:52 INFO - Return code: 0 07:16:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:16:52 INFO - Using env: {'DISPLAY': ':0', 07:16:52 INFO - 'HOME': '/home/cltbld', 07:16:52 INFO - 'LANG': 'en_US.UTF-8', 07:16:52 INFO - 'LANGUAGE': 'en_US:en', 07:16:52 INFO - 'LOGNAME': 'cltbld', 07:16:52 INFO - 'MAIL': '/var/mail/cltbld', 07:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:52 INFO - 'MOZ_NO_REMOTE': '1', 07:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:52 INFO - 'NO_EM_RESTART': '1', 07:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:52 INFO - 'PWD': '/builds/slave/test', 07:16:52 INFO - 'SHELL': '/bin/bash', 07:16:52 INFO - 'SHLVL': '1', 07:16:52 INFO - 'TERM': 'linux', 07:16:52 INFO - 'TMOUT': '86400', 07:16:52 INFO - 'USER': 'cltbld', 07:16:52 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:52 INFO - '_': '/tools/buildbot/bin/python'} 07:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:16:52 INFO - Cleaning up... 07:16:52 INFO - Return code: 0 07:16:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc4c74af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, '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': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', '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 07:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:16:52 INFO - Using env: {'DISPLAY': ':0', 07:16:52 INFO - 'HOME': '/home/cltbld', 07:16:52 INFO - 'LANG': 'en_US.UTF-8', 07:16:52 INFO - 'LANGUAGE': 'en_US:en', 07:16:52 INFO - 'LOGNAME': 'cltbld', 07:16:52 INFO - 'MAIL': '/var/mail/cltbld', 07:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:52 INFO - 'MOZ_NO_REMOTE': '1', 07:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:52 INFO - 'NO_EM_RESTART': '1', 07:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:52 INFO - 'PWD': '/builds/slave/test', 07:16:52 INFO - 'SHELL': '/bin/bash', 07:16:52 INFO - 'SHLVL': '1', 07:16:52 INFO - 'TERM': 'linux', 07:16:52 INFO - 'TMOUT': '86400', 07:16:52 INFO - 'USER': 'cltbld', 07:16:52 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:52 INFO - '_': '/tools/buildbot/bin/python'} 07:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:52 INFO - Downloading/unpacking flake8 07:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:16:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:16:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:16:55 INFO - no previously-included directories found matching 'docs/_build' 07:16:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:16:55 INFO - Running setup.py install for pep8 07:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:16:55 INFO - no previously-included directories found matching 'docs/_build' 07:16:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:16:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:16:55 INFO - Cleaning up... 07:16:55 INFO - Return code: 0 07:16:55 INFO - Running post-action listener: _resource_record_post_action 07:16:55 INFO - Running post-action listener: _start_resource_monitoring 07:16:55 INFO - Starting resource monitoring. 07:16:55 INFO - ##### 07:16:55 INFO - ##### Finished create-virtualenv step (success) 07:16:55 INFO - ##### 07:16:55 INFO - ##### 07:16:55 INFO - ##### Running install step. 07:16:55 INFO - ##### 07:16:55 INFO - Running pre-action listener: _resource_record_pre_action 07:16:55 INFO - Running main action method: install 07:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:55 INFO - Reading from file tmpfile_stdout 07:16:55 INFO - Detecting whether we're running mozinstall >=1.0... 07:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:16:55 INFO - Reading from file tmpfile_stdout 07:16:55 INFO - Output received: 07:16:55 INFO - Usage: mozinstall [options] installer 07:16:55 INFO - Options: 07:16:55 INFO - -h, --help show this help message and exit 07:16:55 INFO - -d DEST, --destination=DEST 07:16:55 INFO - Directory to install application into. [default: 07:16:55 INFO - "/builds/slave/test"] 07:16:55 INFO - --app=APP Application being installed. [default: firefox] 07:16:55 INFO - mkdir: /builds/slave/test/build/application 07:16:55 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'] 07:16:55 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 07:17:11 INFO - Reading from file tmpfile_stdout 07:17:11 INFO - Output received: 07:17:11 INFO - /builds/slave/test/build/application/firefox/firefox 07:17:11 INFO - Running post-action listener: _resource_record_post_action 07:17:11 INFO - ##### 07:17:11 INFO - ##### Finished install step (success) 07:17:11 INFO - ##### 07:17:11 INFO - ##### 07:17:11 INFO - ##### Running run-tests step. 07:17:11 INFO - ##### 07:17:11 INFO - Running pre-action listener: _resource_record_pre_action 07:17:11 INFO - Running main action method: run_tests 07:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:17:11 INFO - Python 2.7.3 07:17:11 INFO - Return code: 0 07:17:11 INFO - grabbing minidump binary from tooltool 07:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:11 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 0xe3f1c0>, '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 0xe400c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe40550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:17:11 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 07:17:11 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 07:17:11 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 07:17:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:17:11 INFO - Return code: 0 07:17:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:17:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:17:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:17:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:17:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:17:12 INFO - Return code: 0 07:17:12 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/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 07:17:12 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/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 07:17:12 INFO - Using env: {'DISPLAY': ':0', 07:17:12 INFO - 'HOME': '/home/cltbld', 07:17:12 INFO - 'LANG': 'en_US.UTF-8', 07:17:12 INFO - 'LANGUAGE': 'en_US:en', 07:17:12 INFO - 'LOGNAME': 'cltbld', 07:17:12 INFO - 'MAIL': '/var/mail/cltbld', 07:17:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:17:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:12 INFO - 'MOZ_NO_REMOTE': '1', 07:17:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:17:12 INFO - 'NO_EM_RESTART': '1', 07:17:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:12 INFO - 'PWD': '/builds/slave/test', 07:17:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:17:12 INFO - 'SHELL': '/bin/bash', 07:17:12 INFO - 'SHLVL': '1', 07:17:12 INFO - 'TERM': 'linux', 07:17:12 INFO - 'TMOUT': '86400', 07:17:12 INFO - 'USER': 'cltbld', 07:17:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864', 07:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:12 INFO - '_': '/tools/buildbot/bin/python'} 07:17:12 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/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 07:17:13 INFO - mozversion application_buildid: 20160623055826 07:17:13 INFO - mozversion application_changeset: d1102663db10b3d4b9358f3cf4e16b7c56902352 07:17:13 INFO - mozversion application_display_name: Nightly 07:17:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:17:13 INFO - mozversion application_name: Firefox 07:17:13 INFO - mozversion application_remotingname: firefox 07:17:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:17:13 INFO - mozversion application_vendor: Mozilla 07:17:13 INFO - mozversion application_version: 50.0a1 07:17:13 INFO - mozversion platform_buildid: 20160623055826 07:17:13 INFO - mozversion platform_changeset: d1102663db10b3d4b9358f3cf4e16b7c56902352 07:17:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:17:13 INFO - mozversion platform_version: 50.0a1 07:17:13 INFO - using testdate: 1466691433 07:17:13 INFO - actual date: 1466691433 07:17:13 INFO - starting webserver on 'localhost:38933' 07:17:13 INFO - SUITE-START | Running 2 tests 07:17:13 INFO - TEST-START | dromaeo_css 07:17:13 INFO - operating with platform_type : linux_ 07:17:13 INFO - Initialising browser for dromaeo_css test... 07:17:13 INFO - TEST-INFO | started process 3400 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCDo0js/profile http://localhost:38933/getInfo.html) 07:17:21 INFO - PROCESS | 3400 | __metrics Screen width/height:1600/1200 07:17:21 INFO - PROCESS | 3400 | colorDepth:24 07:17:21 INFO - PROCESS | 3400 | Browser inner width/height: 1024/697 07:17:21 INFO - PROCESS | 3400 | __metrics 07:17:22 INFO - PROCESS | 3400 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:17:23 INFO - TEST-INFO | 3400: exit 0 07:17:23 INFO - Browser initialized. 07:17:23 INFO - Running cycle 1/1 for dromaeo_css test... 07:17:23 INFO - TEST-INFO | started process 3486 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCDo0js/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:17:35 INFO - PROCESS | 3486 | RSS: Main: 175874048 07:17:35 INFO - PROCESS | 3486 | 07:18:26 INFO - PROCESS | 3486 | 1466691506908 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] 07:20:40 INFO - PROCESS | 3486 | Cycle 1(1): loaded http://localhost:38933/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38933/tests/dromaeo/cssquery-ext.html) 07:20:41 INFO - PROCESS | 3486 | RSS: Main: 260825088 07:20:41 INFO - PROCESS | 3486 | 07:23:43 INFO - PROCESS | 3486 | Cycle 1(1): loaded http://localhost:38933/tests/dromaeo/cssquery-ext.html (next: http://localhost:38933/tests/dromaeo/cssquery-jquery.html) 07:23:44 INFO - PROCESS | 3486 | RSS: Main: 263380992 07:23:44 INFO - PROCESS | 3486 | 07:26:46 INFO - PROCESS | 3486 | Cycle 1(1): loaded http://localhost:38933/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38933/tests/dromaeo/cssquery-mootools.html) 07:26:47 INFO - PROCESS | 3486 | RSS: Main: 262836224 07:26:47 INFO - PROCESS | 3486 | 07:29:49 INFO - PROCESS | 3486 | Cycle 1(1): loaded http://localhost:38933/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38933/tests/dromaeo/cssquery-prototype.html) 07:29:50 INFO - PROCESS | 3486 | RSS: Main: 259682304 07:29:50 INFO - PROCESS | 3486 | 07:32:53 INFO - PROCESS | 3486 | Cycle 1(1): loaded http://localhost:38933/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38933/tests/dromaeo/cssquery-yui.html) 07:32:53 INFO - PROCESS | 3486 | RSS: Main: 239136768 07:32:53 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | Cycle 1(1): loaded http://localhost:38933/tests/dromaeo/cssquery-yui.html (next: http://localhost:38933/tests/dromaeo/cssquery-dojo.html) 07:35:56 INFO - PROCESS | 3486 | RSS: Main: 294735872 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | __start_tp_report 07:35:56 INFO - PROCESS | 3486 | _x_x_mozilla_page_load 07:35:56 INFO - PROCESS | 3486 | _x_x_mozilla_page_load_details 07:35:56 INFO - PROCESS | 3486 | |i|pagename|runs| 07:35:56 INFO - PROCESS | 3486 | |0;dojo.html;2655.0441840937265;2660.0822716162943;2690.475357305326;2691.3002619318972;2704.7971402144844;4696.694714843541;4749.90500189996;4757.524247575241;4842.636802239833;4905.779239934202;4758.262469317261;4765.976170119153;4771.90456190876;4784.449788274358;4801.543853333937;4182.184474027566;4318.8488402905905;4422.97788511057;4444.622207112383;4548.113117942003;4444.799984000711;4482.955170448303;4500.549945005506;4511.323301504768;4529.23003089474;4218.325067989107;4251.893702657459;4255.829766809324;4302;4331.7834108294455;4333.414988976511;4335.176316499855;4338.631216346615;4363.607275345203;4448.021435284232;4384.495782984965;4394.802233899451;4402.295632698753;4425.955740442587;4441.578050085237;4645.372874661911;4759.286107083965;4816.662833601649;4825.396825396825;4880.975595122037;5145.614078944094;5242.842714718527;5248.029114613771;5251.054810134175;5252.2384254282915;5224.712640804754;5263.0526505229045;5268.973655131738;5277.525022747971;5288.153895376721;4692.319613656001;4693.155232058262;4757.762111894392;4830.8792280193275;4890.144224760653;5265.473452654781;5327.3074500314715;5338.813141540028;5342.465753424704;5345.599080069011;5061.696298222045;5069.695818250917;5120.846374608767;5123.692578445305;5126.948730512721;4965.1311020571975;5105.846824595268;5124.590032797368;5125.56432703217;5131.640785145079;5100.285959965608;5111.795528178831;5119.6416250862085;5120.558281019378;5120.769565369567;5101.795928162906;5103.642745007888;5109.412417572027;5111.258867464197;5147.408048074444;4279.293916503804;4313.439252897103;4315.395844581761;4359.498657654411;4362.367456718822;3826.770393776382;3833.9424908626393;3845.0195200223766;3862.3627101528496;3865.4974853268895;4320.373545835836;4357.3899654048455;4370.278903980808;4370.475542934804;4378.8905277367685;5191.169412893994;5191.974040129775;5196;5230;5233.319668443078;4371.825126994884;4387.232234358974;4388.890277743082;4430.689851710414;4431.556844315607;4506.076254367847;4589.2657174851865;4637.1653102441205;4657.208274593259;4667.719936803803;4842;4844.636652251166;4845.055214233191;4878.243872199815;4884.438289596742;5089.847304580869;5100.821471248489;5158.123119069842;5159.071367153798;5162.716050617102;4630.027694184259;4787.06652202831;4802.951970480251;4827.106985207748;4853.0779151961015;5101.9489805101475;5116.948830511797;5119.180931051014;5125.948740512547;5173.948260517347;5167.354080739908;5182.041322355376;5183.740812959412;5187.481251874782;5190.40310364313;4839.104765618372;4896.094222568836;4921.458639549575;4944.678595891256;4976.2038073908;4949.529794669501;4977.701337919736;4980.402351717672;4983.6760610560195;5024.598032157492;4760.333553302609;4904.901901962012;4908.190148625436;4977.950220497749;4979.427365852974;5027.723475208899;5040.269160971712;5042.344495215599;5048.873778155576;5070.797168113381;4943.134951383566;4948.851534453972;4950.331705219749;4953.578945789504;5000.2499625056535 07:35:56 INFO - PROCESS | 3486 | |1;ext.html;556.2823957095347;570.9543236541077;578.196307133085;582.9650220986741;584.5674201091192;38351.808240958824;40344.39483407747;40427.1914561709;44222.33666494997;44266.557334426645;38377.61622383775;38816;38855.80572097136;38869.80565097178;38898.41652375217;7349;7722.111957124918;7834.177411371781;7838.608069596526;10136.13842823361;9947.05502977216;10362;10364.637237696683;10374.170066394709;10375.896241037606;10443;10488.37069775812;10488.370697758159;10494.108000819939;10497.68506944789;7558.73544425948;7779.8833017504785;7781.572013539253;10150.13723833475;10337;9906.20750339971;10321;10326.173906087495;10334;10338.22463315247;38674.41988370177;39040.21919561621;39048;39050.21899562021;39062.80468597667;2997.9850100749577;3172.5558421820965;3288.161518812711;3288.802671839673;3430.022443603586;29771.404571908657;29854.402911941856;29992.342803727268;30211.244718881982;30300.54549181764;3595.658412450839;3624.075860655516;3633.7100329383215;3657.030886814996;3723.2739615774935;265751;271917;273728;404162;404624;51986;52062.21906671403;52332;52385.23762386134;52643.210351844755;32574.837125814694;33807.66192338094;33886.152846179044;35455.29089418197;35591.11022224465;13860.445582176802;13943.930280348737;13982.230977296347;13991.30043497821;14002.299885005708;9849.753756156153;11101.44492775358;11363.886361136449;11374.772504550028;11607.825882611767;17611;17627.823721762874;17684;17716.379926702502;17763.20065597051;6521.869562608721;6631.966840165768;6653.401193892573;6743.898841517381;6831.248562658104;803.9155888631609;947.3984020147257;951.0014484790942;994.2046362909643;1009.7021378693303;26634.068456241737;26917.730822691916;27005.729942700713;27076.864615676797;27585.172444826687;942.7643089227693;957.0716405087054;974.8635191073255;1007.3552926127285;1031.6750223679517;14479.927600362142;14822.703545929022;14863.702725945637;15109.848901511064;15124.924375378272;325804;329022;329608;329888;330067;46886.531134688215;47261;47432.28851567229;47432.28851567229;47626.285605715944;1053.5522402978704;1078.4553800330782;1170.6722117807028;1212.0545974140184;1249.3378509390038;27147.457050858477;27800.049987503124;30173;30224;30591.388172235984;44238;44912.32631510465;45037.549624503336;45061.32408013948;45074;360972;361044;363773;363830;364593;29233.561496577993;38702;38997.415038775005;39012.60987390203;41615;42320;43501.56498435102;44046.77976610096;44585.77707111444;48784.75607621939;37050.81474592718;37419.43870841885;37875.62124378721;38083.61916380912;38375.42436863505;37203.62796372001;37248.81375593105;37270;37707.09807724978;38057.4291385635;35757.46363804597;36654.08364790795;36690.08274793151;36753;36766.63233367632;31268.68731312658;33990.49014264739;34432.139196520286;34492.48261276033;34718.82640586781;25826.483470330862;25927.611085834105;26098.347541311618;26128;26208.73791262063 07:35:56 INFO - PROCESS | 3486 | |2;jquery.html;361.51917949127653;363.7999100494727;368.9852405903764;373.1901773152259;378.8465671403081;15717.685646287082;16726.832731672694;17699.91150044248;17713.202905869235;17727.202275897565;16202.918985405056;16426.260818263177;17096.316147354122;17203.913980430112;17210.483685489424;5011.448740638527;5174.741262936857;5192.221166824968;5210.140326846066;5218.295530103444;3743.9251214975684;3967.642912137907;5128.128218202895;5831.650100993954;6192.81421557354;6218.160548325986;6224.190855188819;6240.563160578761;6248.937510624904;6254.968725156368;6054.545909056838;6136.723847426876;6208.751649933997;6235.937640623603;6241.28225254097;3383.5263063171174;4974.60203183748;5320.707361095138;5328.440513746078;5429.728513574306;19862.90068549648;20440.795592044185;20441.4889627759;20752.792472075238;20810.5837883243;1906.3613689414062;1924.0668275886185;1948.1136083082163;1984.1864835417555;2004.9097790599312;12755.681107972283;13311.267880266581;13316.400761965639;13405.262710550916;13579.796303055462;1771.601389687323;1798.5144011116925;1841.3739328628196;1854.2212270846278;1905.580772230121;68363;68389.65805170992;68655;69205.65397172981;70307.29692703059;9918.15695665862;10000.599976001024;10096.495175241209;10103.393796372242;10125.493725313705;13615.659608509866;13657.385417656229;13680.52118175879;13719.45122195101;13727.862721372858;7129.966154907509;7143.1428228612895;7148.571085734872;7284.417246620258;7296.379807716297;7144.357007869213;7194.064771579766;7201.099862517185;7346.33882950537;7381.966524686548;4312.266914624522;4375.365571992043;4376.439782727077;4387.407699960529;4477.820887164542;1630.6086539230498;1767.0827152539382;1772.1493683031958;1837.0559285899321;1845.2711179084185;811.6307080278459;840.9915900840913;854.598338780772;875.7941883657336;881.7575166829173;16029.759553606706;16850.410235641695;17042.403515876886;17207.483775486755;17419.303227870743;811.1563973467528;826.442156534936;837.1293854391487;861.440063958432;911.1981456318398;8006.13558039008;9638.759031024281;9657.275704322205;9733.367331123454;9908;388347;393465;394882;395144;395895;72094;74038.25961740314;74553;76721;79517;895.9148880856309;896.5114012863005;914.6067191107754;918.8116436130578;953.3898305084751;15741.606459838138;15765.29056192497;15876.92061539685;16015.599610009844;16335.346586136422;37558;37855.62144378521;38193.23613527769;38398.61601384062;38504.42243366408;52802.73598631982;54062.189067163235;54421;56096.719516402154;56741.43258567362;7693.49992250502;7755.689772409266;7760.728374506865;7878.566678832719;8020.358371330396;10876.673699789018;11771.705707357385;11864.347460889388;12170.330631815335;12317.137800353888;10655.20085993569;10733.838992415276;10920.50857711389;11052.889471105185;11179.552817887194;10634.73413164646;10657.733556661145;10779.784404312026;10857.40284284372;10916.399598022184;10775.461226938465;10929.344239345664;11129.944350278196;11209.15931305139;11356.659300221007;7735.729249476241;7817;7818.296353328228;7949.880751788844;8021.799455013671;9998.550065247227;10240.64157754475;10559.736006599896;10716.19628527848;10797.784044319227 07:35:56 INFO - PROCESS | 3486 | |3;mootools.html;1207.2515040674782;1365.822443082399;1398.9230592317422;1480.6594483268848;1694.5424735321456;6691.0967019452355;7600.163981961987;7840.921590784104;7861.449698521106;7887.566183859885;7632.96183519083;7761.689532418696;7764.340031097349;7778.883316750239;7778.8833167502535;2216.9889150554263;2242.4281808138903;2244.6408574628063;2249.1453247765908;2252.842301038928;2177.17267438374;2204.5370472200857;2210.4031911383995;2213.5904857601313;2219.2676416782506;2142.582196471689;2157.590057889004;2168.1652563762987;2168.5554461335387;2171.771963943787;2131.5630295789324;2133.5412886229537;2137.850350475467;2138.5188332625266;2138.550904310097;2101.71626830379;2138.4012476506605;2144.613969485492;2147.291393840029;2158.266189495563;7582.431317651143;7686.961565192194;7734.110577283628;7734.381249500027;7827.6868925242925;4527.388802511652;4642.187617166982;4650.395448591661;4658.18481765693;4662.673612847102;2835.957460638092;2915.4023425197784;2988.3126880817317;2991.2820922978544;2999.43010827942;962.3696478806393;979.9514519464176;990.4948476277078;993.3791380387258;998.7503124218945;10480.371177729361;10553.208509361828;10626.893731062668;10638.255322127454;10670.199735019905;4138.8551400700835;4163.250614889288;4223.092035212444;4241.300185469363;4249.2563801334645;8160.224778646019;8214.8357032859;8251.876221856677;8252.46358986664;8261.421700481029;4707.85876423701;4709.105269998689;4710.26990816424;4710.293455981561;4717.127331443694;3608.9278214435562;3747.5315585551807;4241.936370954438;4335.067960388532;4359.9782001090425;3500.0899766061007;3547;3617.2584620152807;3645.179834537261;3646.34365814156;1068.417712346773;1084.5715942202785;1096.2216826053432;1125.6004118537965;1128.5542210826677;760.3612965109335;766.252903419173;772.7102336623766;775;780.8594452998515;4898.951010489921;4925.926111108336;4936.111499929975;5019.222020586815;5026.396832380065;420.90108824426846;423.89402649337666;432.51774271686844;433.3672837656993;436.6964959353219;3235.724963378092;3376.375370556455;3382.0868365541164;3383.678550537695;3421.9657803422147;9546.093121153619;9895.703128906145;9921.603135874628;9926.851097233548;9926.950365248127;9430.858537121949;9570.95214523923;9591.664291749756;9605.47169905662;9620.13418792312;7302.926970730331;7360.153582337993;7361.263873612703;7379.852402951911;7383.741569045058;4656.534346565452;4783.234682450791;4788.856334309976;4809.398825146856;4840.249761287007;8622.568871556272;8812.91187088121;8921.509316987627;9008.954955225181;9096.954515227382;8958.283337332867;9059.954700226457;9109.817803644022;9119.635214591342;9148.817023659356;5668.26312579362;6101.206843110367;6226.283977342482;6265.122882796321;6297.55917085818;7465.477416580788;7471.215522370073;7481.326680598772;7532.962335188289;7537.133229678657;6514.446272066927;6736;6777.525573209828;6812.693428795617;6869.416099631588;6589.7364105435245;6636.402723754885;6644.800655980328;6761.492888033319;6843;5416.539594134542;5577.721113944368;5740.655560666373;5741;5745.454181852718;4344.174606824693;4352.73883566987;4408.823647054211;4489.595936365743;4509.391232183613;4451.065276292015;4476.149531588988;4490.461144662661;4526.683132180716;4554.772261386851 07:35:56 INFO - PROCESS | 3486 | |4;prototype.html;294.45084916630486;297.6710734638223;299.98950036748715;302.570350102854;304.6861732415611;4804.399450068741;4950.13372659784;4954.5788607968325;4956.4547899731;4973.602111831054;4859;4873.7806798694055;4888.706677599346;4914.262860570907;4924.335214746017;3620.637936206378;3723.7765734055906;3725;3853.190829925719;3890.3580909149955;3791.5260592425807;3819.6944244460515;3855;3866.3427217373114;3872.4965754451882;3654.6162652921457;3792.886213413602;3825.1448047696986;3834.482344883447;3836.366999445102;3734.495843061179;3793.279276937387;3798.3922572388287;3804.714646401503;3808.2383523295453;3362.663733626642;3363.9327213455595;3367.107716455141;3380.9830950845335;3396.1849156202334;4824.2040063389495;4840.201366774478;4842.491538388454;4877.439094504107;4884.534639608103;4251.829926802924;4349.869503914888;4380.386745855582;4400.933985990213;4421.336799480104;5096.51583099605;5098;5121.411037730634;5123.538881500645;5146.614003949681;3677.7057835373107;3706.184639379332;3707.4994875691705;3729.1982223821738;3741.4013757798893;5295.894082118374;5355.464453554652;5393.2449457076045;5429.837104886859;5452.863678408032;3765.1573062338307;3766.60450652683;3779.5259848659043;3794.5959994801547;3794.981025094912;3810.6760925321105;3816.236752649426;3838.1172330364175;3858.0354911272184;3859.1702783901596;4373.825046998148;4403.8458653946955;4404.493483249404;4406.096750166208;4420.911581768347;4930.630202734809;5084.3898732152375;5086.3133476980875;5097.847064588068;5133.332666753298;1313.8094976228394;1333.2134040915905;1335.1788649980385;1336.1982810313734;1344.2740919903338;851.5614458553849;869.3783944479601;908.9136532029447;932.1843387036344;943.2878176976338;4443.711323716087;4459.531749166291;4473.590818892038;4475.216837053567;4497.437820272466;5200.63595548308;5226.790928362823;5244.895102097937;5306.469353064663;5313.1764576490705;4264.616184543406;4404.163208990281;4427.645788336926;4472.351509031172;4487.596116349544;2802.1173330400857;2808.971910280871;2815.4509870575453;2826;2848.2167403963663;4958.380202474691;4958.9008219835405;4963.925541116886;4988;5004.84985450437;5154.329937108152;5477.342718873681;5490.995786537965;5501.504864562208;5509.47659972302;4971.701697898137;4997.150484417585;5007.173816320266;5018.673786203885;5026.447090820006;5278.472152784844;5305.151175811851;5340.252364668871;5371.516563509303;5375.569954403716;5148.851884660929;5200.635955483232;5244.449332819999;5252.345511163971;5277.313949186581;4925.729084900365;5014.272930424995;5033.245013247895;5056.6207534434325;5059;3782.867599634;3785.5268091488565;3791.4123310886857;3804.6575808177395;3813.427985802152;5218.765155567932;5363.731813409236;5383.16560933056;5400.83797486076;5416.729163541729;5146.485351464973;5218.356217791384;5230.215467679878;5231.90130072689;5241.423443421144;5202.453742356998;5203.661761985459;5207.218917162304;5208.713520756395;5209.583233341248;4876.1718203441205;4937.432195297587;5121.871953201051;5136.640435169502;5151.793928242978;5023.924641130313;5196.2725218470205;5242.775692948885;5260.290405618113;5260.39505456862;5125.718085505333;5137.691738495702;5141.588672906233;5145.331106956071;5147.1910786018825 07:35:56 INFO - PROCESS | 3486 | |5;yui.html;675.3044364304765;710.747684571977;721.6283613938825;731.6012773038693;769.3191525499931;12524.123311368208;13115.278659673713;13755.862441375582;13821.447142114315;13866.514671986482;11962.222455540406;11966.581169659064;12002.81995770062;12036.398180090982;12044.638660840166;620.4663988969482;762.4091329219843;779.1779672445582;794.6662401791261;797.5732982854174;786.4573444323427;797.9880301795478;798.1858504325585;798.3294033012269;799.5522507395848;827.4208054361941;832.9125441828612;833.3541505333385;833.4249367936135;834.0033659776569;647.7311915555039;801.7434420985287;824.9422540422173;827.6482494939627;828.3828547731948;604.5767962426319;604.9122877182784;604.948579370754;605.0923614578132;605.3462260758371;12067.155299129065;12113.33376664283;12133.575324863676;12134.696632584257;12147;11845.17083804134;12318.322492262922;12992.805107923388;13071.895424836575;13109.344532773322;11577.189596728233;11691.590794322157;11695.122865785015;11934.522619095225;12467.750644987049;4174.227767862955;4221.24017676818;4229.492460904711;4253.532111467736;4387.210302145612;55583;55692.72153639246;55716.72141639306;55753.72123139399;55944.44055559474;10273.897261027294;10299.845502317472;10494.527746251284;10542.209334299958;10589.205809564313;19087.713684294748;19108.808911910983;19227.80772192288;19233.134508947132;19266.518337041685;6890.069840571559;6928.411085057725;6943.6875340609795;7040.577565346096;7108.3957863581145;3803.201327721154;3825.732198746061;3880.165764360676;3889.0471834400755;3892.0464486201063;2890.566415037761;2971.821690698565;2985.4178435205363;2998.4902566563737;3000.1299623109053;493.6001838510818;499.65274134476914;501.7014876148686;508.47626944247486;513.6019584821793;418.8659628918717;497.65164384930694;573.4724053870449;589.8762856757883;633.6609913696151;8296;8521.147885211429;8536.74389768308;8639.136086391436;8665.220130188314;530.5490333216735;555.1145922253999;601.3565484931082;603.5171862510063;640.9102725618324;3886.3587508060855;3888.9611103889165;3929.8231579578987;3946.4474973503734;4087.7941007402887;91744.54127729453;93755.53122234344;94622;95545.52227238819;96715.51642241885;65117;66052;66671.66664166648;80317.19682803215;80458;77904.61047694726;78740.60629696815;79394;79683.60158199172;80037.59981200173;8379.70671026511;8596.91403085961;8725.389222754346;8725.912740872764;8838.248748856418;38570.87860167718;38704.41943370795;47942;48872.266915997;49236.261456077475;43130.56869431266;43170;61237.38762612317;61687.38312616816;64362.67818660877;9231.446113233227;9357.578908948977;9585.664501742685;11551.826722599;11741.765164696488;10654.200934929757;10703.625373111903;11738.354390508262;12155.088368372231;12418.565350212699;10958.671239862817;11039.227254092137;11128.221024528206;11559.364234967157;11758.470868810753;11584.94207528957;11600.30398176112;11916.285022898654;12002.579909703118;12056.939715301367;11086.833697494705;11118.777624447628;11137.777244455228;11184.2856221186;11239.325640461599;9962.551685174329;10268.845967310646;10339.84490232632;10379.06588407047;10645.520951557353;10007.299489035697;10226.897731022595;10265.640702575374;10276.486175691334;10317.9484102579 07:35:56 INFO - PROCESS | 3486 | __end_tp_report 07:35:56 INFO - PROCESS | 3486 | __start_cc_report 07:35:56 INFO - PROCESS | 3486 | _x_x_mozilla_cycle_collect,974 07:35:56 INFO - PROCESS | 3486 | __end_cc_report 07:35:56 INFO - PROCESS | 3486 | __startTimestamp1466692556749__endTimestamp 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | ------- Summary: start ------- 07:35:56 INFO - PROCESS | 3486 | Number of tests: 6 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | [#0] dojo.html Cycles:165 Average:4745.83 Median:4878.24 stddev:512.19 (10.5%) stddev-sans-first:486.79 07:35:56 INFO - PROCESS | 3486 | Values: 2655.0 2660.1 2690.5 2691.3 2704.8 4696.7 4749.9 4757.5 4842.6 4905.8 4758.3 4766.0 4771.9 4784.4 4801.5 4182.2 4318.8 4423.0 4444.6 4548.1 4444.8 4483.0 4500.5 4511.3 4529.2 4218.3 4251.9 4255.8 4302.0 4331.8 4333.4 4335.2 4338.6 4363.6 4448.0 4384.5 4394.8 4402.3 4426.0 4441.6 4645.4 4759.3 4816.7 4825.4 4881.0 5145.6 5242.8 5248.0 5251.1 5252.2 5224.7 5263.1 5269.0 5277.5 5288.2 4692.3 4693.2 4757.8 4830.9 4890.1 5265.5 5327.3 5338.8 5342.5 5345.6 5061.7 5069.7 5120.8 5123.7 5126.9 4965.1 5105.8 5124.6 5125.6 5131.6 5100.3 5111.8 5119.6 5120.6 5120.8 5101.8 5103.6 5109.4 5111.3 5147.4 4279.3 4313.4 4315.4 4359.5 4362.4 3826.8 3833.9 3845.0 3862.4 3865.5 4320.4 4357.4 4370.3 4370.5 4378.9 5191.2 5192.0 5196.0 5230.0 5233.3 4371.8 4387.2 4388.9 4430.7 4431.6 4506.1 4589.3 4637.2 4657.2 4667.7 4842.0 4844.6 4845.1 4878.2 4884.4 5089.8 5100.8 5158.1 5159.1 5162.7 4630.0 4787.1 4803.0 4827.1 4853.1 5101.9 5116.9 5119.2 5125.9 5173.9 5167.4 5182.0 5183.7 5187.5 5190.4 4839.1 4896.1 4921.5 4944.7 4976.2 4949.5 4977.7 4980.4 4983.7 5024.6 4760.3 4904.9 4908.2 4978.0 4979.4 5027.7 5040.3 5042.3 5048.9 5070.8 4943.1 4948.9 4950.3 4953.6 5000.2 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | [#1] ext.html Cycles:180 Average:49346.36 Median:182334.04 stddev:89640.74 (49.2%) stddev-sans-first:89817.35 07:35:56 INFO - PROCESS | 3486 | Values: 556.3 571.0 578.2 583.0 584.6 38351.8 40344.4 40427.2 44222.3 44266.6 38377.6 38816.0 38855.8 38869.8 38898.4 7349.0 7722.1 7834.2 7838.6 10136.1 9947.1 10362.0 10364.6 10374.2 10375.9 10443.0 10488.4 10488.4 10494.1 10497.7 7558.7 7779.9 7781.6 10150.1 10337.0 9906.2 10321.0 10326.2 10334.0 10338.2 38674.4 39040.2 39048.0 39050.2 39062.8 2998.0 3172.6 3288.2 3288.8 3430.0 29771.4 29854.4 29992.3 30211.2 30300.5 3595.7 3624.1 3633.7 3657.0 3723.3 265751.0 271917.0 273728.0 404162.0 404624.0 51986.0 52062.2 52332.0 52385.2 52643.2 32574.8 33807.7 33886.2 35455.3 35591.1 13860.4 13943.9 13982.2 13991.3 14002.3 9849.8 11101.4 11363.9 11374.8 11607.8 17611.0 17627.8 17684.0 17716.4 17763.2 6521.9 6632.0 6653.4 6743.9 6831.2 803.9 947.4 951.0 994.2 1009.7 26634.1 26917.7 27005.7 27076.9 27585.2 942.8 957.1 974.9 1007.4 1031.7 14479.9 14822.7 14863.7 15109.8 15124.9 325804.0 329022.0 329608.0 329888.0 330067.0 46886.5 47261.0 47432.3 47432.3 47626.3 1053.6 1078.5 1170.7 1212.1 1249.3 27147.5 27800.0 30173.0 30224.0 30591.4 44238.0 44912.3 45037.5 45061.3 45074.0 360972.0 361044.0 363773.0 363830.0 364593.0 29233.6 38702.0 38997.4 39012.6 41615.0 42320.0 43501.6 44046.8 44585.8 48784.8 37050.8 37419.4 37875.6 38083.6 38375.4 37203.6 37248.8 37270.0 37707.1 38057.4 35757.5 36654.1 36690.1 36753.0 36766.6 31268.7 33990.5 34432.1 34492.5 34718.8 25826.5 25927.6 26098.3 26128.0 26208.7 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | [#2] jquery.html Cycles:180 Average:24722.99 Median:390906.00 stddev:64923.70 (16.6%) stddev-sans-first:65080.06 07:35:56 INFO - PROCESS | 3486 | Values: 361.5 363.8 369.0 373.2 378.8 15717.7 16726.8 17699.9 17713.2 17727.2 16202.9 16426.3 17096.3 17203.9 17210.5 5011.4 5174.7 5192.2 5210.1 5218.3 3743.9 3967.6 5128.1 5831.7 6192.8 6218.2 6224.2 6240.6 6248.9 6255.0 6054.5 6136.7 6208.8 6235.9 6241.3 3383.5 4974.6 5320.7 5328.4 5429.7 19862.9 20440.8 20441.5 20752.8 20810.6 1906.4 1924.1 1948.1 1984.2 2004.9 12755.7 13311.3 13316.4 13405.3 13579.8 1771.6 1798.5 1841.4 1854.2 1905.6 68363.0 68389.7 68655.0 69205.7 70307.3 9918.2 10000.6 10096.5 10103.4 10125.5 13615.7 13657.4 13680.5 13719.5 13727.9 7130.0 7143.1 7148.6 7284.4 7296.4 7144.4 7194.1 7201.1 7346.3 7382.0 4312.3 4375.4 4376.4 4387.4 4477.8 1630.6 1767.1 1772.1 1837.1 1845.3 811.6 841.0 854.6 875.8 881.8 16029.8 16850.4 17042.4 17207.5 17419.3 811.2 826.4 837.1 861.4 911.2 8006.1 9638.8 9657.3 9733.4 9908.0 388347.0 393465.0 394882.0 395144.0 395895.0 72094.0 74038.3 74553.0 76721.0 79517.0 895.9 896.5 914.6 918.8 953.4 15741.6 15765.3 15876.9 16015.6 16335.3 37558.0 37855.6 38193.2 38398.6 38504.4 52802.7 54062.2 54421.0 56096.7 56741.4 7693.5 7755.7 7760.7 7878.6 8020.4 10876.7 11771.7 11864.3 12170.3 12317.1 10655.2 10733.8 10920.5 11052.9 11179.6 10634.7 10657.7 10779.8 10857.4 10916.4 10775.5 10929.3 11129.9 11209.2 11356.7 7735.7 7817.0 7818.3 7949.9 8021.8 9998.6 10240.6 10559.7 10716.2 10797.8 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | [#3] mootools.html Cycles:180 Average:5028.77 Median:4750.18 stddev:2861.64 (60.2%) stddev-sans-first:2855.26 07:35:56 INFO - PROCESS | 3486 | Values: 1207.3 1365.8 1398.9 1480.7 1694.5 6691.1 7600.2 7840.9 7861.4 7887.6 7633.0 7761.7 7764.3 7778.9 7778.9 2217.0 2242.4 2244.6 2249.1 2252.8 2177.2 2204.5 2210.4 2213.6 2219.3 2142.6 2157.6 2168.2 2168.6 2171.8 2131.6 2133.5 2137.9 2138.5 2138.6 2101.7 2138.4 2144.6 2147.3 2158.3 7582.4 7687.0 7734.1 7734.4 7827.7 4527.4 4642.2 4650.4 4658.2 4662.7 2836.0 2915.4 2988.3 2991.3 2999.4 962.4 980.0 990.5 993.4 998.8 10480.4 10553.2 10626.9 10638.3 10670.2 4138.9 4163.3 4223.1 4241.3 4249.3 8160.2 8214.8 8251.9 8252.5 8261.4 4707.9 4709.1 4710.3 4710.3 4717.1 3608.9 3747.5 4241.9 4335.1 4360.0 3500.1 3547.0 3617.3 3645.2 3646.3 1068.4 1084.6 1096.2 1125.6 1128.6 760.4 766.3 772.7 775.0 780.9 4899.0 4925.9 4936.1 5019.2 5026.4 420.9 423.9 432.5 433.4 436.7 3235.7 3376.4 3382.1 3383.7 3422.0 9546.1 9895.7 9921.6 9926.9 9927.0 9430.9 9571.0 9591.7 9605.5 9620.1 7302.9 7360.2 7361.3 7379.9 7383.7 4656.5 4783.2 4788.9 4809.4 4840.2 8622.6 8812.9 8921.5 9009.0 9097.0 8958.3 9060.0 9109.8 9119.6 9148.8 5668.3 6101.2 6226.3 6265.1 6297.6 7465.5 7471.2 7481.3 7533.0 7537.1 6514.4 6736.0 6777.5 6812.7 6869.4 6589.7 6636.4 6644.8 6761.5 6843.0 5416.5 5577.7 5740.7 5741.0 5745.5 4344.2 4352.7 4408.8 4489.6 4509.4 4451.1 4476.1 4490.5 4526.7 4554.8 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | [#4] prototype.html Cycles:180 Average:4286.60 Median:4919.30 stddev:1245.79 (25.3%) stddev-sans-first:1212.72 07:35:56 INFO - PROCESS | 3486 | Values: 294.5 297.7 300.0 302.6 304.7 4804.4 4950.1 4954.6 4956.5 4973.6 4859.0 4873.8 4888.7 4914.3 4924.3 3620.6 3723.8 3725.0 3853.2 3890.4 3791.5 3819.7 3855.0 3866.3 3872.5 3654.6 3792.9 3825.1 3834.5 3836.4 3734.5 3793.3 3798.4 3804.7 3808.2 3362.7 3363.9 3367.1 3381.0 3396.2 4824.2 4840.2 4842.5 4877.4 4884.5 4251.8 4349.9 4380.4 4400.9 4421.3 5096.5 5098.0 5121.4 5123.5 5146.6 3677.7 3706.2 3707.5 3729.2 3741.4 5295.9 5355.5 5393.2 5429.8 5452.9 3765.2 3766.6 3779.5 3794.6 3795.0 3810.7 3816.2 3838.1 3858.0 3859.2 4373.8 4403.8 4404.5 4406.1 4420.9 4930.6 5084.4 5086.3 5097.8 5133.3 1313.8 1333.2 1335.2 1336.2 1344.3 851.6 869.4 908.9 932.2 943.3 4443.7 4459.5 4473.6 4475.2 4497.4 5200.6 5226.8 5244.9 5306.5 5313.2 4264.6 4404.2 4427.6 4472.4 4487.6 2802.1 2809.0 2815.5 2826.0 2848.2 4958.4 4958.9 4963.9 4988.0 5004.8 5154.3 5477.3 5491.0 5501.5 5509.5 4971.7 4997.2 5007.2 5018.7 5026.4 5278.5 5305.2 5340.3 5371.5 5375.6 5148.9 5200.6 5244.4 5252.3 5277.3 4925.7 5014.3 5033.2 5056.6 5059.0 3782.9 3785.5 3791.4 3804.7 3813.4 5218.8 5363.7 5383.2 5400.8 5416.7 5146.5 5218.4 5230.2 5231.9 5241.4 5202.5 5203.7 5207.2 5208.7 5209.6 4876.2 4937.4 5121.9 5136.6 5151.8 5023.9 5196.3 5242.8 5260.3 5260.4 5125.7 5137.7 5141.6 5145.3 5147.2 07:35:56 INFO - PROCESS | 3486 | 07:35:56 INFO - PROCESS | 3486 | [#5] yui.html Cycles:180 Average:17059.35 Median:26164.61 stddev:23914.70 (91.4%) stddev-sans-first:23950.14 07:35:56 INFO - PROCESS | 3486 | Values: 675.3 710.7 721.6 731.6 769.3 12524.1 13115.3 13755.9 13821.4 13866.5 11962.2 11966.6 12002.8 12036.4 12044.6 620.5 762.4 779.2 794.7 797.6 786.5 798.0 798.2 798.3 799.6 827.4 832.9 833.4 833.4 834.0 647.7 801.7 824.9 827.6 828.4 604.6 604.9 604.9 605.1 605.3 12067.2 12113.3 12133.6 12134.7 12147.0 11845.2 12318.3 12992.8 13071.9 13109.3 11577.2 11691.6 11695.1 11934.5 12467.8 4174.2 4221.2 4229.5 4253.5 4387.2 55583.0 55692.7 55716.7 55753.7 55944.4 10273.9 10299.8 10494.5 10542.2 10589.2 19087.7 19108.8 19227.8 19233.1 19266.5 6890.1 6928.4 6943.7 7040.6 7108.4 3803.2 3825.7 3880.2 3889.0 3892.0 2890.6 2971.8 2985.4 2998.5 3000.1 493.6 499.7 501.7 508.5 513.6 418.9 497.7 573.5 589.9 633.7 8296.0 8521.1 8536.7 8639.1 8665.2 530.5 555.1 601.4 603.5 640.9 3886.4 3889.0 3929.8 3946.4 4087.8 91744.5 93755.5 94622.0 95545.5 96715.5 65117.0 66052.0 66671.7 80317.2 80458.0 77904.6 78740.6 79394.0 79683.6 80037.6 8379.7 8596.9 8725.4 8725.9 8838.2 38570.9 38704.4 47942.0 48872.3 49236.3 43130.6 43170.0 61237.4 61687.4 64362.7 9231.4 9357.6 9585.7 11551.8 11741.8 10654.2 10703.6 11738.4 12155.1 12418.6 10958.7 11039.2 11128.2 11559.4 11758.5 11584.9 11600.3 11916.3 12002.6 12056.9 11086.8 11118.8 11137.8 11184.3 11239.3 9962.6 10268.8 10339.8 10379.1 10645.5 10007.3 10226.9 10265.6 10276.5 10317.9 07:35:56 INFO - PROCESS | 3486 | -------- Summary: end -------- 07:35:56 INFO - PROCESS | 3486 | 07:35:57 INFO - TEST-INFO | 3486: exit 0 07:35:57 INFO - TEST-OK | dromaeo_css | took 1124384ms 07:35:57 INFO - TEST-START | kraken 07:35:57 INFO - operating with platform_type : linux_ 07:35:57 INFO - Initialising browser for kraken test... 07:35:57 INFO - TEST-INFO | started process 3671 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1BdwM9/profile http://localhost:38933/getInfo.html) 07:36:02 INFO - PROCESS | 3671 | __metrics Screen width/height:1600/1200 07:36:02 INFO - PROCESS | 3671 | colorDepth:24 07:36:02 INFO - PROCESS | 3671 | Browser inner width/height: 1024/697 07:36:02 INFO - PROCESS | 3671 | __metrics 07:36:03 INFO - PROCESS | 3671 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:36:04 INFO - TEST-INFO | 3671: exit 0 07:36:04 INFO - Browser initialized. 07:36:04 INFO - Running cycle 1/1 for kraken test... 07:36:04 INFO - TEST-INFO | started process 3752 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1BdwM9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:36:16 INFO - PROCESS | 3752 | RSS: Main: 176431104 07:36:16 INFO - PROCESS | 3752 | 07:36:19 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38933/tests/kraken/driver.html?testName=audio-beat-detection) 07:36:20 INFO - PROCESS | 3752 | RSS: Main: 234188800 07:36:20 INFO - PROCESS | 3752 | 07:36:23 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38933/tests/kraken/driver.html?testName=audio-dft) 07:36:23 INFO - PROCESS | 3752 | RSS: Main: 225071104 07:36:23 INFO - PROCESS | 3752 | 07:36:26 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38933/tests/kraken/driver.html?testName=audio-fft) 07:36:27 INFO - PROCESS | 3752 | RSS: Main: 289460224 07:36:27 INFO - PROCESS | 3752 | 07:36:29 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38933/tests/kraken/driver.html?testName=audio-oscillator) 07:36:30 INFO - PROCESS | 3752 | RSS: Main: 199290880 07:36:30 INFO - PROCESS | 3752 | 07:36:32 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38933/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:36:33 INFO - PROCESS | 3752 | RSS: Main: 203116544 07:36:33 INFO - PROCESS | 3752 | 07:36:36 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38933/tests/kraken/driver.html?testName=imaging-darkroom) 07:36:37 INFO - PROCESS | 3752 | RSS: Main: 239722496 07:36:37 INFO - PROCESS | 3752 | 07:36:40 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38933/tests/kraken/driver.html?testName=imaging-desaturate) 07:36:41 INFO - PROCESS | 3752 | RSS: Main: 235294720 07:36:41 INFO - PROCESS | 3752 | 07:36:44 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38933/tests/kraken/driver.html?testName=json-parse-financial) 07:36:44 INFO - PROCESS | 3752 | RSS: Main: 238071808 07:36:44 INFO - PROCESS | 3752 | 07:36:46 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38933/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:36:47 INFO - PROCESS | 3752 | RSS: Main: 226025472 07:36:47 INFO - PROCESS | 3752 | 07:36:48 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:36:49 INFO - PROCESS | 3752 | RSS: Main: 230510592 07:36:49 INFO - PROCESS | 3752 | 07:36:51 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:36:52 INFO - PROCESS | 3752 | RSS: Main: 238153728 07:36:52 INFO - PROCESS | 3752 | 07:36:56 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:36:56 INFO - PROCESS | 3752 | RSS: Main: 235110400 07:36:56 INFO - PROCESS | 3752 | 07:36:59 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:37:00 INFO - PROCESS | 3752 | RSS: Main: 237146112 07:37:00 INFO - PROCESS | 3752 | 07:37:01 INFO - PROCESS | 3752 | Cycle 1(1): loaded http://localhost:38933/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38933/tests/kraken/driver.html?testName=ai-astar) 07:37:02 INFO - PROCESS | 3752 | RSS: Main: 281092096 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | __start_tp_report 07:37:02 INFO - PROCESS | 3752 | _x_x_mozilla_page_load 07:37:02 INFO - PROCESS | 3752 | _x_x_mozilla_page_load_details 07:37:02 INFO - PROCESS | 3752 | |i|pagename|runs| 07:37:02 INFO - PROCESS | 3752 | |0;ai-astar;102;113;115;114;114;113;112;114;115;114 07:37:02 INFO - PROCESS | 3752 | |1;audio-beat-detection;159;136;131;193;131;132;158;133;188;131 07:37:02 INFO - PROCESS | 3752 | |2;audio-dft;163;160;160;161;160;161;161;162;162;161 07:37:02 INFO - PROCESS | 3752 | |3;audio-fft;80;75;75;125;76;76;75;75;98;76 07:37:02 INFO - PROCESS | 3752 | |4;audio-oscillator;86;86;118;86;86;86;126;85;87;127 07:37:02 INFO - PROCESS | 3752 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;116 07:37:02 INFO - PROCESS | 3752 | |6;imaging-darkroom;146;147;146;147;146;147;147;146;146;146 07:37:02 INFO - PROCESS | 3752 | |7;imaging-desaturate;95;96;95;95;95;95;95;96;95;94 07:37:02 INFO - PROCESS | 3752 | |8;json-parse-financial;66;65;66;64;64;64;65;64;64;63 07:37:02 INFO - PROCESS | 3752 | |9;json-stringify-tinderbox;65;65;65;65;66;65;66;66;65;64 07:37:02 INFO - PROCESS | 3752 | |10;stanford-crypto-aes;82;80;83;82;82;83;82;82;91;82 07:37:02 INFO - PROCESS | 3752 | |11;stanford-crypto-ccm;143;140;137;131;139;128;137;127;136;132 07:37:02 INFO - PROCESS | 3752 | |12;stanford-crypto-pbkdf2;168;168;170;169;170;165;166;166;165;169 07:37:02 INFO - PROCESS | 3752 | |13;stanford-crypto-sha256-iterative;67;66;67;66;67;67;67;73;65;66 07:37:02 INFO - PROCESS | 3752 | __end_tp_report 07:37:02 INFO - PROCESS | 3752 | __start_cc_report 07:37:02 INFO - PROCESS | 3752 | _x_x_mozilla_cycle_collect,1831 07:37:02 INFO - PROCESS | 3752 | __end_cc_report 07:37:02 INFO - PROCESS | 3752 | __startTimestamp1466692622198__endTimestamp 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | ------- Summary: start ------- 07:37:02 INFO - PROCESS | 3752 | Number of tests: 14 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.84 (3.4%) stddev-sans-first:0.97 07:37:02 INFO - PROCESS | 3752 | Values: 102.0 113.0 115.0 114.0 114.0 113.0 112.0 114.0 115.0 114.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#1] audio-beat-detection Cycles:10 Average:149.20 Median:147.00 stddev:24.32 (16.5%) stddev-sans-first:25.54 07:37:02 INFO - PROCESS | 3752 | Values: 159.0 136.0 131.0 193.0 131.0 132.0 158.0 133.0 188.0 131.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.78 07:37:02 INFO - PROCESS | 3752 | Values: 163.0 160.0 160.0 161.0 160.0 161.0 161.0 162.0 162.0 161.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.00 stddev:16.33 (21.5%) stddev-sans-first:17.29 07:37:02 INFO - PROCESS | 3752 | Values: 80.0 75.0 75.0 125.0 76.0 76.0 75.0 75.0 98.0 76.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:86.00 stddev:18.35 (21.3%) stddev-sans-first:19.00 07:37:02 INFO - PROCESS | 3752 | Values: 86.0 86.0 118.0 86.0 86.0 86.0 126.0 85.0 87.0 127.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:37:02 INFO - PROCESS | 3752 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:37:02 INFO - PROCESS | 3752 | Values: 146.0 147.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 146.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:37:02 INFO - PROCESS | 3752 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.50 stddev:0.97 (1.5%) stddev-sans-first:0.87 07:37:02 INFO - PROCESS | 3752 | Values: 66.0 65.0 66.0 64.0 64.0 64.0 65.0 64.0 64.0 63.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 07:37:02 INFO - PROCESS | 3752 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 66.0 66.0 65.0 64.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:82.00 stddev:2.96 (3.6%) stddev-sans-first:3.12 07:37:02 INFO - PROCESS | 3752 | Values: 82.0 80.0 83.0 82.0 82.0 83.0 82.0 82.0 91.0 82.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:137.00 stddev:5.29 (3.9%) stddev-sans-first:4.76 07:37:02 INFO - PROCESS | 3752 | Values: 143.0 140.0 137.0 131.0 139.0 128.0 137.0 127.0 136.0 132.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:168.50 stddev:1.96 (1.2%) stddev-sans-first:2.07 07:37:02 INFO - PROCESS | 3752 | Values: 168.0 168.0 170.0 169.0 170.0 165.0 166.0 166.0 165.0 169.0 07:37:02 INFO - PROCESS | 3752 | 07:37:02 INFO - PROCESS | 3752 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:2.18 (3.3%) stddev-sans-first:2.32 07:37:02 INFO - PROCESS | 3752 | Values: 67.0 66.0 67.0 66.0 67.0 67.0 67.0 73.0 65.0 66.0 07:37:02 INFO - PROCESS | 3752 | -------- Summary: end -------- 07:37:02 INFO - PROCESS | 3752 | 07:37:03 INFO - TEST-INFO | 3752: exit 0 07:37:03 INFO - TEST-OK | kraken | took 65696ms 07:37:03 INFO - SUITE-END | took 1190s 07:37:03 INFO - Completed test suite (00:19:50) 07:37:03 INFO - javascript benchmark 07:37:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2655.0441840937265, 2660.0822716162943, 2690.475357305326, 2691.3002619318972, 2704.7971402144844, 4696.694714843541, 4749.90500189996, 4757.524247575241, 4842.636802239833, 4905.779239934202, 4758.262469317261, 4765.976170119153, 4771.90456190876, 4784.449788274358, 4801.543853333937, 4182.184474027566, 4318.8488402905905, 4422.97788511057, 4444.622207112383, 4548.113117942003, 4444.799984000711, 4482.955170448303, 4500.549945005506, 4511.323301504768, 4529.23003089474, 4218.325067989107, 4251.893702657459, 4255.829766809324, 4302.0, 4331.7834108294455, 4333.414988976511, 4335.176316499855, 4338.631216346615, 4363.607275345203, 4448.021435284232, 4384.495782984965, 4394.802233899451, 4402.295632698753, 4425.955740442587, 4441.578050085237, 4645.372874661911, 4759.286107083965, 4816.662833601649, 4825.396825396825, 4880.975595122037, 5145.614078944094, 5242.842714718527, 5248.029114613771, 5251.054810134175, 5252.2384254282915, 5224.712640804754, 5263.0526505229045, 5268.973655131738, 5277.525022747971, 5288.153895376721, 4692.319613656001, 4693.155232058262, 4757.762111894392, 4830.8792280193275, 4890.144224760653, 5265.473452654781, 5327.3074500314715, 5338.813141540028, 5342.465753424704, 5345.599080069011, 5061.696298222045, 5069.695818250917, 5120.846374608767, 5123.692578445305, 5126.948730512721, 4965.1311020571975, 5105.846824595268, 5124.590032797368, 5125.56432703217, 5131.640785145079, 5100.285959965608, 5111.795528178831, 5119.6416250862085, 5120.558281019378, 5120.769565369567, 5101.795928162906, 5103.642745007888, 5109.412417572027, 5111.258867464197, 5147.408048074444, 4279.293916503804, 4313.439252897103, 4315.395844581761, 4359.498657654411, 4362.367456718822, 3826.770393776382, 3833.9424908626393, 3845.0195200223766, 3862.3627101528496, 3865.4974853268895, 4320.373545835836, 4357.3899654048455, 4370.278903980808, 4370.475542934804, 4378.8905277367685, 5191.169412893994, 5191.974040129775, 5196.0, 5230.0, 5233.319668443078, 4371.825126994884, 4387.232234358974, 4388.890277743082, 4430.689851710414, 4431.556844315607, 4506.076254367847, 4589.2657174851865, 4637.1653102441205, 4657.208274593259, 4667.719936803803, 4842.0, 4844.636652251166, 4845.055214233191, 4878.243872199815, 4884.438289596742, 5089.847304580869, 5100.821471248489, 5158.123119069842, 5159.071367153798, 5162.716050617102, 4630.027694184259, 4787.06652202831, 4802.951970480251, 4827.106985207748, 4853.0779151961015, 5101.9489805101475, 5116.948830511797, 5119.180931051014, 5125.948740512547, 5173.948260517347, 5167.354080739908, 5182.041322355376, 5183.740812959412, 5187.481251874782, 5190.40310364313, 4839.104765618372, 4896.094222568836, 4921.458639549575, 4944.678595891256, 4976.2038073908, 4949.529794669501, 4977.701337919736, 4980.402351717672, 4983.6760610560195, 5024.598032157492, 4760.333553302609, 4904.901901962012, 4908.190148625436, 4977.950220497749, 4979.427365852974, 5027.723475208899, 5040.269160971712, 5042.344495215599, 5048.873778155576, 5070.797168113381, 4943.134951383566, 4948.851534453972, 4950.331705219749, 4953.578945789504, 5000.2499625056535], "value": 4712.578928180626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.2823957095347, 570.9543236541077, 578.196307133085, 582.9650220986741, 584.5674201091192, 38351.808240958824, 40344.39483407747, 40427.1914561709, 44222.33666494997, 44266.557334426645, 38377.61622383775, 38816.0, 38855.80572097136, 38869.80565097178, 38898.41652375217, 7349.0, 7722.111957124918, 7834.177411371781, 7838.608069596526, 10136.13842823361, 9947.05502977216, 10362.0, 10364.637237696683, 10374.170066394709, 10375.896241037606, 10443.0, 10488.37069775812, 10488.370697758159, 10494.108000819939, 10497.68506944789, 7558.73544425948, 7779.8833017504785, 7781.572013539253, 10150.13723833475, 10337.0, 9906.20750339971, 10321.0, 10326.173906087495, 10334.0, 10338.22463315247, 38674.41988370177, 39040.21919561621, 39048.0, 39050.21899562021, 39062.80468597667, 2997.9850100749577, 3172.5558421820965, 3288.161518812711, 3288.802671839673, 3430.022443603586, 29771.404571908657, 29854.402911941856, 29992.342803727268, 30211.244718881982, 30300.54549181764, 3595.658412450839, 3624.075860655516, 3633.7100329383215, 3657.030886814996, 3723.2739615774935, 265751.0, 271917.0, 273728.0, 404162.0, 404624.0, 51986.0, 52062.21906671403, 52332.0, 52385.23762386134, 52643.210351844755, 32574.837125814694, 33807.66192338094, 33886.152846179044, 35455.29089418197, 35591.11022224465, 13860.445582176802, 13943.930280348737, 13982.230977296347, 13991.30043497821, 14002.299885005708, 9849.753756156153, 11101.44492775358, 11363.886361136449, 11374.772504550028, 11607.825882611767, 17611.0, 17627.823721762874, 17684.0, 17716.379926702502, 17763.20065597051, 6521.869562608721, 6631.966840165768, 6653.401193892573, 6743.898841517381, 6831.248562658104, 803.9155888631609, 947.3984020147257, 951.0014484790942, 994.2046362909643, 1009.7021378693303, 26634.068456241737, 26917.730822691916, 27005.729942700713, 27076.864615676797, 27585.172444826687, 942.7643089227693, 957.0716405087054, 974.8635191073255, 1007.3552926127285, 1031.6750223679517, 14479.927600362142, 14822.703545929022, 14863.702725945637, 15109.848901511064, 15124.924375378272, 325804.0, 329022.0, 329608.0, 329888.0, 330067.0, 46886.531134688215, 47261.0, 47432.28851567229, 47432.28851567229, 47626.285605715944, 1053.5522402978704, 1078.4553800330782, 1170.6722117807028, 1212.0545974140184, 1249.3378509390038, 27147.457050858477, 27800.049987503124, 30173.0, 30224.0, 30591.388172235984, 44238.0, 44912.32631510465, 45037.549624503336, 45061.32408013948, 45074.0, 360972.0, 361044.0, 363773.0, 363830.0, 364593.0, 29233.561496577993, 38702.0, 38997.415038775005, 39012.60987390203, 41615.0, 42320.0, 43501.56498435102, 44046.77976610096, 44585.77707111444, 48784.75607621939, 37050.81474592718, 37419.43870841885, 37875.62124378721, 38083.61916380912, 38375.42436863505, 37203.62796372001, 37248.81375593105, 37270.0, 37707.09807724978, 38057.4291385635, 35757.46363804597, 36654.08364790795, 36690.08274793151, 36753.0, 36766.63233367632, 31268.68731312658, 33990.49014264739, 34432.139196520286, 34492.48261276033, 34718.82640586781, 25826.483470330862, 25927.611085834105, 26098.347541311618, 26128.0, 26208.73791262063], "value": 18257.95898135512, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.51917949127653, 363.7999100494727, 368.9852405903764, 373.1901773152259, 378.8465671403081, 15717.685646287082, 16726.832731672694, 17699.91150044248, 17713.202905869235, 17727.202275897565, 16202.918985405056, 16426.260818263177, 17096.316147354122, 17203.913980430112, 17210.483685489424, 5011.448740638527, 5174.741262936857, 5192.221166824968, 5210.140326846066, 5218.295530103444, 3743.9251214975684, 3967.642912137907, 5128.128218202895, 5831.650100993954, 6192.81421557354, 6218.160548325986, 6224.190855188819, 6240.563160578761, 6248.937510624904, 6254.968725156368, 6054.545909056838, 6136.723847426876, 6208.751649933997, 6235.937640623603, 6241.28225254097, 3383.5263063171174, 4974.60203183748, 5320.707361095138, 5328.440513746078, 5429.728513574306, 19862.90068549648, 20440.795592044185, 20441.4889627759, 20752.792472075238, 20810.5837883243, 1906.3613689414062, 1924.0668275886185, 1948.1136083082163, 1984.1864835417555, 2004.9097790599312, 12755.681107972283, 13311.267880266581, 13316.400761965639, 13405.262710550916, 13579.796303055462, 1771.601389687323, 1798.5144011116925, 1841.3739328628196, 1854.2212270846278, 1905.580772230121, 68363.0, 68389.65805170992, 68655.0, 69205.65397172981, 70307.29692703059, 9918.15695665862, 10000.599976001024, 10096.495175241209, 10103.393796372242, 10125.493725313705, 13615.659608509866, 13657.385417656229, 13680.52118175879, 13719.45122195101, 13727.862721372858, 7129.966154907509, 7143.1428228612895, 7148.571085734872, 7284.417246620258, 7296.379807716297, 7144.357007869213, 7194.064771579766, 7201.099862517185, 7346.33882950537, 7381.966524686548, 4312.266914624522, 4375.365571992043, 4376.439782727077, 4387.407699960529, 4477.820887164542, 1630.6086539230498, 1767.0827152539382, 1772.1493683031958, 1837.0559285899321, 1845.2711179084185, 811.6307080278459, 840.9915900840913, 854.598338780772, 875.7941883657336, 881.7575166829173, 16029.759553606706, 16850.410235641695, 17042.403515876886, 17207.483775486755, 17419.303227870743, 811.1563973467528, 826.442156534936, 837.1293854391487, 861.440063958432, 911.1981456318398, 8006.13558039008, 9638.759031024281, 9657.275704322205, 9733.367331123454, 9908.0, 388347.0, 393465.0, 394882.0, 395144.0, 395895.0, 72094.0, 74038.25961740314, 74553.0, 76721.0, 79517.0, 895.9148880856309, 896.5114012863005, 914.6067191107754, 918.8116436130578, 953.3898305084751, 15741.606459838138, 15765.29056192497, 15876.92061539685, 16015.599610009844, 16335.346586136422, 37558.0, 37855.62144378521, 38193.23613527769, 38398.61601384062, 38504.42243366408, 52802.73598631982, 54062.189067163235, 54421.0, 56096.719516402154, 56741.43258567362, 7693.49992250502, 7755.689772409266, 7760.728374506865, 7878.566678832719, 8020.358371330396, 10876.673699789018, 11771.705707357385, 11864.347460889388, 12170.330631815335, 12317.137800353888, 10655.20085993569, 10733.838992415276, 10920.50857711389, 11052.889471105185, 11179.552817887194, 10634.73413164646, 10657.733556661145, 10779.784404312026, 10857.40284284372, 10916.399598022184, 10775.461226938465, 10929.344239345664, 11129.944350278196, 11209.15931305139, 11356.659300221007, 7735.729249476241, 7817.0, 7818.296353328228, 7949.880751788844, 8021.799455013671, 9998.550065247227, 10240.64157754475, 10559.736006599896, 10716.19628527848, 10797.784044319227], "value": 8413.749878427121, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1207.2515040674782, 1365.822443082399, 1398.9230592317422, 1480.6594483268848, 1694.5424735321456, 6691.0967019452355, 7600.163981961987, 7840.921590784104, 7861.449698521106, 7887.566183859885, 7632.96183519083, 7761.689532418696, 7764.340031097349, 7778.883316750239, 7778.8833167502535, 2216.9889150554263, 2242.4281808138903, 2244.6408574628063, 2249.1453247765908, 2252.842301038928, 2177.17267438374, 2204.5370472200857, 2210.4031911383995, 2213.5904857601313, 2219.2676416782506, 2142.582196471689, 2157.590057889004, 2168.1652563762987, 2168.5554461335387, 2171.771963943787, 2131.5630295789324, 2133.5412886229537, 2137.850350475467, 2138.5188332625266, 2138.550904310097, 2101.71626830379, 2138.4012476506605, 2144.613969485492, 2147.291393840029, 2158.266189495563, 7582.431317651143, 7686.961565192194, 7734.110577283628, 7734.381249500027, 7827.6868925242925, 4527.388802511652, 4642.187617166982, 4650.395448591661, 4658.18481765693, 4662.673612847102, 2835.957460638092, 2915.4023425197784, 2988.3126880817317, 2991.2820922978544, 2999.43010827942, 962.3696478806393, 979.9514519464176, 990.4948476277078, 993.3791380387258, 998.7503124218945, 10480.371177729361, 10553.208509361828, 10626.893731062668, 10638.255322127454, 10670.199735019905, 4138.8551400700835, 4163.250614889288, 4223.092035212444, 4241.300185469363, 4249.2563801334645, 8160.224778646019, 8214.8357032859, 8251.876221856677, 8252.46358986664, 8261.421700481029, 4707.85876423701, 4709.105269998689, 4710.26990816424, 4710.293455981561, 4717.127331443694, 3608.9278214435562, 3747.5315585551807, 4241.936370954438, 4335.067960388532, 4359.9782001090425, 3500.0899766061007, 3547.0, 3617.2584620152807, 3645.179834537261, 3646.34365814156, 1068.417712346773, 1084.5715942202785, 1096.2216826053432, 1125.6004118537965, 1128.5542210826677, 760.3612965109335, 766.252903419173, 772.7102336623766, 775.0, 780.8594452998515, 4898.951010489921, 4925.926111108336, 4936.111499929975, 5019.222020586815, 5026.396832380065, 420.90108824426846, 423.89402649337666, 432.51774271686844, 433.3672837656993, 436.6964959353219, 3235.724963378092, 3376.375370556455, 3382.0868365541164, 3383.678550537695, 3421.9657803422147, 9546.093121153619, 9895.703128906145, 9921.603135874628, 9926.851097233548, 9926.950365248127, 9430.858537121949, 9570.95214523923, 9591.664291749756, 9605.47169905662, 9620.13418792312, 7302.926970730331, 7360.153582337993, 7361.263873612703, 7379.852402951911, 7383.741569045058, 4656.534346565452, 4783.234682450791, 4788.856334309976, 4809.398825146856, 4840.249761287007, 8622.568871556272, 8812.91187088121, 8921.509316987627, 9008.954955225181, 9096.954515227382, 8958.283337332867, 9059.954700226457, 9109.817803644022, 9119.635214591342, 9148.817023659356, 5668.26312579362, 6101.206843110367, 6226.283977342482, 6265.122882796321, 6297.55917085818, 7465.477416580788, 7471.215522370073, 7481.326680598772, 7532.962335188289, 7537.133229678657, 6514.446272066927, 6736.0, 6777.525573209828, 6812.693428795617, 6869.416099631588, 6589.7364105435245, 6636.402723754885, 6644.800655980328, 6761.492888033319, 6843.0, 5416.539594134542, 5577.721113944368, 5740.655560666373, 5741.0, 5745.454181852718, 4344.174606824693, 4352.73883566987, 4408.823647054211, 4489.595936365743, 4509.391232183613, 4451.065276292015, 4476.149531588988, 4490.461144662661, 4526.683132180716, 4554.772261386851], "value": 3982.5513780319643, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.45084916630486, 297.6710734638223, 299.98950036748715, 302.570350102854, 304.6861732415611, 4804.399450068741, 4950.13372659784, 4954.5788607968325, 4956.4547899731, 4973.602111831054, 4859.0, 4873.7806798694055, 4888.706677599346, 4914.262860570907, 4924.335214746017, 3620.637936206378, 3723.7765734055906, 3725.0, 3853.190829925719, 3890.3580909149955, 3791.5260592425807, 3819.6944244460515, 3855.0, 3866.3427217373114, 3872.4965754451882, 3654.6162652921457, 3792.886213413602, 3825.1448047696986, 3834.482344883447, 3836.366999445102, 3734.495843061179, 3793.279276937387, 3798.3922572388287, 3804.714646401503, 3808.2383523295453, 3362.663733626642, 3363.9327213455595, 3367.107716455141, 3380.9830950845335, 3396.1849156202334, 4824.2040063389495, 4840.201366774478, 4842.491538388454, 4877.439094504107, 4884.534639608103, 4251.829926802924, 4349.869503914888, 4380.386745855582, 4400.933985990213, 4421.336799480104, 5096.51583099605, 5098.0, 5121.411037730634, 5123.538881500645, 5146.614003949681, 3677.7057835373107, 3706.184639379332, 3707.4994875691705, 3729.1982223821738, 3741.4013757798893, 5295.894082118374, 5355.464453554652, 5393.2449457076045, 5429.837104886859, 5452.863678408032, 3765.1573062338307, 3766.60450652683, 3779.5259848659043, 3794.5959994801547, 3794.981025094912, 3810.6760925321105, 3816.236752649426, 3838.1172330364175, 3858.0354911272184, 3859.1702783901596, 4373.825046998148, 4403.8458653946955, 4404.493483249404, 4406.096750166208, 4420.911581768347, 4930.630202734809, 5084.3898732152375, 5086.3133476980875, 5097.847064588068, 5133.332666753298, 1313.8094976228394, 1333.2134040915905, 1335.1788649980385, 1336.1982810313734, 1344.2740919903338, 851.5614458553849, 869.3783944479601, 908.9136532029447, 932.1843387036344, 943.2878176976338, 4443.711323716087, 4459.531749166291, 4473.590818892038, 4475.216837053567, 4497.437820272466, 5200.63595548308, 5226.790928362823, 5244.895102097937, 5306.469353064663, 5313.1764576490705, 4264.616184543406, 4404.163208990281, 4427.645788336926, 4472.351509031172, 4487.596116349544, 2802.1173330400857, 2808.971910280871, 2815.4509870575453, 2826.0, 2848.2167403963663, 4958.380202474691, 4958.9008219835405, 4963.925541116886, 4988.0, 5004.84985450437, 5154.329937108152, 5477.342718873681, 5490.995786537965, 5501.504864562208, 5509.47659972302, 4971.701697898137, 4997.150484417585, 5007.173816320266, 5018.673786203885, 5026.447090820006, 5278.472152784844, 5305.151175811851, 5340.252364668871, 5371.516563509303, 5375.569954403716, 5148.851884660929, 5200.635955483232, 5244.449332819999, 5252.345511163971, 5277.313949186581, 4925.729084900365, 5014.272930424995, 5033.245013247895, 5056.6207534434325, 5059.0, 3782.867599634, 3785.5268091488565, 3791.4123310886857, 3804.6575808177395, 3813.427985802152, 5218.765155567932, 5363.731813409236, 5383.16560933056, 5400.83797486076, 5416.729163541729, 5146.485351464973, 5218.356217791384, 5230.215467679878, 5231.90130072689, 5241.423443421144, 5202.453742356998, 5203.661761985459, 5207.218917162304, 5208.713520756395, 5209.583233341248, 4876.1718203441205, 4937.432195297587, 5121.871953201051, 5136.640435169502, 5151.793928242978, 5023.924641130313, 5196.2725218470205, 5242.775692948885, 5260.290405618113, 5260.39505456862, 5125.718085505333, 5137.691738495702, 5141.588672906233, 5145.331106956071, 5147.1910786018825], "value": 3891.422872923942, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.3044364304765, 710.747684571977, 721.6283613938825, 731.6012773038693, 769.3191525499931, 12524.123311368208, 13115.278659673713, 13755.862441375582, 13821.447142114315, 13866.514671986482, 11962.222455540406, 11966.581169659064, 12002.81995770062, 12036.398180090982, 12044.638660840166, 620.4663988969482, 762.4091329219843, 779.1779672445582, 794.6662401791261, 797.5732982854174, 786.4573444323427, 797.9880301795478, 798.1858504325585, 798.3294033012269, 799.5522507395848, 827.4208054361941, 832.9125441828612, 833.3541505333385, 833.4249367936135, 834.0033659776569, 647.7311915555039, 801.7434420985287, 824.9422540422173, 827.6482494939627, 828.3828547731948, 604.5767962426319, 604.9122877182784, 604.948579370754, 605.0923614578132, 605.3462260758371, 12067.155299129065, 12113.33376664283, 12133.575324863676, 12134.696632584257, 12147.0, 11845.17083804134, 12318.322492262922, 12992.805107923388, 13071.895424836575, 13109.344532773322, 11577.189596728233, 11691.590794322157, 11695.122865785015, 11934.522619095225, 12467.750644987049, 4174.227767862955, 4221.24017676818, 4229.492460904711, 4253.532111467736, 4387.210302145612, 55583.0, 55692.72153639246, 55716.72141639306, 55753.72123139399, 55944.44055559474, 10273.897261027294, 10299.845502317472, 10494.527746251284, 10542.209334299958, 10589.205809564313, 19087.713684294748, 19108.808911910983, 19227.80772192288, 19233.134508947132, 19266.518337041685, 6890.069840571559, 6928.411085057725, 6943.6875340609795, 7040.577565346096, 7108.3957863581145, 3803.201327721154, 3825.732198746061, 3880.165764360676, 3889.0471834400755, 3892.0464486201063, 2890.566415037761, 2971.821690698565, 2985.4178435205363, 2998.4902566563737, 3000.1299623109053, 493.6001838510818, 499.65274134476914, 501.7014876148686, 508.47626944247486, 513.6019584821793, 418.8659628918717, 497.65164384930694, 573.4724053870449, 589.8762856757883, 633.6609913696151, 8296.0, 8521.147885211429, 8536.74389768308, 8639.136086391436, 8665.220130188314, 530.5490333216735, 555.1145922253999, 601.3565484931082, 603.5171862510063, 640.9102725618324, 3886.3587508060855, 3888.9611103889165, 3929.8231579578987, 3946.4474973503734, 4087.7941007402887, 91744.54127729453, 93755.53122234344, 94622.0, 95545.52227238819, 96715.51642241885, 65117.0, 66052.0, 66671.66664166648, 80317.19682803215, 80458.0, 77904.61047694726, 78740.60629696815, 79394.0, 79683.60158199172, 80037.59981200173, 8379.70671026511, 8596.91403085961, 8725.389222754346, 8725.912740872764, 8838.248748856418, 38570.87860167718, 38704.41943370795, 47942.0, 48872.266915997, 49236.261456077475, 43130.56869431266, 43170.0, 61237.38762612317, 61687.38312616816, 64362.67818660877, 9231.446113233227, 9357.578908948977, 9585.664501742685, 11551.826722599, 11741.765164696488, 10654.200934929757, 10703.625373111903, 11738.354390508262, 12155.088368372231, 12418.565350212699, 10958.671239862817, 11039.227254092137, 11128.221024528206, 11559.364234967157, 11758.470868810753, 11584.94207528957, 11600.30398176112, 11916.285022898654, 12002.579909703118, 12056.939715301367, 11086.833697494705, 11118.777624447628, 11137.777244455228, 11184.2856221186, 11239.325640461599, 9962.551685174329, 10268.845967310646, 10339.84490232632, 10379.06588407047, 10645.520951557353, 10007.299489035697, 10226.897731022595, 10265.640702575374, 10276.486175691334, 10317.9484102579], "value": 6532.003221072906, "unit": "score"}], "name": "dromaeo_css", "value": 6469.113311133037, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 115.0, 114.0, 114.0, 113.0, 112.0, 114.0, 115.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 136.0, 131.0, 193.0, 131.0, 132.0, 158.0, 133.0, 188.0, 131.0], "value": 149.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 161.0, 160.0, 161.0, 161.0, 162.0, 162.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 75.0, 125.0, 76.0, 76.0, 75.0, 75.0, 98.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 118.0, 86.0, 86.0, 86.0, 126.0, 85.0, 87.0, 127.0], "value": 97.3, "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, 117.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 63.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 64.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 83.0, 82.0, 82.0, 83.0, 82.0, 82.0, 91.0, 82.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 137.0, 131.0, 139.0, 128.0, 137.0, 127.0, 136.0, 132.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 170.0, 169.0, 170.0, 165.0, 166.0, 166.0, 165.0, 169.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 73.0, 65.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1543.8999999999999, "alertThreshold": 2.0}]} 07:37:03 INFO - Return code: 0 07:37:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:37:03 INFO - # TBPL SUCCESS # 07:37:03 INFO - Running post-action listener: _resource_record_post_action 07:37:03 INFO - ##### 07:37:03 INFO - ##### Finished run-tests step (success) 07:37:03 INFO - ##### 07:37:03 INFO - Running post-run listener: _resource_record_post_run 07:37:03 INFO - Total resource usage - Wall time: 1208s; CPU: 13.0%; Read bytes: 7688192; Write bytes: 431075328; Read time: 900; Write time: 486844 07:37:03 INFO - TinderboxPrint: CPU usage
13.1% 07:37:03 INFO - TinderboxPrint: I/O read bytes / time
7,688,192 / 900 07:37:03 INFO - TinderboxPrint: I/O write bytes / time
431,075,328 / 486,844 07:37:03 INFO - TinderboxPrint: CPU idle
8,372.4 (86.8%) 07:37:03 INFO - TinderboxPrint: CPU user
1,214.9 (12.6%) 07:37:03 INFO - TinderboxPrint: Swap in / out
0 / 0 07:37:03 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 36864; Write bytes: 15503360; Read time: 32; Write time: 2172 07:37:03 INFO - run-tests - Wall time: 1192s; CPU: 13.0%; Read bytes: 5890048; Write bytes: 415571968; Read time: 816; Write time: 484672 07:37:04 INFO - Running post-run listener: _upload_blobber_files 07:37:04 INFO - Blob upload gear active. 07:37:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:37:04 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 07:37:04 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'] 07:37:04 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 07:37:04 INFO - (blobuploader) - INFO - Open directory for files ... 07:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:37:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:05 INFO - (blobuploader) - INFO - Done attempting. 07:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:06 INFO - (blobuploader) - INFO - Done attempting. 07:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:37:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:09 INFO - (blobuploader) - INFO - Done attempting. 07:37:09 INFO - (blobuploader) - INFO - Iteration through files over. 07:37:09 INFO - Return code: 0 07:37:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:37:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:37:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55803e3623e892ed78f881dbd04ff6c86265e490b17889082b375c3ac1e5220defdd68a6945bb5b2e6f1e1ca3d60ea40aface27d8743d7efe8ad0f4348210e7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0b16adb78b090258842159cc54faa149745ed9a46c733b2445c66a82c821b02715d12b7195e709ca18b803be0f225fdbeea04cdb5c6d84ed743968843d9965e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a51ce8ddd85bc18dc9d2bd4206a8b6eca9c18f554ff0eba2ce1f8b6facd97cd09abdc4775e93cfe6af6147f179970aa8ed95f54084f8b348985e61c3966f842d"} 07:37:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:37:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:37:09 INFO - Contents: 07:37:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55803e3623e892ed78f881dbd04ff6c86265e490b17889082b375c3ac1e5220defdd68a6945bb5b2e6f1e1ca3d60ea40aface27d8743d7efe8ad0f4348210e7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0b16adb78b090258842159cc54faa149745ed9a46c733b2445c66a82c821b02715d12b7195e709ca18b803be0f225fdbeea04cdb5c6d84ed743968843d9965e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a51ce8ddd85bc18dc9d2bd4206a8b6eca9c18f554ff0eba2ce1f8b6facd97cd09abdc4775e93cfe6af6147f179970aa8ed95f54084f8b348985e61c3966f842d"} 07:37:09 INFO - Running post-run listener: copy_logs_to_upload_dir 07:37:09 INFO - Copying logs to upload dir... 07:37:09 INFO - mkdir: /builds/slave/test/build/upload/logs 07:37:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.135023 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-06-23 07:37:09.855814) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 07:37:09.859779) ========= 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=62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864 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/55803e3623e892ed78f881dbd04ff6c86265e490b17889082b375c3ac1e5220defdd68a6945bb5b2e6f1e1ca3d60ea40aface27d8743d7efe8ad0f4348210e7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0b16adb78b090258842159cc54faa149745ed9a46c733b2445c66a82c821b02715d12b7195e709ca18b803be0f225fdbeea04cdb5c6d84ed743968843d9965e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a51ce8ddd85bc18dc9d2bd4206a8b6eca9c18f554ff0eba2ce1f8b6facd97cd09abdc4775e93cfe6af6147f179970aa8ed95f54084f8b348985e61c3966f842d"} build_url:https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/N_vbBqUxSaqHsPWycMlA4w/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/55803e3623e892ed78f881dbd04ff6c86265e490b17889082b375c3ac1e5220defdd68a6945bb5b2e6f1e1ca3d60ea40aface27d8743d7efe8ad0f4348210e7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0b16adb78b090258842159cc54faa149745ed9a46c733b2445c66a82c821b02715d12b7195e709ca18b803be0f225fdbeea04cdb5c6d84ed743968843d9965e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a51ce8ddd85bc18dc9d2bd4206a8b6eca9c18f554ff0eba2ce1f8b6facd97cd09abdc4775e93cfe6af6147f179970aa8ed95f54084f8b348985e61c3966f842d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 07:37:09.886007) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 07:37:09.886395) ========= 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=62c568580acbfdaac3eec7be000001dc-1466690365.480670-1853522864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004741 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 07:37:09.969331) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 07:37:09.969651) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 07:37:09.969960) ========= ========= Total master_lag: 0.17 =========