builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-042 starttime: 1461369433.3 results: success (0) buildid: 20160422142650 builduid: 0e0a78d064b14b7f8537b3303394d2df revision: 922334c70324e4295dcfcfafe6da4ae2e1154834 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.303263) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.303657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.303959) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.338625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.338914) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-22 16:57:13-- 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% 47.2M=0s 2016-04-22 16:57:13 (47.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.538781) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.539067) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013754 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.588250) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:13.588565) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 922334c70324e4295dcfcfafe6da4ae2e1154834 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 922334c70324e4295dcfcfafe6da4ae2e1154834 --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=74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-22 16:57:13,527 truncating revision to first 12 chars 2016-04-22 16:57:13,527 Setting DEBUG logging. 2016-04-22 16:57:13,527 attempt 1/10 2016-04-22 16:57:13,527 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/922334c70324?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 16:57:14,047 unpacking tar archive at: mozilla-aurora-922334c70324/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980122 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:14.580577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:14.580874) ========= script_repo_revision: 922334c70324e4295dcfcfafe6da4ae2e1154834 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:14.581265) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:14.581547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 16:57:14.590091) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 46 secs) (at 2016-04-22 16:57:14.590369) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:57:14 INFO - MultiFileLogger online at 20160422 16:57:14 in /builds/slave/test 16:57:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:57:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:57:14 INFO - {'append_to_log': False, 16:57:14 INFO - 'base_work_dir': '/builds/slave/test', 16:57:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:57:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:57:14 INFO - 'branch': 'Mozilla-Aurora', 16:57:14 INFO - 'buildbot_json_path': 'buildprops.json', 16:57:14 INFO - 'config_files': ('talos/linux_config.py',), 16:57:14 INFO - 'default_actions': ('clobber', 16:57:14 INFO - 'read-buildbot-config', 16:57:14 INFO - 'download-and-extract', 16:57:14 INFO - 'populate-webroot', 16:57:14 INFO - 'create-virtualenv', 16:57:14 INFO - 'install', 16:57:14 INFO - 'run-tests'), 16:57:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:57:14 INFO - 'download_minidump_stackwalk': True, 16:57:14 INFO - 'download_symbols': 'ondemand', 16:57:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:57:14 INFO - 'tooltool.py': '/tools/tooltool.py', 16:57:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:57:14 INFO - '/tools/misc-python/virtualenv.py')}, 16:57:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:57:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:57:14 INFO - 'installer_path': 'installer.exe', 16:57:14 INFO - 'log_level': 'info', 16:57:14 INFO - 'log_name': 'talos', 16:57:14 INFO - 'log_to_console': True, 16:57:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:57:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:57:14 INFO - 'opt_config_files': (), 16:57:14 INFO - 'pip_index': False, 16:57:14 INFO - 'sps_profile': False, 16:57:14 INFO - 'sps_profile_interval': 0, 16:57:14 INFO - 'suite': 'dromaeojs-e10s', 16:57:14 INFO - 'system_bits': '32', 16:57:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:57:14 INFO - 'title': 'talos-linux64-ix-042', 16:57:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:57:14 INFO - 'use_talos_json': True, 16:57:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:57:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:57:14 INFO - 'work_dir': 'build'} 16:57:14 INFO - ##### 16:57:14 INFO - ##### Running clobber step. 16:57:14 INFO - ##### 16:57:14 INFO - Running pre-action listener: _resource_record_pre_action 16:57:14 INFO - Running main action method: clobber 16:57:14 INFO - rmtree: /builds/slave/test/build 16:57:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:57:15 INFO - Running post-action listener: _resource_record_post_action 16:57:15 INFO - ##### 16:57:15 INFO - ##### Running read-buildbot-config step. 16:57:15 INFO - ##### 16:57:15 INFO - Running pre-action listener: _resource_record_pre_action 16:57:15 INFO - Running main action method: read_buildbot_config 16:57:15 INFO - Using buildbot properties: 16:57:15 INFO - { 16:57:15 INFO - "project": "", 16:57:15 INFO - "product": "firefox", 16:57:15 INFO - "script_repo_revision": "production", 16:57:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 16:57:15 INFO - "repository": "", 16:57:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 16:57:15 INFO - "buildid": "20160422142650", 16:57:15 INFO - "pgo_build": "True", 16:57:15 INFO - "basedir": "/builds/slave/test", 16:57:15 INFO - "buildnumber": 311, 16:57:15 INFO - "slavename": "talos-linux64-ix-042", 16:57:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:57:15 INFO - "platform": "ubuntu64_hw", 16:57:15 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:57:15 INFO - "branch": "mozilla-aurora", 16:57:15 INFO - "repo_path": "releases/mozilla-aurora", 16:57:15 INFO - "revision": "922334c70324e4295dcfcfafe6da4ae2e1154834", 16:57:15 INFO - "stage_platform": "linux64-pgo", 16:57:15 INFO - "builduid": "0e0a78d064b14b7f8537b3303394d2df", 16:57:15 INFO - "slavebuilddir": "test" 16:57:15 INFO - } 16:57:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2. 16:57:15 INFO - Running post-action listener: _resource_record_post_action 16:57:15 INFO - ##### 16:57:15 INFO - ##### Running download-and-extract step. 16:57:15 INFO - ##### 16:57:15 INFO - Running pre-action listener: _resource_record_pre_action 16:57:15 INFO - Running main action method: download_and_extract 16:57:15 INFO - mkdir: /builds/slave/test/build/tests 16:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:57:15 INFO - https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:57:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 16:57:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 16:57:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 16:57:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:57:23 INFO - Downloaded 1448 bytes. 16:57:23 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 16:57:23 INFO - Using the following test package requirements: 16:57:23 INFO - {u'common': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 16:57:23 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:57:23 INFO - u'gtest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:57:23 INFO - u'jittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'jsshell-linux-x86_64.zip'], 16:57:23 INFO - u'mochitest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:57:23 INFO - u'mozbase': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 16:57:23 INFO - u'reftest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:57:23 INFO - u'talos': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:57:23 INFO - u'web-platform': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:57:23 INFO - u'webapprt': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 16:57:23 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 16:57:23 INFO - u'firefox-47.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:57:23 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:57:23 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')]} 16:57:23 INFO - https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:57:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 16:57:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 16:57:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 16:57:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:57:31 INFO - Downloaded 23358704 bytes. 16:57:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:57:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:57:32 INFO - Return code: 0 16:57:32 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:57: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')]} 16:57:32 INFO - https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:57:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 16:57:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 16:57:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 16:57:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:58:04 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip: The read operation timed out 16:58:04 INFO - retry: attempt #1 caught exception: The read operation timed out 16:58:04 INFO - retry: Failed, sleeping 30 seconds before retrying 16:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:58:35 INFO - Downloaded 23358704 bytes. 16:58:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:58:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:58:37 INFO - Return code: 0 16:58:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:37 INFO - https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:58:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 16:58:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 16:58:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 16:58:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:58:38 INFO - Downloaded 10845198 bytes. 16:58:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:58:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:58:38 INFO - Return code: 0 16:58: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')]} 16:58:38 INFO - https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:58:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 16:58:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 16:58:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 16:58:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:58:39 INFO - Downloaded 60840785 bytes. 16:58:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 16:58:39 INFO - mkdir: /builds/slave/test/properties 16:58:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:58:39 INFO - Writing to file /builds/slave/test/properties/build_url 16:58:39 INFO - Contents: 16:58:39 INFO - build_url:https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 16:58:40 INFO - Running post-action listener: _resource_record_post_action 16:58:40 INFO - Running post-action listener: set_extra_try_arguments 16:58:40 INFO - ##### 16:58:40 INFO - ##### Running populate-webroot step. 16:58:40 INFO - ##### 16:58:40 INFO - Running pre-action listener: _resource_record_pre_action 16:58:40 INFO - Running main action method: populate_webroot 16:58:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:58:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:58:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:58:40 INFO - '--tppagecycles', 16:58:40 INFO - '7'], 16:58:40 INFO - 'tests': ['tsvgm']}}, 16:58:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:58:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:58:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:58:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:58:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:58:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:58:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'tests': ['damp', 'tps']}, 16:58:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['damp', 'tps']}, 16:58:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:58:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:58:40 INFO - 'other': {'tests': ['a11yr', 16:58:40 INFO - 'ts_paint', 16:58:40 INFO - 'tpaint', 16:58:40 INFO - 'sessionrestore', 16:58:40 INFO - 'sessionrestore_no_auto_restore', 16:58:40 INFO - 'tabpaint']}, 16:58:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['a11yr', 16:58:40 INFO - 'ts_paint', 16:58:40 INFO - 'tpaint', 16:58:40 INFO - 'sessionrestore', 16:58:40 INFO - 'sessionrestore_no_auto_restore', 16:58:40 INFO - 'tabpaint']}, 16:58:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['a11yr', 16:58:40 INFO - 'ts_paint', 16:58:40 INFO - 'tpaint', 16:58:40 INFO - 'sessionrestore', 16:58:40 INFO - 'sessionrestore_no_auto_restore', 16:58:40 INFO - 'tabpaint']}, 16:58:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['a11yr', 16:58:40 INFO - 'ts_paint', 16:58:40 INFO - 'tpaint', 16:58:40 INFO - 'sessionrestore', 16:58:40 INFO - 'sessionrestore_no_auto_restore', 16:58:40 INFO - 'tabpaint']}, 16:58:40 INFO - 'other_l64': {'tests': ['a11yr', 16:58:40 INFO - 'ts_paint', 16:58:40 INFO - 'tpaint', 16:58:40 INFO - 'sessionrestore', 16:58:40 INFO - 'sessionrestore_no_auto_restore', 16:58:40 INFO - 'tabpaint']}, 16:58:40 INFO - 'other_nol64': {'tests': ['a11yr', 16:58:40 INFO - 'ts_paint', 16:58:40 INFO - 'tpaint', 16:58:40 INFO - 'sessionrestore', 16:58:40 INFO - 'sessionrestore_no_auto_restore', 16:58:40 INFO - 'tabpaint']}, 16:58:40 INFO - 'svgr': {'tests': ['tsvgx', 16:58:40 INFO - 'tsvgr_opacity', 16:58:40 INFO - 'tart', 16:58:40 INFO - 'tscrollx', 16:58:40 INFO - 'cart']}, 16:58:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['tsvgx', 16:58:40 INFO - 'tsvgr_opacity', 16:58:40 INFO - 'tart', 16:58:40 INFO - 'tscrollx', 16:58:40 INFO - 'cart']}, 16:58:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'tests': ['tp5o']}, 16:58:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'talos_options': ['--e10s'], 16:58:40 INFO - 'tests': ['tp5o']}, 16:58:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'talos_options': ['--xperf_path', 16:58:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:58:40 INFO - 'tests': ['tp5n']}, 16:58:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:40 INFO - 'talos_options': ['--e10s', 16:58:40 INFO - '--xperf_path', 16:58:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:58:40 INFO - 'tests': ['tp5n']}}, 16:58:40 INFO - 'talos.zip': {'path': '', 16:58:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:58:40 INFO - Running post-action listener: _resource_record_post_action 16:58:40 INFO - ##### 16:58:40 INFO - ##### Running create-virtualenv step. 16:58:40 INFO - ##### 16:58:40 INFO - Running pre-action listener: _resource_record_pre_action 16:58:40 INFO - Running main action method: create_virtualenv 16:58:40 INFO - Creating virtualenv /builds/slave/test/build/venv 16:58:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:58:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:58:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:58:40 INFO - Using real prefix '/usr' 16:58:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:58:42 INFO - Installing distribute.............................................................................................................................................................................................done. 16:58:44 INFO - Installing pip.................done. 16:58:44 INFO - Return code: 0 16:58:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:58: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')]} 16:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58: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')]} 16:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:44 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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:58:44 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 16:58:44 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 16:58:44 INFO - Using env: {'DISPLAY': ':0', 16:58:44 INFO - 'HOME': '/home/cltbld', 16:58:44 INFO - 'LANG': 'en_US.UTF-8', 16:58:44 INFO - 'LANGUAGE': 'en_US:en', 16:58:44 INFO - 'LOGNAME': 'cltbld', 16:58:44 INFO - 'MAIL': '/var/mail/cltbld', 16:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:44 INFO - 'MOZ_NO_REMOTE': '1', 16:58:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:58:44 INFO - 'NO_EM_RESTART': '1', 16:58:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:44 INFO - 'PWD': '/builds/slave/test', 16:58:44 INFO - 'SHELL': '/bin/bash', 16:58:44 INFO - 'SHLVL': '1', 16:58:44 INFO - 'TERM': 'linux', 16:58:44 INFO - 'TMOUT': '86400', 16:58:44 INFO - 'USER': 'cltbld', 16:58:44 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:44 INFO - '_': '/tools/buildbot/bin/python'} 16:58:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:44 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 16:58:44 INFO - Cleaning up... 16:58:44 INFO - Return code: 0 16:58:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:58: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')]} 16:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58: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')]} 16:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:44 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:58:44 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>=0.7.1'] in /builds/slave/test/build 16:58:44 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>=0.7.1 16:58:44 INFO - Using env: {'DISPLAY': ':0', 16:58:44 INFO - 'HOME': '/home/cltbld', 16:58:44 INFO - 'LANG': 'en_US.UTF-8', 16:58:44 INFO - 'LANGUAGE': 'en_US:en', 16:58:44 INFO - 'LOGNAME': 'cltbld', 16:58:44 INFO - 'MAIL': '/var/mail/cltbld', 16:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:44 INFO - 'MOZ_NO_REMOTE': '1', 16:58:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:58:44 INFO - 'NO_EM_RESTART': '1', 16:58:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:44 INFO - 'PWD': '/builds/slave/test', 16:58:44 INFO - 'SHELL': '/bin/bash', 16:58:44 INFO - 'SHLVL': '1', 16:58:44 INFO - 'TERM': 'linux', 16:58:44 INFO - 'TMOUT': '86400', 16:58:44 INFO - 'USER': 'cltbld', 16:58:44 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:44 INFO - '_': '/tools/buildbot/bin/python'} 16:58:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:44 INFO - Downloading/unpacking psutil>=0.7.1 16:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:58:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:58:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:58:47 INFO - Installing collected packages: psutil 16:58:47 INFO - Running setup.py install for psutil 16:58:47 INFO - building 'psutil._psutil_linux' extension 16:58:47 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 16:58:49 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 16:58:50 INFO - building 'psutil._psutil_posix' extension 16:58:50 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 16:58:50 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 16:58:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:58:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:58:50 INFO - Successfully installed psutil 16:58:50 INFO - Cleaning up... 16:58:50 INFO - Return code: 0 16:58:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:58:50 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')]} 16:58:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:50 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')]} 16:58:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:50 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:58:50 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.0.0'] in /builds/slave/test/build 16:58:50 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.0.0 16:58:50 INFO - Using env: {'DISPLAY': ':0', 16:58:50 INFO - 'HOME': '/home/cltbld', 16:58:50 INFO - 'LANG': 'en_US.UTF-8', 16:58:50 INFO - 'LANGUAGE': 'en_US:en', 16:58:50 INFO - 'LOGNAME': 'cltbld', 16:58:50 INFO - 'MAIL': '/var/mail/cltbld', 16:58:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:50 INFO - 'MOZ_NO_REMOTE': '1', 16:58:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:58:50 INFO - 'NO_EM_RESTART': '1', 16:58:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:58:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:50 INFO - 'PWD': '/builds/slave/test', 16:58:50 INFO - 'SHELL': '/bin/bash', 16:58:50 INFO - 'SHLVL': '1', 16:58:50 INFO - 'TERM': 'linux', 16:58:50 INFO - 'TMOUT': '86400', 16:58:50 INFO - 'USER': 'cltbld', 16:58:50 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:58:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:50 INFO - '_': '/tools/buildbot/bin/python'} 16:58:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:58:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:58:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:58:52 INFO - Installing collected packages: mozsystemmonitor 16:58:52 INFO - Running setup.py install for mozsystemmonitor 16:58:52 INFO - Successfully installed mozsystemmonitor 16:58:52 INFO - Cleaning up... 16:58:52 INFO - Return code: 0 16:58:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:58: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')]} 16:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58: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')]} 16:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58: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', '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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:58: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:58: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 blobuploader==1.2.4 16:58:52 INFO - Using env: {'DISPLAY': ':0', 16:58:52 INFO - 'HOME': '/home/cltbld', 16:58:52 INFO - 'LANG': 'en_US.UTF-8', 16:58:52 INFO - 'LANGUAGE': 'en_US:en', 16:58:52 INFO - 'LOGNAME': 'cltbld', 16:58:52 INFO - 'MAIL': '/var/mail/cltbld', 16:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:52 INFO - 'MOZ_NO_REMOTE': '1', 16:58:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:58:52 INFO - 'NO_EM_RESTART': '1', 16:58:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:52 INFO - 'PWD': '/builds/slave/test', 16:58:52 INFO - 'SHELL': '/bin/bash', 16:58:52 INFO - 'SHLVL': '1', 16:58:52 INFO - 'TERM': 'linux', 16:58:52 INFO - 'TMOUT': '86400', 16:58:52 INFO - 'USER': 'cltbld', 16:58:52 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:52 INFO - '_': '/tools/buildbot/bin/python'} 16:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:52 INFO - Downloading/unpacking blobuploader==1.2.4 16:58: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 16:58: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 16:58: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 16:58: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 16:58: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 16:58: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 16:58:54 INFO - Downloading blobuploader-1.2.4.tar.gz 16:58:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:58:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:58: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 16:58: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 16:58: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 16:58: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 16:58: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 16:58: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 16:58:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:58:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:55 INFO - Downloading docopt-0.6.1.tar.gz 16:58:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:58:55 INFO - Installing collected packages: blobuploader, requests, docopt 16:58:55 INFO - Running setup.py install for blobuploader 16:58:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:58:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:58:55 INFO - Running setup.py install for requests 16:58:55 INFO - Running setup.py install for docopt 16:58:56 INFO - Successfully installed blobuploader requests docopt 16:58:56 INFO - Cleaning up... 16:58:56 INFO - Return code: 0 16:58:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:58:56 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')]} 16:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:56 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')]} 16:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:56 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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:58:56 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 16:58:56 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 16:58:56 INFO - Using env: {'DISPLAY': ':0', 16:58:56 INFO - 'HOME': '/home/cltbld', 16:58:56 INFO - 'LANG': 'en_US.UTF-8', 16:58:56 INFO - 'LANGUAGE': 'en_US:en', 16:58:56 INFO - 'LOGNAME': 'cltbld', 16:58:56 INFO - 'MAIL': '/var/mail/cltbld', 16:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:56 INFO - 'MOZ_NO_REMOTE': '1', 16:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:58:56 INFO - 'NO_EM_RESTART': '1', 16:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:56 INFO - 'PWD': '/builds/slave/test', 16:58:56 INFO - 'SHELL': '/bin/bash', 16:58:56 INFO - 'SHLVL': '1', 16:58:56 INFO - 'TERM': 'linux', 16:58:56 INFO - 'TMOUT': '86400', 16:58:56 INFO - 'USER': 'cltbld', 16:58:56 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:56 INFO - '_': '/tools/buildbot/bin/python'} 16:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:58:56 INFO - Running setup.py (path:/tmp/pip-__mzEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:58:56 INFO - Running setup.py (path:/tmp/pip-VvWsel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:58:56 INFO - Running setup.py (path:/tmp/pip-WmOhs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:58:56 INFO - Running setup.py (path:/tmp/pip-2MEuNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:58:56 INFO - Running setup.py (path:/tmp/pip-_jPDCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:58:56 INFO - Running setup.py (path:/tmp/pip-eBUsGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:58:56 INFO - Running setup.py (path:/tmp/pip-X_JPyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:58:56 INFO - Running setup.py (path:/tmp/pip-kQgKi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:58:56 INFO - Running setup.py (path:/tmp/pip-cVrMcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:58:56 INFO - Running setup.py (path:/tmp/pip-npVqE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:58:57 INFO - Running setup.py (path:/tmp/pip-ns0oAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:58:57 INFO - Running setup.py (path:/tmp/pip-xJlx4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:58:57 INFO - Running setup.py (path:/tmp/pip-zuKxMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:58:57 INFO - Running setup.py (path:/tmp/pip-wWxtIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:58:57 INFO - Running setup.py (path:/tmp/pip-Fip7PN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:58:57 INFO - Running setup.py (path:/tmp/pip-g1qqeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:58:57 INFO - Running setup.py (path:/tmp/pip-BuldZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:58:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:58:57 INFO - Running setup.py install for manifestparser 16:58:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:58:57 INFO - Running setup.py install for mozcrash 16:58:57 INFO - Running setup.py install for mozdebug 16:58:57 INFO - Running setup.py install for mozdevice 16:58:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:58:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:58:57 INFO - Running setup.py install for mozfile 16:58:58 INFO - Running setup.py install for mozhttpd 16:58:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Running setup.py install for mozinfo 16:58:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Running setup.py install for mozInstall 16:58:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Running setup.py install for mozleak 16:58:58 INFO - Running setup.py install for mozlog 16:58:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Running setup.py install for moznetwork 16:58:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Running setup.py install for mozprocess 16:58:58 INFO - Running setup.py install for mozprofile 16:58:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:58:58 INFO - Running setup.py install for mozrunner 16:58:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:58:59 INFO - Running setup.py install for mozscreenshot 16:58:59 INFO - Running setup.py install for moztest 16:58:59 INFO - Running setup.py install for mozversion 16:58:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:58:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:58:59 INFO - Cleaning up... 16:58:59 INFO - Return code: 0 16:58:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:58:59 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')]} 16:58:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:59 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')]} 16:58:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:59 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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:58:59 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 16:58:59 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 16:58:59 INFO - Using env: {'DISPLAY': ':0', 16:58:59 INFO - 'HOME': '/home/cltbld', 16:58:59 INFO - 'LANG': 'en_US.UTF-8', 16:58:59 INFO - 'LANGUAGE': 'en_US:en', 16:58:59 INFO - 'LOGNAME': 'cltbld', 16:58:59 INFO - 'MAIL': '/var/mail/cltbld', 16:58:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:59 INFO - 'MOZ_NO_REMOTE': '1', 16:58:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:58:59 INFO - 'NO_EM_RESTART': '1', 16:58:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:58:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:59 INFO - 'PWD': '/builds/slave/test', 16:58:59 INFO - 'SHELL': '/bin/bash', 16:58:59 INFO - 'SHLVL': '1', 16:58:59 INFO - 'TERM': 'linux', 16:58:59 INFO - 'TMOUT': '86400', 16:58:59 INFO - 'USER': 'cltbld', 16:58:59 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:58:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:59 INFO - '_': '/tools/buildbot/bin/python'} 16:58:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:58:59 INFO - Running setup.py (path:/tmp/pip-g6SSUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:58:59 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)) 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:58:59 INFO - Running setup.py (path:/tmp/pip-hDkkjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:58:59 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)) 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:58:59 INFO - Running setup.py (path:/tmp/pip-vfdh2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:58:59 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)) 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:58:59 INFO - Running setup.py (path:/tmp/pip-KaSuey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:58:59 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)) 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:58:59 INFO - Running setup.py (path:/tmp/pip-M68JtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:58:59 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)) 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:58:59 INFO - Running setup.py (path:/tmp/pip-sDeV98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:58:59 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)) 16:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:58:59 INFO - Running setup.py (path:/tmp/pip-WJEh4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:00 INFO - Running setup.py (path:/tmp/pip-gZdhG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:00 INFO - Running setup.py (path:/tmp/pip-XfpI72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:00 INFO - Running setup.py (path:/tmp/pip-DdsoiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:00 INFO - Running setup.py (path:/tmp/pip-yCOeaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:00 INFO - Running setup.py (path:/tmp/pip-UmEVdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:00 INFO - Running setup.py (path:/tmp/pip-t4JoGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:59:00 INFO - Running setup.py (path:/tmp/pip-H4c7yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:00 INFO - Running setup.py (path:/tmp/pip-DQ2C8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:59:00 INFO - Running setup.py (path:/tmp/pip-uHxOHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:59:00 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)) 16:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:59:00 INFO - Running setup.py (path:/tmp/pip-exnb85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:59:00 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)) 16:59:00 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)) 16:59:00 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)) 16:59:00 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)) 16:59:00 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)) 16:59:00 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)) 16:59:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:02 INFO - Downloading blessings-1.6.tar.gz 16:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:59:02 INFO - Installing collected packages: blessings 16:59:02 INFO - Running setup.py install for blessings 16:59:02 INFO - Successfully installed blessings 16:59:02 INFO - Cleaning up... 16:59:02 INFO - Return code: 0 16:59:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:59:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:59:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:59:03 INFO - Reading from file tmpfile_stdout 16:59:03 INFO - Current package versions: 16:59:03 INFO - argparse == 1.2.1 16:59:03 INFO - blessings == 1.6 16:59:03 INFO - blobuploader == 1.2.4 16:59:03 INFO - docopt == 0.6.1 16:59:03 INFO - manifestparser == 1.1 16:59:03 INFO - mozInstall == 1.12 16:59:03 INFO - mozcrash == 0.17 16:59:03 INFO - mozdebug == 0.1 16:59:03 INFO - mozdevice == 0.48 16:59:03 INFO - mozfile == 1.2 16:59:03 INFO - mozhttpd == 0.7 16:59:03 INFO - mozinfo == 0.9 16:59:03 INFO - mozleak == 0.1 16:59:03 INFO - mozlog == 3.1 16:59:03 INFO - moznetwork == 0.27 16:59:03 INFO - mozprocess == 0.22 16:59:03 INFO - mozprofile == 0.28 16:59:03 INFO - mozrunner == 6.11 16:59:03 INFO - mozscreenshot == 0.1 16:59:03 INFO - mozsystemmonitor == 0.0 16:59:03 INFO - moztest == 0.7 16:59:03 INFO - mozversion == 1.4 16:59:03 INFO - psutil == 3.1.1 16:59:03 INFO - requests == 1.2.3 16:59:03 INFO - wsgiref == 0.1.2 16:59:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:59:03 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 16:59:03 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 16:59:03 INFO - Using env: {'DISPLAY': ':0', 16:59:03 INFO - 'HOME': '/home/cltbld', 16:59:03 INFO - 'LANG': 'en_US.UTF-8', 16:59:03 INFO - 'LANGUAGE': 'en_US:en', 16:59:03 INFO - 'LOGNAME': 'cltbld', 16:59:03 INFO - 'MAIL': '/var/mail/cltbld', 16:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:03 INFO - 'MOZ_NO_REMOTE': '1', 16:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:59:03 INFO - 'NO_EM_RESTART': '1', 16:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:03 INFO - 'PWD': '/builds/slave/test', 16:59:03 INFO - 'SHELL': '/bin/bash', 16:59:03 INFO - 'SHLVL': '1', 16:59:03 INFO - 'TERM': 'linux', 16:59:03 INFO - 'TMOUT': '86400', 16:59:03 INFO - 'USER': 'cltbld', 16:59:03 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:03 INFO - '_': '/tools/buildbot/bin/python'} 16:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 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)) 16:59:03 INFO - Cleaning up... 16:59:03 INFO - Return code: 0 16:59:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:59:03 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 16:59:03 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 16:59:03 INFO - Using env: {'DISPLAY': ':0', 16:59:03 INFO - 'HOME': '/home/cltbld', 16:59:03 INFO - 'LANG': 'en_US.UTF-8', 16:59:03 INFO - 'LANGUAGE': 'en_US:en', 16:59:03 INFO - 'LOGNAME': 'cltbld', 16:59:03 INFO - 'MAIL': '/var/mail/cltbld', 16:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:03 INFO - 'MOZ_NO_REMOTE': '1', 16:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:59:03 INFO - 'NO_EM_RESTART': '1', 16:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:03 INFO - 'PWD': '/builds/slave/test', 16:59:03 INFO - 'SHELL': '/bin/bash', 16:59:03 INFO - 'SHLVL': '1', 16:59:03 INFO - 'TERM': 'linux', 16:59:03 INFO - 'TMOUT': '86400', 16:59:03 INFO - 'USER': 'cltbld', 16:59:03 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:03 INFO - '_': '/tools/buildbot/bin/python'} 16:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:03 INFO - Downloading/unpacking jsonschema 16:59:03 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 16:59:03 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 16:59:03 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 16:59:03 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 16:59:03 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 16:59:03 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 16:59:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:59:05 INFO - Downloading/unpacking functools32 (from jsonschema) 16:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:59:05 INFO - warning: no files found matching '*.txt' 16:59:05 INFO - no previously-included directories found matching 'build' 16:59:05 INFO - no previously-included directories found matching 'dist' 16:59:05 INFO - no previously-included directories found matching '.git*' 16:59:05 INFO - Installing collected packages: jsonschema, functools32 16:59:05 INFO - Running setup.py install for functools32 16:59:05 INFO - warning: no files found matching '*.txt' 16:59:05 INFO - no previously-included directories found matching 'build' 16:59:05 INFO - no previously-included directories found matching 'dist' 16:59:05 INFO - no previously-included directories found matching '.git*' 16:59:05 INFO - Successfully installed jsonschema functools32 16:59:05 INFO - Cleaning up... 16:59:05 INFO - Return code: 0 16:59:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:59:05 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')]} 16:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:05 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')]} 16:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:05 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 0x7f0f9ea04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c12410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', '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 16:59:05 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 16:59:05 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 16:59:05 INFO - Using env: {'DISPLAY': ':0', 16:59:05 INFO - 'HOME': '/home/cltbld', 16:59:05 INFO - 'LANG': 'en_US.UTF-8', 16:59:05 INFO - 'LANGUAGE': 'en_US:en', 16:59:05 INFO - 'LOGNAME': 'cltbld', 16:59:05 INFO - 'MAIL': '/var/mail/cltbld', 16:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:05 INFO - 'MOZ_NO_REMOTE': '1', 16:59:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:59:05 INFO - 'NO_EM_RESTART': '1', 16:59:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:05 INFO - 'PWD': '/builds/slave/test', 16:59:05 INFO - 'SHELL': '/bin/bash', 16:59:05 INFO - 'SHLVL': '1', 16:59:05 INFO - 'TERM': 'linux', 16:59:05 INFO - 'TMOUT': '86400', 16:59:05 INFO - 'USER': 'cltbld', 16:59:05 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:05 INFO - '_': '/tools/buildbot/bin/python'} 16:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:06 INFO - Downloading/unpacking flake8 16:59:06 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 16:59:06 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 16:59:06 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 16:59:06 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 16:59:06 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 16:59:06 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 16:59:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:59:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:59:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:59:08 INFO - no previously-included directories found matching 'docs/_build' 16:59:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:59:08 INFO - Running setup.py install for pep8 16:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:59:08 INFO - no previously-included directories found matching 'docs/_build' 16:59:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:59:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:59:08 INFO - Cleaning up... 16:59:09 INFO - Return code: 0 16:59:09 INFO - Running post-action listener: _resource_record_post_action 16:59:09 INFO - Running post-action listener: _start_resource_monitoring 16:59:09 INFO - Starting resource monitoring. 16:59:09 INFO - ##### 16:59:09 INFO - ##### Running install step. 16:59:09 INFO - ##### 16:59:09 INFO - Running pre-action listener: _resource_record_pre_action 16:59:09 INFO - Running main action method: install 16:59:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:59:09 INFO - Reading from file tmpfile_stdout 16:59:09 INFO - Detecting whether we're running mozinstall >=1.0... 16:59:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:59:09 INFO - Reading from file tmpfile_stdout 16:59:09 INFO - Output received: 16:59:09 INFO - Usage: mozinstall [options] installer 16:59:09 INFO - Options: 16:59:09 INFO - -h, --help show this help message and exit 16:59:09 INFO - -d DEST, --destination=DEST 16:59:09 INFO - Directory to install application into. [default: 16:59:09 INFO - "/builds/slave/test"] 16:59:09 INFO - --app=APP Application being installed. [default: firefox] 16:59:09 INFO - mkdir: /builds/slave/test/build/application 16:59:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:59:24 INFO - Reading from file tmpfile_stdout 16:59:24 INFO - Output received: 16:59:24 INFO - /builds/slave/test/build/application/firefox/firefox 16:59:24 INFO - Running post-action listener: _resource_record_post_action 16:59:24 INFO - ##### 16:59:24 INFO - ##### Running run-tests step. 16:59:24 INFO - ##### 16:59:24 INFO - Running pre-action listener: _resource_record_pre_action 16:59:24 INFO - Running main action method: run_tests 16:59:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:59:24 INFO - Python 2.7.3 16:59:24 INFO - Return code: 0 16:59:24 INFO - grabbing minidump binary from tooltool 16:59: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')]} 16:59:24 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca1160>, '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 0x2ca2060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca24f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:59:24 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 16:59:24 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 16:59:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:59:24 INFO - Return code: 0 16:59:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:59:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:59:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:59:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:59:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:59:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:59:26 INFO - Return code: 0 16:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:59:26 INFO - Using env: {'DISPLAY': ':0', 16:59:26 INFO - 'HOME': '/home/cltbld', 16:59:26 INFO - 'LANG': 'en_US.UTF-8', 16:59:26 INFO - 'LANGUAGE': 'en_US:en', 16:59:26 INFO - 'LOGNAME': 'cltbld', 16:59:26 INFO - 'MAIL': '/var/mail/cltbld', 16:59:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:59:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:26 INFO - 'MOZ_NO_REMOTE': '1', 16:59:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:59:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:59:26 INFO - 'NO_EM_RESTART': '1', 16:59:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:26 INFO - 'PWD': '/builds/slave/test', 16:59:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:59:26 INFO - 'SHELL': '/bin/bash', 16:59:26 INFO - 'SHLVL': '1', 16:59:26 INFO - 'TERM': 'linux', 16:59:26 INFO - 'TMOUT': '86400', 16:59:26 INFO - 'USER': 'cltbld', 16:59:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252', 16:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:26 INFO - '_': '/tools/buildbot/bin/python'} 16:59:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:59:26 INFO - mozversion application_buildid: 20160422142650 16:59:26 INFO - mozversion application_changeset: 922334c70324e4295dcfcfafe6da4ae2e1154834 16:59:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:59:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:59:26 INFO - mozversion application_name: Firefox 16:59:26 INFO - mozversion application_remotingname: firefox-dev 16:59:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:59:26 INFO - mozversion application_vendor: Mozilla 16:59:26 INFO - mozversion application_version: 47.0a2 16:59:26 INFO - mozversion platform_buildid: 20160422142650 16:59:26 INFO - mozversion platform_changeset: 922334c70324e4295dcfcfafe6da4ae2e1154834 16:59:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:59:26 INFO - mozversion platform_version: 47.0a2 16:59:26 INFO - using testdate: 1461369566 16:59:26 INFO - actual date: 1461369566 16:59:26 INFO - starting webserver on 'localhost:38271' 16:59:26 INFO - SUITE-START | Running 2 tests 16:59:26 INFO - TEST-START | dromaeo_css 16:59:26 INFO - operating with platform_type : linux_ 16:59:26 INFO - Initialising browser for dromaeo_css test... 16:59:26 INFO - TEST-INFO | started process 3299 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo_jbTK/profile http://localhost:38271/getInfo.html) 16:59:30 INFO - PROCESS | 3299 | 16:59:30 INFO - PROCESS | 3299 | (process:3377): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:59:31 INFO - PROCESS | 3299 | __metrics Screen width/height:1600/1200 16:59:31 INFO - PROCESS | 3299 | colorDepth:24 16:59:31 INFO - PROCESS | 3299 | Browser inner width/height: 1024/697 16:59:31 INFO - PROCESS | 3299 | __metrics 16:59:32 ERROR - PROCESS | 3299 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 16:59:33 INFO - TEST-INFO | 3299: exit 0 16:59:33 INFO - Browser initialized. 16:59:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:59:33 INFO - TEST-INFO | started process 3428 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo_jbTK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:59:35 INFO - PROCESS | 3428 | 16:59:35 INFO - PROCESS | 3428 | (process:3498): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:59:45 INFO - PROCESS | 3428 | RSS: Main: 151085056 16:59:45 INFO - PROCESS | 3428 | 17:00:34 INFO - PROCESS | 3428 | 1461369634693 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] 17:02:42 INFO - PROCESS | 3428 | Cycle 1(1): loaded http://localhost:38271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38271/tests/dromaeo/cssquery-ext.html) 17:02:43 INFO - PROCESS | 3428 | RSS: Main: 155750400 17:02:43 INFO - PROCESS | 3428 | 17:05:45 INFO - PROCESS | 3428 | Cycle 1(1): loaded http://localhost:38271/tests/dromaeo/cssquery-ext.html (next: http://localhost:38271/tests/dromaeo/cssquery-jquery.html) 17:05:46 INFO - PROCESS | 3428 | RSS: Main: 153440256 17:05:46 INFO - PROCESS | 3428 | 17:08:47 INFO - PROCESS | 3428 | Cycle 1(1): loaded http://localhost:38271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38271/tests/dromaeo/cssquery-mootools.html) 17:08:48 INFO - PROCESS | 3428 | RSS: Main: 153415680 17:08:48 INFO - PROCESS | 3428 | 17:11:50 INFO - PROCESS | 3428 | Cycle 1(1): loaded http://localhost:38271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38271/tests/dromaeo/cssquery-prototype.html) 17:11:50 INFO - PROCESS | 3428 | RSS: Main: 152788992 17:11:50 INFO - PROCESS | 3428 | 17:14:53 INFO - PROCESS | 3428 | Cycle 1(1): loaded http://localhost:38271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38271/tests/dromaeo/cssquery-yui.html) 17:14:53 INFO - PROCESS | 3428 | RSS: Main: 153714688 17:14:53 INFO - PROCESS | 3428 | 17:17:55 INFO - PROCESS | 3428 | Cycle 1(1): loaded http://localhost:38271/tests/dromaeo/cssquery-yui.html (next: http://localhost:38271/tests/dromaeo/cssquery-dojo.html) 17:17:56 INFO - PROCESS | 3428 | RSS: Main: 155852800 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | __start_tp_report 17:17:56 INFO - PROCESS | 3428 | _x_x_mozilla_page_load 17:17:56 INFO - PROCESS | 3428 | _x_x_mozilla_page_load_details 17:17:56 INFO - PROCESS | 3428 | |i|pagename|runs| 17:17:56 INFO - PROCESS | 3428 | |0;dojo.html;3336.132605522564;3407.5570175877133;3421.5894092708877;3450.1202193440668;3458.602260740014;5704.800331988381;5708;5713.742881570329;5736.5984381093285;5746.511546518545;5659.886802263953;5686.886262274752;5694.857628559278;5708.1437784332375;5724.7137643117785;5153.3815942087;5176.55999240065;5230.8430747077455;5254.527092561669;5309.097453432925;4997.625178111638;5102.387713474388;5282.418933917266;5286.524212820846;5287.39194992575;5083.593312534988;5162.432132465426;5196.714180720059;5218.765155568009;5276.393214780292;5264.8157314494;5265.52610265078;5265.578753699695;5276.15581506957;5294.100002999461;5023.472535383768;5045;5065.569426598743;5165.63840531163;5249.790008399659;5478.315210598676;5528.198411230349;5533.5849811263915;5571.693556854371;5571.693556854371;6133.6319820810895;6189.690515474208;6194.5044396448175;6216.067589861512;6220.533459990474;5916.4675179233645;6134.049222370495;6194.659293738842;6200.441960223601;6218.31598524162;5802.216700745387;5806.593538452352;5807;5817.534597232254;5820.912686309709;6124.112003759519;6360.300366959631;6363.300036995926;6365.649889256043;6376.3623637635865;6034.275886893601;6042.879142417215;6093.725782339805;6100.908486372708;6130.846728831726;6139.758987969359;6157.8460538486;6188.690565471708;6198.752049918043;6202.379762023762;5864;5999.370066143079;6085.92182497994;6113.29387775398;6114.354250337185;6046.727897244635;6137.0794380842335;6137.483631710977;6156.846078847975;6168.691565421801;4638.0491999140095;4648.209804333272;4677.64917631179;4683.742394168354;4713.552212539803;4043.6158564936286;4052.8378864845076;4116.855910043134;4199.559046300155;4216.093539888877;5474.534664553478;5844.824655260264;5877.647341159544;5924.7037648118285;5939.22790037301;6050.818475445743;6062.151298818169;6085.5435842312;6116;6118.663473508911;5653.349864765522;5747.339056008613;5763;5776.711164441761;5797.420257974253;5300.9734951325;5315.096433606296;5331.360236771612;5348.786048558092;5361.276227709287;6348.619082855043;6384.3934826191435;6398.520110991694;6398.8080357588415;6416.935830641727;6024.789132380346;6107.6640784757265;6171.876562468815;6202.4417802398;6246.469050130699;6339.90490142639;6341.7780377688405;6348.428641422294;6350.428461438492;6361.904571431525;6105.267367915879;6112.083187521729;6114.785982490592;6116.082587611894;6118.938810611837;5956.8808623828145;6119.632822030692;6136.73353236607;6195.2875419325555;6200.255969283715;6006.459418652343;6033.3061697905305;6039.849003774941;6045.0327947528185;6054;6026.306974697965;6040.75836966534;6048.0625503047095;6048.455638992512;6054.122152287806;5654.236678048411;5920.703964801829;5953.017752070859;5970.880582388415;6003.849903752266;6003.339632640494;6010.33886272501;6018.007028840192;6065.363136870565;6078.057901025348;5692.203091567267;5950.2264705587995;5960.0761881908375;5962.25471816023;5973.940260597512 17:17:56 INFO - PROCESS | 3428 | |1;ext.html;549.4454239311933;566.2299272988736;568.4571234471081;609.9695015249239;615.5691016288598;37277.25445491092;37838.24323513535;38228.23543529128;38355.42466862992;38457.42313865294;38959;39130.413043804365;39195.80402097993;39360.80319598398;39504.80247598758;8571.057183709803;8704.608292626846;8717.302615790753;8718.607662655195;8737.475751454933;8508.319334453228;8770.56147192641;8784.429012114193;8820.73537793864;8836.248918841875;8751.3874028818;8768.123187681213;8831.735047948572;8852.690155844546;8868.556572171397;8842.204201621853;8845.425047371964;8846.071162527938;8869.2017718405;8937.553122343921;8896.377253592187;8959.641614335418;8999.415038022573;9051.185393314567;9065.048169942127;39730.80134599337;40078.59921400778;40166.799166003984;40210.79894600537;40273.395899061536;3576.8390422430793;3817.2556351511466;4215.45199124115;4281.443412356405;4352.956470435287;31421.528677069862;32049.198770030936;32164.356712865847;32335;32945;4204.810783514749;4243.787810609457;4254.553271906436;4265.808038638269;4285.764282964436;268927;269137;269516;270310;271163.999999998;63459.6827015862;63957.36042639607;64332.35667643264;64578.354216458174;64604.67697661481;74482.25517744753;74515.62742186255;74692.25307746853;74918.25081749221;74924.25075749282;15287.770683439758;15320.157391343582;15349.769753453707;15417.229138543027;15497.07017578949;11345.13619260352;11347.886521134847;11353.943230283961;11409.771804563863;11438.714032149097;21732.34802955914;22054.448638784157;22108.447288817908;22265.888670556542;22829.7717022831;7662.1571627120975;7900.525968441912;7987.960060199662;8012.639431225609;8038.557879316693;1022.6341027106205;1066.157735389034;1117.709395557161;1138.135017386792;1206.2099324942176;29494;29633.703662963093;29773.553396699066;30234.54648180235;30367.392652146835;963.5663951221978;978.2760757039881;984.7488890333062;990.5740531571494;1166.6033548593518;15326.972073737457;15335.386584536494;15477.380904763684;15586.376544938303;15605.141717205663;317454;514372;516914;517196;520254;53786.193207101926;54747.45252547503;55499;55632;55658.44341556533;1488.8138982627172;1498.1460567476652;1498.2808252039083;1500.647347873249;1511.1915125407854;25268.747312527375;25821.870890645427;25927;26136.215913522625;26161.869190654685;42227;42424.5757542433;42752.358714618684;42849.57150428581;43395.132097357244;280818;281230;281692;282258;283874;34117.147071323416;34191.65808341885;34362.484562732076;34665.82667086648;35075.29849403049;45324.32013519866;45827.77086114548;46538.53461465342;46772.29841552312;46916.29625555551;38030.80984595059;38066.80966595149;38794;39432.21135577215;40346.39480407855;38250.85247442581;38325.42511862268;39204.80397598108;39641.60358396379;39742;38162.42756358601;38321.23357532778;38828.80585597054;39382.2123557533;39513.80243098881;33299.606744652905;33514;33939.981800546026;34079;34156.31687366289;24968.250952471455;26551;26641.8667906667;26781.330466738487;26820.32949176208 17:17:56 INFO - PROCESS | 3428 | |2;jquery.html;371.2815701617371;388.6191532298346;389.27983231022614;393.58476806968656;401.79508450690173;17101.91449042756;17672.29310827567;18123.45629631113;18216;18337.266509339643;16929.407470738526;17233.82766172338;17244.482665520023;17383.565410864736;17449.91275043623;5176.948230517703;5576.079946808792;5600.383957764642;5630.915536266939;5646.322441307049;5169.508896654812;5315.681059136445;5350.3312085989255;5362.4637536246255;5364.839054828361;5227.477252274761;5316.20256961455;5332.760025798849;5668.801591944282;5749.798757043504;5734.741936612862;5764.798232061858;5769.451902069297;5777.88444231113;5815.505682017034;5455.318085239345;5541.750621222055;5551.944480555224;5573.804916827891;5579.302587176603;13494.797578036338;13579.660508487268;13591.660208494768;13661.316934153252;17279.74080388795;2104.926327578543;2221.9888900555557;2291.450051987528;2358.622620380731;2367.360812580611;9793.755156121082;9965.80068398628;9969.302148849583;11185.66443006711;12685.74628507434;1837.3110083718605;1862.9813701862943;1913.1582103874389;1993.362124120282;2072.823809976154;63753.68123159401;65112.02331964977;65284;65413.67293163551;65961;10220.846687299696;10532.47279144535;10584.682459526226;10610.893891061145;10729.83905241422;14413.567592972227;14537.92731036338;14552.708945821025;14573.92713036428;14674.926625366805;7339.816504587321;7542.434317426105;7787.29914307715;7823.765287041398;7841.843163136819;7211.8557628848175;7225.710971561184;7232.421406287484;7234.421246300283;7430.702771889172;4304.849330273425;4488.506264310923;4522.095580883771;4595.6783025187915;4682.672212945061;1891.186789680423;1947.8831270123555;1964.9662599209748;1966.4002479243682;2022.9393118206478;809.6032943857585;851.5103815306223;945.8770359853175;950.9952450237705;1109.8580633622664;16696.66606667884;17330.133493325284;17457.388991385495;17626.647467050843;17959.461216163538;995.9900400996042;1024.134606257711;1040.9115225205792;1059.422614675004;1067.4449286371043;9833.4099954003;10160.187185025181;10278.17774578032;10295.794084118275;10399.636012739518;160790;162294;261687;264305;440442;76159;76487.23512764912;76570.61714691391;78896.21103789004;79698;1007.4408703169664;1011.5852500474917;1012.9392236465688;1023.2019025160387;1028.6759670703705;15846.207689615703;15992.600184995003;16255.431059912846;16365.181740913145;16444;34211;34426;34724.47913281252;34913;34981.47527787136;50186;50493;50612.74693626508;50713.98572028465;50833;7890.644920978455;7932.801679958047;7977.321927636216;8047.114817369968;8147.511149331059;10491.94754026225;11753.059755219732;12101.81847272274;12321.137520373488;12737.93631031839;10270.794584108127;10377.117944974761;10388.42863642507;10604.681859544226;10958.671239862817;10275.537600807833;10535.4732263385;10706.571737130427;10748.623798167027;11037.944810276218;10767.246292759432;10785.784284314426;10962.835557466486;11100.000999909724;11215.1027917768;8179.0594081681365;8209.630566624397;8222.547759873265;8323.95838020806;8605.698800541952;8872.600732967161;9698.95150524271;10470.371777693363;10694.89305106939;10754.193435492525 17:17:56 INFO - PROCESS | 3428 | |3;mootools.html;1383.584924522643;1457.0237940579811;1572.2846105022215;1928.6817675083612;1948.6492431362358;7320.450966177545;7399.741009064684;8396.62215200316;8682.913170868298;8732.912670873282;7573.431992600563;7781.7665470035845;8132;8353.081161072278;8374.916250837505;2454.656348111261;2475.356407334097;2483.1805504183667;2496.425822060927;2507.7868381187623;2360.7639236076425;2415.2150551070886;2422.9273121806373;2424.49085692004;2426.8665223412704;2463.7289898111194;2467.962980555293;2475.1955614425383;2483.913063042794;2496.400863792686;2465.3590066582638;2472.4560596668703;2474.653548503202;2477.541654793869;2503.6494890715317;2452.546278938402;2468.5556599812026;2473.2703852363597;2492.5887228607257;2529.304441278645;8437.198466145706;8481.872771908425;8484.448510846836;8498.490090594583;8507.872381914276;5060.51925067118;5168.3797944246935;5181.792728290864;5190.844274671766;5200.453952335026;3246.1722260823576;3255.5930508686415;3275.9836200818845;3278.327942771726;3319.3029463812627;1001.5593139018808;1989.263972330247;2007.1068374573322;2012.0442789674933;2012.1146695453954;10708.089812365959;10778.5688572457;10782.353058816418;10790.136789056856;10812.243142980073;4183.832646694098;4290.099079193342;4307.913841723148;4354.259775838115;4360;8363.498190108612;8375.497470151811;8421.284190843724;8429.915700843036;8450.492970421794;4997.9750101249265;5039.647224694236;5042.924356134661;5052.595792336604;5063.797448102035;4589.770511474413;4666.323383109498;4706.388169537938;4728.787204575802;4739.241721324641;3687.391580389206;3775.7356985011334;3779.6787273081545;3866.130120722871;3934.0361611404815;1114.0920150077661;1125.2517076144272;1139.4530625299737;1173.9119566032582;1178.1458442629196;1163.610190586163;1167.2121318110242;1171.496256609666;1173.313611537243;1175.794236008695;7646.847063058708;7694.422918281151;7704.576248306397;7704.6918123274445;7836.647350869224;1096.2326371539793;1110.6001839337832;1111.8165502691966;1116.7766446710527;1117.502711293483;6413.807585772341;6419.165508483868;6451.3226111257645;6452.12896258999;6486.254080780771;9511.191548718298;10263.384196948058;10278.486075696335;10412.375257484575;10470.424126672953;10141.746456338504;10157.035081667229;10160.745981350525;10212.846807297896;10301.845472317922;8028.799280017929;8080.909077274493;8124.5937703114605;8158.143394943564;8175.836483270301;7596.506227095221;7675.117361503499;7717.382609391123;7736;7772.611369431619;9417.293702972167;9469.289803264921;9620.663276785279;9622.326437149331;9715.659951901649;9544.713658590254;9768.120869121529;9833.360831545926;9962.750931226487;9963.900360996297;6199.194104766351;6819.215790184192;7070.823229419306;7309.963450182715;7385.556866588022;8221.383396245425;8256.711015114442;8260.380471464785;8265.256126948603;8273.089960104504;7185.604791736296;7204.459665525002;7225.494215404871;7314.232005639331;7397.889031664422;7294.598797066213;7365.889511657222;7374.557526548209;7375.594342311224;7414.073240844895;7946.32456241226;7950.721724739838;7963.880541791994;8024.197580241929;8047.557384343917;6927.134108236471;6939.236683964659;6978.581285122909;6999.790006299819;7006.369426751617;7108.893366599608;7181.712731490682;7189.5686258824635;7204;7220.855582888208 17:17:56 INFO - PROCESS | 3428 | |4;prototype.html;369.1896287648611;371.6840685417394;373.42865415913644;375.6581510825149;380.4464504146469;6363.172787537619;6460.644664543448;6471.70877310521;6482.6434546099945;6491.902621460682;6370;6380.680965951707;6382.234131904178;6383.936160638404;6389.393007664276;4705.058988202356;4805.231163013918;4878.512148785128;4894;4938.629602779797;4883.731394773286;4885.193942999401;4885.6335774817035;4902.3872015998;4904;4832.420109586837;4853.587445067174;4858.246971719372;4867.415910090794;4868.537488938563;4847.466778654345;4869.902601947959;4946.788036930975;4976.850694479172;4978.8257410990445;4376.599488163789;4384.101259241879;4388.561143885618;4394.055278115221;4397.714148580332;5986.670733109677;6093.512518998514;6099.268087829444;6113.419225173601;6114.266288045418;5805.709714514257;5826.621269617462;5827.941720582825;5830.30036395631;5831.475167234969;6763.120794296759;6766.661666916634;6768.120144381248;6774;6829.078074459934;4851.369321988154;4855.077535268323;4856.0287942411305;4856.077345304381;4859.708417494961;7055.470839686993;7088.751893683696;7097.077379940574;7099.112610923687;7109.786706398816;4678.6491013174145;4815.831445899446;4838;4973.9306049199595;4988.276699878497;4918.9508104919205;4931.802727890916;4945.258211268267;4947.282644016597;4955.776990035457;5700.7719691212715;5709.685967271756;5714.571407144481;5724.8282551523525;5725.255716756795;6833.384995350442;6836.555623884432;6837.453003759687;6839.350261725128;6840.657967101625;1726.7582538444628;1735.2625134318116;1744.5551384396897;1752.9123543822757;1756.1570446185901;1108;1152.181950814914;1157.2246594781516;1162.96511104667;1168.6844551970921;6548.345165483413;6569.343065693488;6569.4744420447205;6582.769603063965;6601;6926.722931082801;6998.405135563432;7017.052697885822;7027.61348125858;7054.6825392857445;6448.77429289982;6648.8670226595195;6711.261761206263;6715.46276297895;6727.024581435664;3814.5231846019246;3832.3485007548784;3866.323393406199;3869.8839034829;3870.2390412362306;7276.235995220532;7285.271472852778;7306.378957788647;7323.963380183065;7330.926690733025;7301.37938275242;7324.267573242633;7328.78013659591;7334.999999999894;7336;7051.576905385693;7177.856442871113;7183.173934997333;7194.964025179841;7202.891956620655;6986.16166060076;7060.117485314336;7063;7069.363757261665;7082.681279342545;6971.163460384583;6980.650967451708;6989.95150727395;7001.264867188872;7003.474739394667;7155.105611798525;7274.345308922222;7281.5631062136445;7288.817779555554;7308.890366644611;4844.975775121102;4888.755562221803;4914.582260507754;4919.237518184687;4919.827806026772;7061.082059332254;7080.468964827762;7085.964570177116;7127.2516385778745;7134;6848.931510684829;6867.416269617139;6885.449164066963;6916.93083069183;6917.169939607279;6884.8967265490055;6896.655167241718;6900.585964842126;6901.068355772108;6905.240423553506;6551.475881929339;6762.120924279812;6767.39093481589;6772.424343930821;6830.658467076726;6856.485763567852;6858.828529286808;6941.271166527644;6961.408280296231;6963.477739169683;6742.764003260058;6759.932400675931;6776.559523630948;6779.288174741581;6792.083068785848 17:17:56 INFO - PROCESS | 3428 | |5;yui.html;718.339128002238;718.4575645387732;766.9539827610347;788.6372268756372;805.589149533738;14064.507742229023;14280.71438571228;14300.356483958221;14335.35490902908;14353.856461435382;12786.808197877039;12888.16226945248;13022.283774392405;14017;15310;903.2638399704236;913.447364344572;918.9678361257357;920.3051695969534;920.3235621817959;897.851854444016;903.6430609909082;905.9184673379394;905.9320550958704;906.4651855405309;960.3661583354988;960.9135177833994;963.6241865672358;963.8313295173317;967.6613185385129;953.8330792111353;953.9713808585719;954.0650162840386;957.7270477913761;964.7684555706614;704.9506534542584;705.4109818301724;706.0538877903582;708.5961002228731;716.5199316457986;13907.58277251684;13983;13988.95082868789;13994.93002534991;14067.929660351736;12441.191322564095;13151.539696110685;13742.587722368346;13930.37313320903;13976;10270.281080324381;10283.382997020202;10362.326448780881;10522.579096836118;10538.631147909862;3835.673967712748;3865.7487263327794;3875.9224815503812;3886.6485230139697;3896.7467114637743;52994.20508692373;53595.19607205895;53635.73182134103;53766.73116634431;54065.72967135139;10080.29437939352;10103.949480252551;10235.897641023643;10325.896741032493;10459.84310235347;19264.80735192658;19345.51636209078;19366;19367.515812104528;19370.32203872886;7392.667329970164;7472.007318867889;7521.661525231378;7576.696932122763;7593.164751877289;4244.554321796167;4319.222539942778;4375.584319489643;4403.295472724349;4422.84520041797;3140.7173354398215;3147.4806656901355;3193.5369371441475;3196.408664397094;3199.056278397878;559.9580031497654;575.0798722044696;578.2800413485238;584.590786449487;588.1413136820205;521.7026295011883;594.7442599682091;632.550888868899;648.6173157836898;658.1411258307859;8511.829763404698;8535.445196062235;8554.230119289294;8796.736097917073;8967.820643587092;558.2491548866768;573.4408951272476;594.1029046140359;630.8612105336819;632.9335419780857;3782.6973842092566;4068.5117785865887;4092.7953602319767;4122.299209134454;4219.472565929259;63060;63459.6827015862;63617.36382636207;63622.68188659027;63847.6807615959;52497.2125418119;53660;56460;56539.43460565342;62053.37946620476;47780.5221947783;48272.27591586129;48514;51237.97524049498;51391.7430412853;8420.915790842013;8453.873191902003;8468.872966905625;8691;8704.434211776213;37626;37797.24405511929;38579.228415430975;38926.80536597299;39204.21591568096;47612.52387476081;47830.521694782605;47953.28070078882;49148.75425622849;49431.50568494269;10858.077063449695;11309.377984210947;11578.71053223676;11808.881911180779;12300.81548776787;11404.144689148181;11609.535618575163;12094.334811585448;12127.332996685267;12815.61553153407;10853.294535855144;11096.33421994683;11123.555057797921;11254.887451125383;11459.19785614999;10796.946015269874;11020.118390528578;11229.943850280697;11378.601748938747;11437.542498299976;10711;10742.51658675377;10750.08624266946;10754.569817207224;10960.28758130719;9507.524623768923;9617.663381781604;9619.51902404891;9735.659251926148;9994.650187243411;9187.310951678784;9357.625694972125;9410.858837117312;9517.809643807224;9567.712968610675 17:17:56 INFO - PROCESS | 3428 | __end_tp_report 17:17:56 INFO - PROCESS | 3428 | __start_cc_report 17:17:56 INFO - PROCESS | 3428 | _x_x_mozilla_cycle_collect,347 17:17:56 INFO - PROCESS | 3428 | __end_cc_report 17:17:56 INFO - PROCESS | 3428 | __startTimestamp1461370676035__endTimestamp 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | ------- Summary: start ------- 17:17:56 INFO - PROCESS | 3428 | Number of tests: 6 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | [#0] dojo.html Cycles:165 Average:5698.57 Median:5956.88 stddev:650.48 (10.9%) stddev-sans-first:625.52 17:17:56 INFO - PROCESS | 3428 | Values: 3336.1 3407.6 3421.6 3450.1 3458.6 5704.8 5708.0 5713.7 5736.6 5746.5 5659.9 5686.9 5694.9 5708.1 5724.7 5153.4 5176.6 5230.8 5254.5 5309.1 4997.6 5102.4 5282.4 5286.5 5287.4 5083.6 5162.4 5196.7 5218.8 5276.4 5264.8 5265.5 5265.6 5276.2 5294.1 5023.5 5045.0 5065.6 5165.6 5249.8 5478.3 5528.2 5533.6 5571.7 5571.7 6133.6 6189.7 6194.5 6216.1 6220.5 5916.5 6134.0 6194.7 6200.4 6218.3 5802.2 5806.6 5807.0 5817.5 5820.9 6124.1 6360.3 6363.3 6365.6 6376.4 6034.3 6042.9 6093.7 6100.9 6130.8 6139.8 6157.8 6188.7 6198.8 6202.4 5864.0 5999.4 6085.9 6113.3 6114.4 6046.7 6137.1 6137.5 6156.8 6168.7 4638.0 4648.2 4677.6 4683.7 4713.6 4043.6 4052.8 4116.9 4199.6 4216.1 5474.5 5844.8 5877.6 5924.7 5939.2 6050.8 6062.2 6085.5 6116.0 6118.7 5653.3 5747.3 5763.0 5776.7 5797.4 5301.0 5315.1 5331.4 5348.8 5361.3 6348.6 6384.4 6398.5 6398.8 6416.9 6024.8 6107.7 6171.9 6202.4 6246.5 6339.9 6341.8 6348.4 6350.4 6361.9 6105.3 6112.1 6114.8 6116.1 6118.9 5956.9 6119.6 6136.7 6195.3 6200.3 6006.5 6033.3 6039.8 6045.0 6054.0 6026.3 6040.8 6048.1 6048.5 6054.1 5654.2 5920.7 5953.0 5970.9 6003.8 6003.3 6010.3 6018.0 6065.4 6078.1 5692.2 5950.2 5960.1 5962.3 5973.9 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | [#1] ext.html Cycles:180 Average:51602.37 Median:39282.80 stddev:95081.54 (242.0%) stddev-sans-first:95271.00 17:17:56 INFO - PROCESS | 3428 | Values: 549.4 566.2 568.5 610.0 615.6 37277.3 37838.2 38228.2 38355.4 38457.4 38959.0 39130.4 39195.8 39360.8 39504.8 8571.1 8704.6 8717.3 8718.6 8737.5 8508.3 8770.6 8784.4 8820.7 8836.2 8751.4 8768.1 8831.7 8852.7 8868.6 8842.2 8845.4 8846.1 8869.2 8937.6 8896.4 8959.6 8999.4 9051.2 9065.0 39730.8 40078.6 40166.8 40210.8 40273.4 3576.8 3817.3 4215.5 4281.4 4353.0 31421.5 32049.2 32164.4 32335.0 32945.0 4204.8 4243.8 4254.6 4265.8 4285.8 268927.0 269137.0 269516.0 270310.0 271164.0 63459.7 63957.4 64332.4 64578.4 64604.7 74482.3 74515.6 74692.3 74918.3 74924.3 15287.8 15320.2 15349.8 15417.2 15497.1 11345.1 11347.9 11353.9 11409.8 11438.7 21732.3 22054.4 22108.4 22265.9 22829.8 7662.2 7900.5 7988.0 8012.6 8038.6 1022.6 1066.2 1117.7 1138.1 1206.2 29494.0 29633.7 29773.6 30234.5 30367.4 963.6 978.3 984.7 990.6 1166.6 15327.0 15335.4 15477.4 15586.4 15605.1 317454.0 514372.0 516914.0 517196.0 520254.0 53786.2 54747.5 55499.0 55632.0 55658.4 1488.8 1498.1 1498.3 1500.6 1511.2 25268.7 25821.9 25927.0 26136.2 26161.9 42227.0 42424.6 42752.4 42849.6 43395.1 280818.0 281230.0 281692.0 282258.0 283874.0 34117.1 34191.7 34362.5 34665.8 35075.3 45324.3 45827.8 46538.5 46772.3 46916.3 38030.8 38066.8 38794.0 39432.2 40346.4 38250.9 38325.4 39204.8 39641.6 39742.0 38162.4 38321.2 38828.8 39382.2 39513.8 33299.6 33514.0 33940.0 34079.0 34156.3 24968.3 26551.0 26641.9 26781.3 26820.3 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | [#2] jquery.html Cycles:180 Average:20607.82 Median:132027.18 stddev:46852.39 (35.5%) stddev-sans-first:46959.19 17:17:56 INFO - PROCESS | 3428 | Values: 371.3 388.6 389.3 393.6 401.8 17101.9 17672.3 18123.5 18216.0 18337.3 16929.4 17233.8 17244.5 17383.6 17449.9 5176.9 5576.1 5600.4 5630.9 5646.3 5169.5 5315.7 5350.3 5362.5 5364.8 5227.5 5316.2 5332.8 5668.8 5749.8 5734.7 5764.8 5769.5 5777.9 5815.5 5455.3 5541.8 5551.9 5573.8 5579.3 13494.8 13579.7 13591.7 13661.3 17279.7 2104.9 2222.0 2291.5 2358.6 2367.4 9793.8 9965.8 9969.3 11185.7 12685.7 1837.3 1863.0 1913.2 1993.4 2072.8 63753.7 65112.0 65284.0 65413.7 65961.0 10220.8 10532.5 10584.7 10610.9 10729.8 14413.6 14537.9 14552.7 14573.9 14674.9 7339.8 7542.4 7787.3 7823.8 7841.8 7211.9 7225.7 7232.4 7234.4 7430.7 4304.8 4488.5 4522.1 4595.7 4682.7 1891.2 1947.9 1965.0 1966.4 2022.9 809.6 851.5 945.9 951.0 1109.9 16696.7 17330.1 17457.4 17626.6 17959.5 996.0 1024.1 1040.9 1059.4 1067.4 9833.4 10160.2 10278.2 10295.8 10399.6 160790.0 162294.0 261687.0 264305.0 440442.0 76159.0 76487.2 76570.6 78896.2 79698.0 1007.4 1011.6 1012.9 1023.2 1028.7 15846.2 15992.6 16255.4 16365.2 16444.0 34211.0 34426.0 34724.5 34913.0 34981.5 50186.0 50493.0 50612.7 50714.0 50833.0 7890.6 7932.8 7977.3 8047.1 8147.5 10491.9 11753.1 12101.8 12321.1 12737.9 10270.8 10377.1 10388.4 10604.7 10958.7 10275.5 10535.5 10706.6 10748.6 11037.9 10767.2 10785.8 10962.8 11100.0 11215.1 8179.1 8209.6 8222.5 8324.0 8605.7 8872.6 9699.0 10470.4 10694.9 10754.2 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | [#3] mootools.html Cycles:180 Average:5861.85 Median:5175.09 stddev:2992.50 (57.8%) stddev-sans-first:2981.96 17:17:56 INFO - PROCESS | 3428 | Values: 1383.6 1457.0 1572.3 1928.7 1948.6 7320.5 7399.7 8396.6 8682.9 8732.9 7573.4 7781.8 8132.0 8353.1 8374.9 2454.7 2475.4 2483.2 2496.4 2507.8 2360.8 2415.2 2422.9 2424.5 2426.9 2463.7 2468.0 2475.2 2483.9 2496.4 2465.4 2472.5 2474.7 2477.5 2503.6 2452.5 2468.6 2473.3 2492.6 2529.3 8437.2 8481.9 8484.4 8498.5 8507.9 5060.5 5168.4 5181.8 5190.8 5200.5 3246.2 3255.6 3276.0 3278.3 3319.3 1001.6 1989.3 2007.1 2012.0 2012.1 10708.1 10778.6 10782.4 10790.1 10812.2 4183.8 4290.1 4307.9 4354.3 4360.0 8363.5 8375.5 8421.3 8429.9 8450.5 4998.0 5039.6 5042.9 5052.6 5063.8 4589.8 4666.3 4706.4 4728.8 4739.2 3687.4 3775.7 3779.7 3866.1 3934.0 1114.1 1125.3 1139.5 1173.9 1178.1 1163.6 1167.2 1171.5 1173.3 1175.8 7646.8 7694.4 7704.6 7704.7 7836.6 1096.2 1110.6 1111.8 1116.8 1117.5 6413.8 6419.2 6451.3 6452.1 6486.3 9511.2 10263.4 10278.5 10412.4 10470.4 10141.7 10157.0 10160.7 10212.8 10301.8 8028.8 8080.9 8124.6 8158.1 8175.8 7596.5 7675.1 7717.4 7736.0 7772.6 9417.3 9469.3 9620.7 9622.3 9715.7 9544.7 9768.1 9833.4 9962.8 9963.9 6199.2 6819.2 7070.8 7310.0 7385.6 8221.4 8256.7 8260.4 8265.3 8273.1 7185.6 7204.5 7225.5 7314.2 7397.9 7294.6 7365.9 7374.6 7375.6 7414.1 7946.3 7950.7 7963.9 8024.2 8047.6 6927.1 6939.2 6978.6 6999.8 7006.4 7108.9 7181.7 7189.6 7204.0 7220.9 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | [#4] prototype.html Cycles:180 Average:5732.45 Median:6520.12 stddev:1720.51 (26.4%) stddev-sans-first:1677.58 17:17:56 INFO - PROCESS | 3428 | Values: 369.2 371.7 373.4 375.7 380.4 6363.2 6460.6 6471.7 6482.6 6491.9 6370.0 6380.7 6382.2 6383.9 6389.4 4705.1 4805.2 4878.5 4894.0 4938.6 4883.7 4885.2 4885.6 4902.4 4904.0 4832.4 4853.6 4858.2 4867.4 4868.5 4847.5 4869.9 4946.8 4976.9 4978.8 4376.6 4384.1 4388.6 4394.1 4397.7 5986.7 6093.5 6099.3 6113.4 6114.3 5805.7 5826.6 5827.9 5830.3 5831.5 6763.1 6766.7 6768.1 6774.0 6829.1 4851.4 4855.1 4856.0 4856.1 4859.7 7055.5 7088.8 7097.1 7099.1 7109.8 4678.6 4815.8 4838.0 4973.9 4988.3 4919.0 4931.8 4945.3 4947.3 4955.8 5700.8 5709.7 5714.6 5724.8 5725.3 6833.4 6836.6 6837.5 6839.4 6840.7 1726.8 1735.3 1744.6 1752.9 1756.2 1108.0 1152.2 1157.2 1163.0 1168.7 6548.3 6569.3 6569.5 6582.8 6601.0 6926.7 6998.4 7017.1 7027.6 7054.7 6448.8 6648.9 6711.3 6715.5 6727.0 3814.5 3832.3 3866.3 3869.9 3870.2 7276.2 7285.3 7306.4 7324.0 7330.9 7301.4 7324.3 7328.8 7335.0 7336.0 7051.6 7177.9 7183.2 7195.0 7202.9 6986.2 7060.1 7063.0 7069.4 7082.7 6971.2 6980.7 6990.0 7001.3 7003.5 7155.1 7274.3 7281.6 7288.8 7308.9 4845.0 4888.8 4914.6 4919.2 4919.8 7061.1 7080.5 7086.0 7127.3 7134.0 6848.9 6867.4 6885.4 6916.9 6917.2 6884.9 6896.7 6900.6 6901.1 6905.2 6551.5 6762.1 6767.4 6772.4 6830.7 6856.5 6858.8 6941.3 6961.4 6963.5 6742.8 6759.9 6776.6 6779.3 6792.1 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - PROCESS | 3428 | [#5] yui.html Cycles:180 Average:14664.07 Median:48831.38 stddev:17576.54 (36.0%) stddev-sans-first:17594.64 17:17:56 INFO - PROCESS | 3428 | Values: 718.3 718.5 767.0 788.6 805.6 14064.5 14280.7 14300.4 14335.4 14353.9 12786.8 12888.2 13022.3 14017.0 15310.0 903.3 913.4 919.0 920.3 920.3 897.9 903.6 905.9 905.9 906.5 960.4 960.9 963.6 963.8 967.7 953.8 954.0 954.1 957.7 964.8 705.0 705.4 706.1 708.6 716.5 13907.6 13983.0 13989.0 13994.9 14067.9 12441.2 13151.5 13742.6 13930.4 13976.0 10270.3 10283.4 10362.3 10522.6 10538.6 3835.7 3865.7 3875.9 3886.6 3896.7 52994.2 53595.2 53635.7 53766.7 54065.7 10080.3 10103.9 10235.9 10325.9 10459.8 19264.8 19345.5 19366.0 19367.5 19370.3 7392.7 7472.0 7521.7 7576.7 7593.2 4244.6 4319.2 4375.6 4403.3 4422.8 3140.7 3147.5 3193.5 3196.4 3199.1 560.0 575.1 578.3 584.6 588.1 521.7 594.7 632.6 648.6 658.1 8511.8 8535.4 8554.2 8796.7 8967.8 558.2 573.4 594.1 630.9 632.9 3782.7 4068.5 4092.8 4122.3 4219.5 63060.0 63459.7 63617.4 63622.7 63847.7 52497.2 53660.0 56460.0 56539.4 62053.4 47780.5 48272.3 48514.0 51238.0 51391.7 8420.9 8453.9 8468.9 8691.0 8704.4 37626.0 37797.2 38579.2 38926.8 39204.2 47612.5 47830.5 47953.3 49148.8 49431.5 10858.1 11309.4 11578.7 11808.9 12300.8 11404.1 11609.5 12094.3 12127.3 12815.6 10853.3 11096.3 11123.6 11254.9 11459.2 10796.9 11020.1 11229.9 11378.6 11437.5 10711.0 10742.5 10750.1 10754.6 10960.3 9507.5 9617.7 9619.5 9735.7 9994.7 9187.3 9357.6 9410.9 9517.8 9567.7 17:17:56 INFO - PROCESS | 3428 | -------- Summary: end -------- 17:17:56 INFO - PROCESS | 3428 | 17:17:56 INFO - TEST-INFO | 3428: exit 0 17:17:56 INFO - TEST-OK | dromaeo_css | took 1110427ms 17:17:56 INFO - TEST-START | kraken 17:17:56 INFO - operating with platform_type : linux_ 17:17:56 INFO - Initialising browser for kraken test... 17:17:56 INFO - TEST-INFO | started process 3674 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpooCWEz/profile http://localhost:38271/getInfo.html) 17:18:00 INFO - PROCESS | 3674 | 17:18:00 INFO - PROCESS | 3674 | (process:3752): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:18:01 INFO - PROCESS | 3674 | __metrics Screen width/height:1600/1200 17:18:01 INFO - PROCESS | 3674 | colorDepth:24 17:18:01 INFO - PROCESS | 3674 | Browser inner width/height: 1024/697 17:18:01 INFO - PROCESS | 3674 | __metrics 17:18:02 ERROR - PROCESS | 3674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 17:18:02 INFO - TEST-INFO | 3674: exit 0 17:18:02 INFO - Browser initialized. 17:18:02 INFO - Running cycle 1/1 for kraken test... 17:18:02 INFO - TEST-INFO | started process 3803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpooCWEz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:04 INFO - PROCESS | 3803 | 17:18:04 INFO - PROCESS | 3803 | (process:3871): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:18:14 INFO - PROCESS | 3803 | RSS: Main: 150863872 17:18:14 INFO - PROCESS | 3803 | 17:18:17 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38271/tests/kraken/driver.html?testName=audio-beat-detection) 17:18:17 INFO - PROCESS | 3803 | RSS: Main: 156151808 17:18:17 INFO - PROCESS | 3803 | 17:18:20 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38271/tests/kraken/driver.html?testName=audio-dft) 17:18:21 INFO - PROCESS | 3803 | RSS: Main: 155545600 17:18:21 INFO - PROCESS | 3803 | 17:18:24 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38271/tests/kraken/driver.html?testName=audio-fft) 17:18:24 INFO - PROCESS | 3803 | RSS: Main: 156024832 17:18:24 INFO - PROCESS | 3803 | 17:18:26 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38271/tests/kraken/driver.html?testName=audio-oscillator) 17:18:27 INFO - PROCESS | 3803 | RSS: Main: 156102656 17:18:27 INFO - PROCESS | 3803 | 17:18:29 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:18:30 INFO - PROCESS | 3803 | RSS: Main: 156008448 17:18:30 INFO - PROCESS | 3803 | 17:18:33 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38271/tests/kraken/driver.html?testName=imaging-darkroom) 17:18:33 INFO - PROCESS | 3803 | RSS: Main: 156655616 17:18:33 INFO - PROCESS | 3803 | 17:18:37 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38271/tests/kraken/driver.html?testName=imaging-desaturate) 17:18:37 INFO - PROCESS | 3803 | RSS: Main: 156704768 17:18:37 INFO - PROCESS | 3803 | 17:18:40 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38271/tests/kraken/driver.html?testName=json-parse-financial) 17:18:41 INFO - PROCESS | 3803 | RSS: Main: 157081600 17:18:41 INFO - PROCESS | 3803 | 17:18:42 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:18:43 INFO - PROCESS | 3803 | RSS: Main: 156409856 17:18:43 INFO - PROCESS | 3803 | 17:18:45 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:18:45 INFO - PROCESS | 3803 | RSS: Main: 155979776 17:18:45 INFO - PROCESS | 3803 | 17:18:47 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:18:48 INFO - PROCESS | 3803 | RSS: Main: 156438528 17:18:48 INFO - PROCESS | 3803 | 17:18:50 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:18:51 INFO - PROCESS | 3803 | RSS: Main: 157261824 17:18:51 INFO - PROCESS | 3803 | 17:18:53 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:18:54 INFO - PROCESS | 3803 | RSS: Main: 156745728 17:18:54 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:38271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38271/tests/kraken/driver.html?testName=ai-astar) 17:18:56 INFO - PROCESS | 3803 | RSS: Main: 157937664 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | __start_tp_report 17:18:56 INFO - PROCESS | 3803 | _x_x_mozilla_page_load 17:18:56 INFO - PROCESS | 3803 | _x_x_mozilla_page_load_details 17:18:56 INFO - PROCESS | 3803 | |i|pagename|runs| 17:18:56 INFO - PROCESS | 3803 | |0;ai-astar;113;104;111;110;109;121;111;110;109;111 17:18:56 INFO - PROCESS | 3803 | |1;audio-beat-detection;153;130;154;171;129;154;157;128;172;130 17:18:56 INFO - PROCESS | 3803 | |2;audio-dft;163;162;164;161;163;162;161;165;169;167 17:18:56 INFO - PROCESS | 3803 | |3;audio-fft;76;77;72;178;77;76;73;73;161;76 17:18:56 INFO - PROCESS | 3803 | |4;audio-oscillator;94;94;150;93;93;94;150;93;93;144 17:18:56 INFO - PROCESS | 3803 | |5;imaging-gaussian-blur;118;119;117;117;120;117;117;117;117;118 17:18:56 INFO - PROCESS | 3803 | |6;imaging-darkroom;146;147;146;146;147;146;147;146;147;146 17:18:56 INFO - PROCESS | 3803 | |7;imaging-desaturate;97;97;96;96;96;98;96;96;96;95 17:18:56 INFO - PROCESS | 3803 | |8;json-parse-financial;57;55;55;58;57;60;57;64;56;57 17:18:56 INFO - PROCESS | 3803 | |9;json-stringify-tinderbox;64;65;66;64;64;65;65;65;64;66 17:18:56 INFO - PROCESS | 3803 | |10;stanford-crypto-aes;78;78;79;80;80;78;86;83;81;78 17:18:56 INFO - PROCESS | 3803 | |11;stanford-crypto-ccm;110;114;111;111;111;116;120;115;116;112 17:18:56 INFO - PROCESS | 3803 | |12;stanford-crypto-pbkdf2;128;135;149;136;149;136;138;137;137;128 17:18:56 INFO - PROCESS | 3803 | |13;stanford-crypto-sha256-iterative;68;69;66;67;65;75;66;65;66;63 17:18:56 INFO - PROCESS | 3803 | __end_tp_report 17:18:56 INFO - PROCESS | 3803 | __start_cc_report 17:18:56 INFO - PROCESS | 3803 | _x_x_mozilla_cycle_collect,847 17:18:56 INFO - PROCESS | 3803 | __end_cc_report 17:18:56 INFO - PROCESS | 3803 | __startTimestamp1461370736336__endTimestamp 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | ------- Summary: start ------- 17:18:56 INFO - PROCESS | 3803 | Number of tests: 14 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:4.25 (3.8%) stddev-sans-first:4.44 17:18:56 INFO - PROCESS | 3803 | Values: 113.0 104.0 111.0 110.0 109.0 121.0 111.0 110.0 109.0 111.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:154.00 stddev:17.29 (11.2%) stddev-sans-first:18.24 17:18:56 INFO - PROCESS | 3803 | Values: 153.0 130.0 154.0 171.0 129.0 154.0 157.0 128.0 172.0 130.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.50 stddev:2.63 (1.6%) stddev-sans-first:2.77 17:18:56 INFO - PROCESS | 3803 | Values: 163.0 162.0 164.0 161.0 163.0 162.0 161.0 165.0 169.0 167.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#3] audio-fft Cycles:10 Average:93.90 Median:76.00 stddev:40.08 (52.7%) stddev-sans-first:41.99 17:18:56 INFO - PROCESS | 3803 | Values: 76.0 77.0 72.0 178.0 77.0 76.0 73.0 73.0 161.0 76.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:93.00 stddev:26.41 (28.4%) stddev-sans-first:27.39 17:18:56 INFO - PROCESS | 3803 | Values: 94.0 94.0 150.0 93.0 93.0 94.0 150.0 93.0 93.0 144.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:117.50 stddev:1.06 (0.9%) stddev-sans-first:1.12 17:18:56 INFO - PROCESS | 3803 | Values: 118.0 119.0 117.0 117.0 120.0 117.0 117.0 117.0 117.0 118.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:18:56 INFO - PROCESS | 3803 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 147.0 146.0 147.0 146.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 17:18:56 INFO - PROCESS | 3803 | Values: 97.0 97.0 96.0 96.0 96.0 98.0 96.0 96.0 96.0 95.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.00 stddev:2.67 (4.7%) stddev-sans-first:2.83 17:18:56 INFO - PROCESS | 3803 | Values: 57.0 55.0 55.0 58.0 57.0 60.0 57.0 64.0 56.0 57.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 17:18:56 INFO - PROCESS | 3803 | Values: 64.0 65.0 66.0 64.0 64.0 65.0 65.0 65.0 64.0 66.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.00 stddev:2.64 (3.3%) stddev-sans-first:2.69 17:18:56 INFO - PROCESS | 3803 | Values: 78.0 78.0 79.0 80.0 80.0 78.0 86.0 83.0 81.0 78.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#11] stanford-crypto-ccm Cycles:10 Average:113.60 Median:114.50 stddev:3.17 (2.8%) stddev-sans-first:3.08 17:18:56 INFO - PROCESS | 3803 | Values: 110.0 114.0 111.0 111.0 111.0 116.0 120.0 115.0 116.0 112.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.30 Median:137.00 stddev:7.12 (5.2%) stddev-sans-first:6.71 17:18:56 INFO - PROCESS | 3803 | Values: 128.0 135.0 149.0 136.0 149.0 136.0 138.0 137.0 137.0 128.0 17:18:56 INFO - PROCESS | 3803 | 17:18:56 INFO - PROCESS | 3803 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.50 stddev:3.27 (4.9%) stddev-sans-first:3.44 17:18:56 INFO - PROCESS | 3803 | Values: 68.0 69.0 66.0 67.0 65.0 75.0 66.0 65.0 66.0 63.0 17:18:56 INFO - PROCESS | 3803 | -------- Summary: end -------- 17:18:56 INFO - PROCESS | 3803 | 17:18:57 INFO - TEST-INFO | 3803: exit 0 17:18:57 INFO - TEST-OK | kraken | took 60522ms 17:18:57 INFO - SUITE-END | took 1170s 17:18:57 INFO - Completed test suite (00:19:31) 17:18:57 INFO - javascript benchmark 17:18:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3336.132605522564, 3407.5570175877133, 3421.5894092708877, 3450.1202193440668, 3458.602260740014, 5704.800331988381, 5708.0, 5713.742881570329, 5736.5984381093285, 5746.511546518545, 5659.886802263953, 5686.886262274752, 5694.857628559278, 5708.1437784332375, 5724.7137643117785, 5153.3815942087, 5176.55999240065, 5230.8430747077455, 5254.527092561669, 5309.097453432925, 4997.625178111638, 5102.387713474388, 5282.418933917266, 5286.524212820846, 5287.39194992575, 5083.593312534988, 5162.432132465426, 5196.714180720059, 5218.765155568009, 5276.393214780292, 5264.8157314494, 5265.52610265078, 5265.578753699695, 5276.15581506957, 5294.100002999461, 5023.472535383768, 5045.0, 5065.569426598743, 5165.63840531163, 5249.790008399659, 5478.315210598676, 5528.198411230349, 5533.5849811263915, 5571.693556854371, 5571.693556854371, 6133.6319820810895, 6189.690515474208, 6194.5044396448175, 6216.067589861512, 6220.533459990474, 5916.4675179233645, 6134.049222370495, 6194.659293738842, 6200.441960223601, 6218.31598524162, 5802.216700745387, 5806.593538452352, 5807.0, 5817.534597232254, 5820.912686309709, 6124.112003759519, 6360.300366959631, 6363.300036995926, 6365.649889256043, 6376.3623637635865, 6034.275886893601, 6042.879142417215, 6093.725782339805, 6100.908486372708, 6130.846728831726, 6139.758987969359, 6157.8460538486, 6188.690565471708, 6198.752049918043, 6202.379762023762, 5864.0, 5999.370066143079, 6085.92182497994, 6113.29387775398, 6114.354250337185, 6046.727897244635, 6137.0794380842335, 6137.483631710977, 6156.846078847975, 6168.691565421801, 4638.0491999140095, 4648.209804333272, 4677.64917631179, 4683.742394168354, 4713.552212539803, 4043.6158564936286, 4052.8378864845076, 4116.855910043134, 4199.559046300155, 4216.093539888877, 5474.534664553478, 5844.824655260264, 5877.647341159544, 5924.7037648118285, 5939.22790037301, 6050.818475445743, 6062.151298818169, 6085.5435842312, 6116.0, 6118.663473508911, 5653.349864765522, 5747.339056008613, 5763.0, 5776.711164441761, 5797.420257974253, 5300.9734951325, 5315.096433606296, 5331.360236771612, 5348.786048558092, 5361.276227709287, 6348.619082855043, 6384.3934826191435, 6398.520110991694, 6398.8080357588415, 6416.935830641727, 6024.789132380346, 6107.6640784757265, 6171.876562468815, 6202.4417802398, 6246.469050130699, 6339.90490142639, 6341.7780377688405, 6348.428641422294, 6350.428461438492, 6361.904571431525, 6105.267367915879, 6112.083187521729, 6114.785982490592, 6116.082587611894, 6118.938810611837, 5956.8808623828145, 6119.632822030692, 6136.73353236607, 6195.2875419325555, 6200.255969283715, 6006.459418652343, 6033.3061697905305, 6039.849003774941, 6045.0327947528185, 6054.0, 6026.306974697965, 6040.75836966534, 6048.0625503047095, 6048.455638992512, 6054.122152287806, 5654.236678048411, 5920.703964801829, 5953.017752070859, 5970.880582388415, 6003.849903752266, 6003.339632640494, 6010.33886272501, 6018.007028840192, 6065.363136870565, 6078.057901025348, 5692.203091567267, 5950.2264705587995, 5960.0761881908375, 5962.25471816023, 5973.940260597512], "unit": "score", "name": "dojo.html", "value": 5655.239830595867}, {"lowerIsBetter": false, "replicates": [549.4454239311933, 566.2299272988736, 568.4571234471081, 609.9695015249239, 615.5691016288598, 37277.25445491092, 37838.24323513535, 38228.23543529128, 38355.42466862992, 38457.42313865294, 38959.0, 39130.413043804365, 39195.80402097993, 39360.80319598398, 39504.80247598758, 8571.057183709803, 8704.608292626846, 8717.302615790753, 8718.607662655195, 8737.475751454933, 8508.319334453228, 8770.56147192641, 8784.429012114193, 8820.73537793864, 8836.248918841875, 8751.3874028818, 8768.123187681213, 8831.735047948572, 8852.690155844546, 8868.556572171397, 8842.204201621853, 8845.425047371964, 8846.071162527938, 8869.2017718405, 8937.553122343921, 8896.377253592187, 8959.641614335418, 8999.415038022573, 9051.185393314567, 9065.048169942127, 39730.80134599337, 40078.59921400778, 40166.799166003984, 40210.79894600537, 40273.395899061536, 3576.8390422430793, 3817.2556351511466, 4215.45199124115, 4281.443412356405, 4352.956470435287, 31421.528677069862, 32049.198770030936, 32164.356712865847, 32335.0, 32945.0, 4204.810783514749, 4243.787810609457, 4254.553271906436, 4265.808038638269, 4285.764282964436, 268927.0, 269137.0, 269516.0, 270310.0, 271163.999999998, 63459.6827015862, 63957.36042639607, 64332.35667643264, 64578.354216458174, 64604.67697661481, 74482.25517744753, 74515.62742186255, 74692.25307746853, 74918.25081749221, 74924.25075749282, 15287.770683439758, 15320.157391343582, 15349.769753453707, 15417.229138543027, 15497.07017578949, 11345.13619260352, 11347.886521134847, 11353.943230283961, 11409.771804563863, 11438.714032149097, 21732.34802955914, 22054.448638784157, 22108.447288817908, 22265.888670556542, 22829.7717022831, 7662.1571627120975, 7900.525968441912, 7987.960060199662, 8012.639431225609, 8038.557879316693, 1022.6341027106205, 1066.157735389034, 1117.709395557161, 1138.135017386792, 1206.2099324942176, 29494.0, 29633.703662963093, 29773.553396699066, 30234.54648180235, 30367.392652146835, 963.5663951221978, 978.2760757039881, 984.7488890333062, 990.5740531571494, 1166.6033548593518, 15326.972073737457, 15335.386584536494, 15477.380904763684, 15586.376544938303, 15605.141717205663, 317454.0, 514372.0, 516914.0, 517196.0, 520254.0, 53786.193207101926, 54747.45252547503, 55499.0, 55632.0, 55658.44341556533, 1488.8138982627172, 1498.1460567476652, 1498.2808252039083, 1500.647347873249, 1511.1915125407854, 25268.747312527375, 25821.870890645427, 25927.0, 26136.215913522625, 26161.869190654685, 42227.0, 42424.5757542433, 42752.358714618684, 42849.57150428581, 43395.132097357244, 280818.0, 281230.0, 281692.0, 282258.0, 283874.0, 34117.147071323416, 34191.65808341885, 34362.484562732076, 34665.82667086648, 35075.29849403049, 45324.32013519866, 45827.77086114548, 46538.53461465342, 46772.29841552312, 46916.29625555551, 38030.80984595059, 38066.80966595149, 38794.0, 39432.21135577215, 40346.39480407855, 38250.85247442581, 38325.42511862268, 39204.80397598108, 39641.60358396379, 39742.0, 38162.42756358601, 38321.23357532778, 38828.80585597054, 39382.2123557533, 39513.80243098881, 33299.606744652905, 33514.0, 33939.981800546026, 34079.0, 34156.31687366289, 24968.250952471455, 26551.0, 26641.8667906667, 26781.330466738487, 26820.32949176208], "unit": "score", "name": "ext.html", "value": 19380.443978418883}, {"lowerIsBetter": false, "replicates": [371.2815701617371, 388.6191532298346, 389.27983231022614, 393.58476806968656, 401.79508450690173, 17101.91449042756, 17672.29310827567, 18123.45629631113, 18216.0, 18337.266509339643, 16929.407470738526, 17233.82766172338, 17244.482665520023, 17383.565410864736, 17449.91275043623, 5176.948230517703, 5576.079946808792, 5600.383957764642, 5630.915536266939, 5646.322441307049, 5169.508896654812, 5315.681059136445, 5350.3312085989255, 5362.4637536246255, 5364.839054828361, 5227.477252274761, 5316.20256961455, 5332.760025798849, 5668.801591944282, 5749.798757043504, 5734.741936612862, 5764.798232061858, 5769.451902069297, 5777.88444231113, 5815.505682017034, 5455.318085239345, 5541.750621222055, 5551.944480555224, 5573.804916827891, 5579.302587176603, 13494.797578036338, 13579.660508487268, 13591.660208494768, 13661.316934153252, 17279.74080388795, 2104.926327578543, 2221.9888900555557, 2291.450051987528, 2358.622620380731, 2367.360812580611, 9793.755156121082, 9965.80068398628, 9969.302148849583, 11185.66443006711, 12685.74628507434, 1837.3110083718605, 1862.9813701862943, 1913.1582103874389, 1993.362124120282, 2072.823809976154, 63753.68123159401, 65112.02331964977, 65284.0, 65413.67293163551, 65961.0, 10220.846687299696, 10532.47279144535, 10584.682459526226, 10610.893891061145, 10729.83905241422, 14413.567592972227, 14537.92731036338, 14552.708945821025, 14573.92713036428, 14674.926625366805, 7339.816504587321, 7542.434317426105, 7787.29914307715, 7823.765287041398, 7841.843163136819, 7211.8557628848175, 7225.710971561184, 7232.421406287484, 7234.421246300283, 7430.702771889172, 4304.849330273425, 4488.506264310923, 4522.095580883771, 4595.6783025187915, 4682.672212945061, 1891.186789680423, 1947.8831270123555, 1964.9662599209748, 1966.4002479243682, 2022.9393118206478, 809.6032943857585, 851.5103815306223, 945.8770359853175, 950.9952450237705, 1109.8580633622664, 16696.66606667884, 17330.133493325284, 17457.388991385495, 17626.647467050843, 17959.461216163538, 995.9900400996042, 1024.134606257711, 1040.9115225205792, 1059.422614675004, 1067.4449286371043, 9833.4099954003, 10160.187185025181, 10278.17774578032, 10295.794084118275, 10399.636012739518, 160790.0, 162294.0, 261687.0, 264305.0, 440442.0, 76159.0, 76487.23512764912, 76570.61714691391, 78896.21103789004, 79698.0, 1007.4408703169664, 1011.5852500474917, 1012.9392236465688, 1023.2019025160387, 1028.6759670703705, 15846.207689615703, 15992.600184995003, 16255.431059912846, 16365.181740913145, 16444.0, 34211.0, 34426.0, 34724.47913281252, 34913.0, 34981.47527787136, 50186.0, 50493.0, 50612.74693626508, 50713.98572028465, 50833.0, 7890.644920978455, 7932.801679958047, 7977.321927636216, 8047.114817369968, 8147.511149331059, 10491.94754026225, 11753.059755219732, 12101.81847272274, 12321.137520373488, 12737.93631031839, 10270.794584108127, 10377.117944974761, 10388.42863642507, 10604.681859544226, 10958.671239862817, 10275.537600807833, 10535.4732263385, 10706.571737130427, 10748.623798167027, 11037.944810276218, 10767.246292759432, 10785.784284314426, 10962.835557466486, 11100.000999909724, 11215.1027917768, 8179.0594081681365, 8209.630566624397, 8222.547759873265, 8323.95838020806, 8605.698800541952, 8872.600732967161, 9698.95150524271, 10470.371777693363, 10694.89305106939, 10754.193435492525], "unit": "score", "name": "jquery.html", "value": 8414.069472060464}, {"lowerIsBetter": false, "replicates": [1383.584924522643, 1457.0237940579811, 1572.2846105022215, 1928.6817675083612, 1948.6492431362358, 7320.450966177545, 7399.741009064684, 8396.62215200316, 8682.913170868298, 8732.912670873282, 7573.431992600563, 7781.7665470035845, 8132.0, 8353.081161072278, 8374.916250837505, 2454.656348111261, 2475.356407334097, 2483.1805504183667, 2496.425822060927, 2507.7868381187623, 2360.7639236076425, 2415.2150551070886, 2422.9273121806373, 2424.49085692004, 2426.8665223412704, 2463.7289898111194, 2467.962980555293, 2475.1955614425383, 2483.913063042794, 2496.400863792686, 2465.3590066582638, 2472.4560596668703, 2474.653548503202, 2477.541654793869, 2503.6494890715317, 2452.546278938402, 2468.5556599812026, 2473.2703852363597, 2492.5887228607257, 2529.304441278645, 8437.198466145706, 8481.872771908425, 8484.448510846836, 8498.490090594583, 8507.872381914276, 5060.51925067118, 5168.3797944246935, 5181.792728290864, 5190.844274671766, 5200.453952335026, 3246.1722260823576, 3255.5930508686415, 3275.9836200818845, 3278.327942771726, 3319.3029463812627, 1001.5593139018808, 1989.263972330247, 2007.1068374573322, 2012.0442789674933, 2012.1146695453954, 10708.089812365959, 10778.5688572457, 10782.353058816418, 10790.136789056856, 10812.243142980073, 4183.832646694098, 4290.099079193342, 4307.913841723148, 4354.259775838115, 4360.0, 8363.498190108612, 8375.497470151811, 8421.284190843724, 8429.915700843036, 8450.492970421794, 4997.9750101249265, 5039.647224694236, 5042.924356134661, 5052.595792336604, 5063.797448102035, 4589.770511474413, 4666.323383109498, 4706.388169537938, 4728.787204575802, 4739.241721324641, 3687.391580389206, 3775.7356985011334, 3779.6787273081545, 3866.130120722871, 3934.0361611404815, 1114.0920150077661, 1125.2517076144272, 1139.4530625299737, 1173.9119566032582, 1178.1458442629196, 1163.610190586163, 1167.2121318110242, 1171.496256609666, 1173.313611537243, 1175.794236008695, 7646.847063058708, 7694.422918281151, 7704.576248306397, 7704.6918123274445, 7836.647350869224, 1096.2326371539793, 1110.6001839337832, 1111.8165502691966, 1116.7766446710527, 1117.502711293483, 6413.807585772341, 6419.165508483868, 6451.3226111257645, 6452.12896258999, 6486.254080780771, 9511.191548718298, 10263.384196948058, 10278.486075696335, 10412.375257484575, 10470.424126672953, 10141.746456338504, 10157.035081667229, 10160.745981350525, 10212.846807297896, 10301.845472317922, 8028.799280017929, 8080.909077274493, 8124.5937703114605, 8158.143394943564, 8175.836483270301, 7596.506227095221, 7675.117361503499, 7717.382609391123, 7736.0, 7772.611369431619, 9417.293702972167, 9469.289803264921, 9620.663276785279, 9622.326437149331, 9715.659951901649, 9544.713658590254, 9768.120869121529, 9833.360831545926, 9962.750931226487, 9963.900360996297, 6199.194104766351, 6819.215790184192, 7070.823229419306, 7309.963450182715, 7385.556866588022, 8221.383396245425, 8256.711015114442, 8260.380471464785, 8265.256126948603, 8273.089960104504, 7185.604791736296, 7204.459665525002, 7225.494215404871, 7314.232005639331, 7397.889031664422, 7294.598797066213, 7365.889511657222, 7374.557526548209, 7375.594342311224, 7414.073240844895, 7946.32456241226, 7950.721724739838, 7963.880541791994, 8024.197580241929, 8047.557384343917, 6927.134108236471, 6939.236683964659, 6978.581285122909, 6999.790006299819, 7006.369426751617, 7108.893366599608, 7181.712731490682, 7189.5686258824635, 7204.0, 7220.855582888208], "unit": "score", "name": "mootools.html", "value": 4852.990896206599}, {"lowerIsBetter": false, "replicates": [369.1896287648611, 371.6840685417394, 373.42865415913644, 375.6581510825149, 380.4464504146469, 6363.172787537619, 6460.644664543448, 6471.70877310521, 6482.6434546099945, 6491.902621460682, 6370.0, 6380.680965951707, 6382.234131904178, 6383.936160638404, 6389.393007664276, 4705.058988202356, 4805.231163013918, 4878.512148785128, 4894.0, 4938.629602779797, 4883.731394773286, 4885.193942999401, 4885.6335774817035, 4902.3872015998, 4904.0, 4832.420109586837, 4853.587445067174, 4858.246971719372, 4867.415910090794, 4868.537488938563, 4847.466778654345, 4869.902601947959, 4946.788036930975, 4976.850694479172, 4978.8257410990445, 4376.599488163789, 4384.101259241879, 4388.561143885618, 4394.055278115221, 4397.714148580332, 5986.670733109677, 6093.512518998514, 6099.268087829444, 6113.419225173601, 6114.266288045418, 5805.709714514257, 5826.621269617462, 5827.941720582825, 5830.30036395631, 5831.475167234969, 6763.120794296759, 6766.661666916634, 6768.120144381248, 6774.0, 6829.078074459934, 4851.369321988154, 4855.077535268323, 4856.0287942411305, 4856.077345304381, 4859.708417494961, 7055.470839686993, 7088.751893683696, 7097.077379940574, 7099.112610923687, 7109.786706398816, 4678.6491013174145, 4815.831445899446, 4838.0, 4973.9306049199595, 4988.276699878497, 4918.9508104919205, 4931.802727890916, 4945.258211268267, 4947.282644016597, 4955.776990035457, 5700.7719691212715, 5709.685967271756, 5714.571407144481, 5724.8282551523525, 5725.255716756795, 6833.384995350442, 6836.555623884432, 6837.453003759687, 6839.350261725128, 6840.657967101625, 1726.7582538444628, 1735.2625134318116, 1744.5551384396897, 1752.9123543822757, 1756.1570446185901, 1108.0, 1152.181950814914, 1157.2246594781516, 1162.96511104667, 1168.6844551970921, 6548.345165483413, 6569.343065693488, 6569.4744420447205, 6582.769603063965, 6601.0, 6926.722931082801, 6998.405135563432, 7017.052697885822, 7027.61348125858, 7054.6825392857445, 6448.77429289982, 6648.8670226595195, 6711.261761206263, 6715.46276297895, 6727.024581435664, 3814.5231846019246, 3832.3485007548784, 3866.323393406199, 3869.8839034829, 3870.2390412362306, 7276.235995220532, 7285.271472852778, 7306.378957788647, 7323.963380183065, 7330.926690733025, 7301.37938275242, 7324.267573242633, 7328.78013659591, 7334.999999999894, 7336.0, 7051.576905385693, 7177.856442871113, 7183.173934997333, 7194.964025179841, 7202.891956620655, 6986.16166060076, 7060.117485314336, 7063.0, 7069.363757261665, 7082.681279342545, 6971.163460384583, 6980.650967451708, 6989.95150727395, 7001.264867188872, 7003.474739394667, 7155.105611798525, 7274.345308922222, 7281.5631062136445, 7288.817779555554, 7308.890366644611, 4844.975775121102, 4888.755562221803, 4914.582260507754, 4919.237518184687, 4919.827806026772, 7061.082059332254, 7080.468964827762, 7085.964570177116, 7127.2516385778745, 7134.0, 6848.931510684829, 6867.416269617139, 6885.449164066963, 6916.93083069183, 6917.169939607279, 6884.8967265490055, 6896.655167241718, 6900.585964842126, 6901.068355772108, 6905.240423553506, 6551.475881929339, 6762.120924279812, 6767.39093481589, 6772.424343930821, 6830.658467076726, 6856.485763567852, 6858.828529286808, 6941.271166527644, 6961.408280296231, 6963.477739169683, 6742.764003260058, 6759.932400675931, 6776.559523630948, 6779.288174741581, 6792.083068785848], "unit": "score", "name": "prototype.html", "value": 5175.286564358883}, {"lowerIsBetter": false, "replicates": [718.339128002238, 718.4575645387732, 766.9539827610347, 788.6372268756372, 805.589149533738, 14064.507742229023, 14280.71438571228, 14300.356483958221, 14335.35490902908, 14353.856461435382, 12786.808197877039, 12888.16226945248, 13022.283774392405, 14017.0, 15310.0, 903.2638399704236, 913.447364344572, 918.9678361257357, 920.3051695969534, 920.3235621817959, 897.851854444016, 903.6430609909082, 905.9184673379394, 905.9320550958704, 906.4651855405309, 960.3661583354988, 960.9135177833994, 963.6241865672358, 963.8313295173317, 967.6613185385129, 953.8330792111353, 953.9713808585719, 954.0650162840386, 957.7270477913761, 964.7684555706614, 704.9506534542584, 705.4109818301724, 706.0538877903582, 708.5961002228731, 716.5199316457986, 13907.58277251684, 13983.0, 13988.95082868789, 13994.93002534991, 14067.929660351736, 12441.191322564095, 13151.539696110685, 13742.587722368346, 13930.37313320903, 13976.0, 10270.281080324381, 10283.382997020202, 10362.326448780881, 10522.579096836118, 10538.631147909862, 3835.673967712748, 3865.7487263327794, 3875.9224815503812, 3886.6485230139697, 3896.7467114637743, 52994.20508692373, 53595.19607205895, 53635.73182134103, 53766.73116634431, 54065.72967135139, 10080.29437939352, 10103.949480252551, 10235.897641023643, 10325.896741032493, 10459.84310235347, 19264.80735192658, 19345.51636209078, 19366.0, 19367.515812104528, 19370.32203872886, 7392.667329970164, 7472.007318867889, 7521.661525231378, 7576.696932122763, 7593.164751877289, 4244.554321796167, 4319.222539942778, 4375.584319489643, 4403.295472724349, 4422.84520041797, 3140.7173354398215, 3147.4806656901355, 3193.5369371441475, 3196.408664397094, 3199.056278397878, 559.9580031497654, 575.0798722044696, 578.2800413485238, 584.590786449487, 588.1413136820205, 521.7026295011883, 594.7442599682091, 632.550888868899, 648.6173157836898, 658.1411258307859, 8511.829763404698, 8535.445196062235, 8554.230119289294, 8796.736097917073, 8967.820643587092, 558.2491548866768, 573.4408951272476, 594.1029046140359, 630.8612105336819, 632.9335419780857, 3782.6973842092566, 4068.5117785865887, 4092.7953602319767, 4122.299209134454, 4219.472565929259, 63060.0, 63459.6827015862, 63617.36382636207, 63622.68188659027, 63847.6807615959, 52497.2125418119, 53660.0, 56460.0, 56539.43460565342, 62053.37946620476, 47780.5221947783, 48272.27591586129, 48514.0, 51237.97524049498, 51391.7430412853, 8420.915790842013, 8453.873191902003, 8468.872966905625, 8691.0, 8704.434211776213, 37626.0, 37797.24405511929, 38579.228415430975, 38926.80536597299, 39204.21591568096, 47612.52387476081, 47830.521694782605, 47953.28070078882, 49148.75425622849, 49431.50568494269, 10858.077063449695, 11309.377984210947, 11578.71053223676, 11808.881911180779, 12300.81548776787, 11404.144689148181, 11609.535618575163, 12094.334811585448, 12127.332996685267, 12815.61553153407, 10853.294535855144, 11096.33421994683, 11123.555057797921, 11254.887451125383, 11459.19785614999, 10796.946015269874, 11020.118390528578, 11229.943850280697, 11378.601748938747, 11437.542498299976, 10711.0, 10742.51658675377, 10750.08624266946, 10754.569817207224, 10960.28758130719, 9507.524623768923, 9617.663381781604, 9619.51902404891, 9735.659251926148, 9994.650187243411, 9187.310951678784, 9357.625694972125, 9410.858837117312, 9517.809643807224, 9567.712968610675], "unit": "score", "name": "yui.html", "value": 6546.85331779149}], "name": "dromaeo_css", "value": 7302.504654914938}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 104.0, 111.0, 110.0, 109.0, 121.0, 111.0, 110.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.9}, {"lowerIsBetter": true, "replicates": [153.0, 130.0, 154.0, 171.0, 129.0, 154.0, 157.0, 128.0, 172.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 147.8}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 164.0, 161.0, 163.0, 162.0, 161.0, 165.0, 169.0, 167.0], "unit": "score", "name": "audio-dft", "value": 163.7}, {"lowerIsBetter": true, "replicates": [76.0, 77.0, 72.0, 178.0, 77.0, 76.0, 73.0, 73.0, 161.0, 76.0], "unit": "score", "name": "audio-fft", "value": 93.9}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 150.0, 93.0, 93.0, 94.0, 150.0, 93.0, 93.0, 144.0], "unit": "score", "name": "audio-oscillator", "value": 109.8}, {"lowerIsBetter": true, "replicates": [118.0, 119.0, 117.0, 117.0, 120.0, 117.0, 117.0, 117.0, 117.0, 118.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.7}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.4}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 96.0, 96.0, 96.0, 98.0, 96.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [57.0, 55.0, 55.0, 58.0, 57.0, 60.0, 57.0, 64.0, 56.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 66.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.8}, {"lowerIsBetter": true, "replicates": [78.0, 78.0, 79.0, 80.0, 80.0, 78.0, 86.0, 83.0, 81.0, 78.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.1}, {"lowerIsBetter": true, "replicates": [110.0, 114.0, 111.0, 111.0, 111.0, 116.0, 120.0, 115.0, 116.0, 112.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 113.6}, {"lowerIsBetter": true, "replicates": [128.0, 135.0, 149.0, 136.0, 149.0, 136.0, 138.0, 137.0, 137.0, 128.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.3}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 66.0, 67.0, 65.0, 75.0, 66.0, 65.0, 66.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken", "value": 1506.8999999999996}]} 17:18:57 INFO - Return code: 0 17:18:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:18:57 ERROR - # TBPL SUCCESS # 17:18:57 INFO - Running post-action listener: _resource_record_post_action 17:18:57 INFO - Running post-run listener: _resource_record_post_run 17:18:58 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 7974912; Write bytes: 391442432; Read time: 2044; Write time: 307408 17:18:58 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 40439808; Read time: 0; Write time: 7632 17:18:58 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 6234112; Write bytes: 289857536; Read time: 1668; Write time: 228920 17:18:58 INFO - Running post-run listener: _upload_blobber_files 17:18:58 INFO - Blob upload gear active. 17:18:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:18:58 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 17:18:58 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:18:58 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:18:59 INFO - (blobuploader) - INFO - Open directory for files ... 17:18:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:18:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:18:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:18:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:18:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:18:59 INFO - (blobuploader) - INFO - Done attempting. 17:18:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:18:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:18:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:19:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:00 INFO - (blobuploader) - INFO - Done attempting. 17:19:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:19:00 INFO - Return code: 0 17:19:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:19:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:19:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df8081df60e24a9941620c2c09fc1ce4815ea396ab10f364d5ca31c60bcb30ef58547a944dccbc5040a16db1a8af0eb160f379e95e3a4e8f5215f33476468d55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eceba735bf5a53dc535c07620a6115f492ee3e77e821c10a55dda5239623601614e9d8f977b0a583d2a556a3a2a411af5d16707840aeb0f7c7b002f376612a00"} 17:19:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:19:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:19:00 INFO - Contents: 17:19:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df8081df60e24a9941620c2c09fc1ce4815ea396ab10f364d5ca31c60bcb30ef58547a944dccbc5040a16db1a8af0eb160f379e95e3a4e8f5215f33476468d55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eceba735bf5a53dc535c07620a6115f492ee3e77e821c10a55dda5239623601614e9d8f977b0a583d2a556a3a2a411af5d16707840aeb0f7c7b002f376612a00"} 17:19:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:19:00 INFO - Copying logs to upload dir... 17:19:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:19:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1306.274448 ========= master_lag: -0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 46 secs) (at 2016-04-22 17:19:00.693231) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 17:19:00.697060) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df8081df60e24a9941620c2c09fc1ce4815ea396ab10f364d5ca31c60bcb30ef58547a944dccbc5040a16db1a8af0eb160f379e95e3a4e8f5215f33476468d55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eceba735bf5a53dc535c07620a6115f492ee3e77e821c10a55dda5239623601614e9d8f977b0a583d2a556a3a2a411af5d16707840aeb0f7c7b002f376612a00"} build_url:https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011149 build_url: 'https://queue.taskcluster.net/v1/task/D-J9xojiRkazInhyY5FdEA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df8081df60e24a9941620c2c09fc1ce4815ea396ab10f364d5ca31c60bcb30ef58547a944dccbc5040a16db1a8af0eb160f379e95e3a4e8f5215f33476468d55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eceba735bf5a53dc535c07620a6115f492ee3e77e821c10a55dda5239623601614e9d8f977b0a583d2a556a3a2a411af5d16707840aeb0f7c7b002f376612a00"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 17:19:00.720721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 17:19:00.721007) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461369282.326620-1771554252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 17:19:00.804234) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 17:19:00.804655) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 17:19:00.805061) ========= ========= Total master_lag: -0.01 =========