builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1464019229.49 results: success (0) buildid: 20160523061540 builduid: b048b1a6c3734084a413d294d4b3e02a revision: 641d8d468d540ede408c427b1840e35217e44e76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.488158) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.488686) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.488992) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.505663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.505940) ========= 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=df17445c3590ff7897617a0e00000225-1464018583.524884-845511080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-23 09:00:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.8M=0s 2016-05-23 09:00:29 (37.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111002 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.630799) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.631111) ========= 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=df17445c3590ff7897617a0e00000225-1464018583.524884-845511080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021231 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.680175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:29.680526) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 641d8d468d540ede408c427b1840e35217e44e76 --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 641d8d468d540ede408c427b1840e35217e44e76 --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=df17445c3590ff7897617a0e00000225-1464018583.524884-845511080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-23 09:00:30,062 truncating revision to first 12 chars 2016-05-23 09:00:30,062 Setting DEBUG logging. 2016-05-23 09:00:30,062 attempt 1/10 2016-05-23 09:00:30,062 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/641d8d468d54?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 09:00:30,625 unpacking tar archive at: mozilla-aurora-641d8d468d54/testing/mozharness/ program finished with exit code 0 elapsedTime=0.873539 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:30.567192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:30.567517) ========= script_repo_revision: 641d8d468d540ede408c427b1840e35217e44e76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:30.567921) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:30.568189) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 09:00:30.575840) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-23 09:00:30.576154) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=df17445c3590ff7897617a0e00000225-1464018583.524884-845511080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:00:31 INFO - MultiFileLogger online at 20160523 09:00:31 in /builds/slave/test 09:00:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:00:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:31 INFO - {'append_to_log': False, 09:00:31 INFO - 'base_work_dir': '/builds/slave/test', 09:00:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:00:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:31 INFO - 'branch': 'Mozilla-Aurora', 09:00:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:31 INFO - 'config_files': ('talos/linux_config.py',), 09:00:31 INFO - 'default_actions': ('clobber', 09:00:31 INFO - 'read-buildbot-config', 09:00:31 INFO - 'download-and-extract', 09:00:31 INFO - 'populate-webroot', 09:00:31 INFO - 'create-virtualenv', 09:00:31 INFO - 'install', 09:00:31 INFO - 'run-tests'), 09:00:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:31 INFO - 'download_minidump_stackwalk': True, 09:00:31 INFO - 'download_symbols': 'ondemand', 09:00:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:31 INFO - 'installer_path': 'installer.exe', 09:00:31 INFO - 'log_level': 'info', 09:00:31 INFO - 'log_name': 'talos', 09:00:31 INFO - 'log_to_console': True, 09:00:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:00:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:00:31 INFO - 'opt_config_files': (), 09:00:31 INFO - 'pip_index': False, 09:00:31 INFO - 'sps_profile': False, 09:00:31 INFO - 'sps_profile_interval': 0, 09:00:31 INFO - 'suite': 'dromaeojs', 09:00:31 INFO - 'system_bits': '32', 09:00:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:31 INFO - 'title': 'talos-linux64-ix-021', 09:00:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:31 INFO - 'use_talos_json': True, 09:00:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:31 INFO - 'work_dir': 'build'} 09:00:31 INFO - ##### 09:00:31 INFO - ##### Running clobber step. 09:00:31 INFO - ##### 09:00:31 INFO - Running pre-action listener: _resource_record_pre_action 09:00:31 INFO - Running main action method: clobber 09:00:31 INFO - rmtree: /builds/slave/test/build 09:00:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:31 INFO - Running post-action listener: _resource_record_post_action 09:00:31 INFO - ##### 09:00:31 INFO - ##### Running read-buildbot-config step. 09:00:31 INFO - ##### 09:00:31 INFO - Running pre-action listener: _resource_record_pre_action 09:00:31 INFO - Running main action method: read_buildbot_config 09:00:31 INFO - Using buildbot properties: 09:00:31 INFO - { 09:00:31 INFO - "project": "", 09:00:31 INFO - "product": "firefox", 09:00:31 INFO - "script_repo_revision": "production", 09:00:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:00:31 INFO - "repository": "", 09:00:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:00:31 INFO - "buildid": "20160523061540", 09:00:31 INFO - "pgo_build": "True", 09:00:31 INFO - "basedir": "/builds/slave/test", 09:00:31 INFO - "buildnumber": 251, 09:00:31 INFO - "slavename": "talos-linux64-ix-021", 09:00:31 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:00:31 INFO - "platform": "ubuntu64_hw", 09:00:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:00:31 INFO - "branch": "mozilla-aurora", 09:00:31 INFO - "repo_path": "releases/mozilla-aurora", 09:00:31 INFO - "revision": "641d8d468d540ede408c427b1840e35217e44e76", 09:00:31 INFO - "stage_platform": "linux64-pgo", 09:00:31 INFO - "builduid": "b048b1a6c3734084a413d294d4b3e02a", 09:00:31 INFO - "slavebuilddir": "test" 09:00:31 INFO - } 09:00:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 09:00:31 INFO - Running post-action listener: _resource_record_post_action 09:00:31 INFO - ##### 09:00:31 INFO - ##### Running download-and-extract step. 09:00:31 INFO - ##### 09:00:31 INFO - Running pre-action listener: _resource_record_pre_action 09:00:31 INFO - Running main action method: download_and_extract 09:00:31 INFO - mkdir: /builds/slave/test/build/tests 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:31 INFO - https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:00:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:00:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:00:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:00:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:00:32 INFO - Downloaded 1361 bytes. 09:00:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:00:32 INFO - Using the following test package requirements: 09:00:32 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 09:00:32 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:00:32 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:00:32 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'jsshell-linux-x86_64.zip'], 09:00:32 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:00:32 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 09:00:32 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:00:32 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:00:32 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:00:32 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:00:32 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:00:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:00: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')]} 09:00:32 INFO - https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:00:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:00:33 INFO - Downloaded 23813419 bytes. 09:00:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:00:35 INFO - Return code: 0 09:00:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:00:35 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')]} 09:00:35 INFO - https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:00:42 INFO - Downloaded 23813419 bytes. 09:00:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:00:44 INFO - Return code: 0 09:00: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')]} 09:00:44 INFO - https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:00:45 INFO - Downloaded 10840145 bytes. 09:00:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:00:45 INFO - Return code: 0 09:00:45 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')]} 09:00:45 INFO - https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:00:47 INFO - Downloaded 62732688 bytes. 09:00:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:47 INFO - mkdir: /builds/slave/test/properties 09:00:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:47 INFO - Contents: 09:00:47 INFO - build_url:https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:47 INFO - Running post-action listener: _resource_record_post_action 09:00:47 INFO - Running post-action listener: set_extra_try_arguments 09:00:47 INFO - ##### 09:00:47 INFO - ##### Running populate-webroot step. 09:00:47 INFO - ##### 09:00:47 INFO - Running pre-action listener: _resource_record_pre_action 09:00:47 INFO - Running main action method: populate_webroot 09:00:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:47 INFO - '--tppagecycles', 09:00:47 INFO - '7'], 09:00:47 INFO - 'tests': ['tsvgm']}}, 09:00:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['damp', 'tps']}, 09:00:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'tests': ['damp', 'tps']}, 09:00:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['dromaeo_dom']}, 09:00:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['a11yr', 09:00:47 INFO - 'ts_paint', 09:00:47 INFO - 'tpaint', 09:00:47 INFO - 'sessionrestore', 09:00:47 INFO - 'sessionrestore_no_auto_restore', 09:00:47 INFO - 'tabpaint']}, 09:00:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:47 INFO - 'ts_paint', 09:00:47 INFO - 'tpaint', 09:00:47 INFO - 'sessionrestore', 09:00:47 INFO - 'sessionrestore_no_auto_restore', 09:00:47 INFO - 'tabpaint']}, 09:00:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:47 INFO - 'ts_paint', 09:00:47 INFO - 'tpaint', 09:00:47 INFO - 'sessionrestore', 09:00:47 INFO - 'sessionrestore_no_auto_restore', 09:00:47 INFO - 'tabpaint']}, 09:00:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:47 INFO - 'ts_paint', 09:00:47 INFO - 'tpaint', 09:00:47 INFO - 'sessionrestore', 09:00:47 INFO - 'sessionrestore_no_auto_restore', 09:00:47 INFO - 'tabpaint']}, 09:00:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['a11yr', 09:00:47 INFO - 'ts_paint', 09:00:47 INFO - 'tpaint', 09:00:47 INFO - 'sessionrestore', 09:00:47 INFO - 'sessionrestore_no_auto_restore', 09:00:47 INFO - 'tabpaint']}, 09:00:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['a11yr', 09:00:47 INFO - 'ts_paint', 09:00:47 INFO - 'tpaint', 09:00:47 INFO - 'sessionrestore', 09:00:47 INFO - 'sessionrestore_no_auto_restore', 09:00:47 INFO - 'tabpaint']}, 09:00:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['tsvgx', 09:00:47 INFO - 'tsvgr_opacity', 09:00:47 INFO - 'tart', 09:00:47 INFO - 'tscrollx', 09:00:47 INFO - 'cart']}, 09:00:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:47 INFO - 'tsvgr_opacity', 09:00:47 INFO - 'tart', 09:00:47 INFO - 'tscrollx', 09:00:47 INFO - 'cart']}, 09:00:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'talos_options': ['--disable-e10s'], 09:00:47 INFO - 'tests': ['tp5o']}, 09:00:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'tests': ['tp5o']}, 09:00:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'talos_options': ['--disable-e10s', 09:00:47 INFO - '--xperf_path', 09:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:47 INFO - 'tests': ['tp5n']}, 09:00:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:47 INFO - 'talos_options': ['--xperf_path', 09:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:47 INFO - 'tests': ['tp5n']}}, 09:00:47 INFO - 'talos.zip': {'path': '', 09:00:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:47 INFO - Running post-action listener: _resource_record_post_action 09:00:47 INFO - ##### 09:00:47 INFO - ##### Running create-virtualenv step. 09:00:47 INFO - ##### 09:00:47 INFO - Running pre-action listener: _resource_record_pre_action 09:00:47 INFO - Running main action method: create_virtualenv 09:00:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:47 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 09:00:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:48 INFO - Using real prefix '/usr' 09:00:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:50 INFO - Installing pip.................done. 09:00:50 INFO - Return code: 0 09:00:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:00: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', 'pip>=1.5'] in /builds/slave/test/build 09:00: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 pip>=1.5 09:00:50 INFO - Using env: {'DISPLAY': ':0', 09:00:50 INFO - 'HOME': '/home/cltbld', 09:00:50 INFO - 'LANG': 'en_US.UTF-8', 09:00:50 INFO - 'LANGUAGE': 'en_US:en', 09:00:50 INFO - 'LOGNAME': 'cltbld', 09:00:50 INFO - 'MAIL': '/var/mail/cltbld', 09:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:50 INFO - 'MOZ_NO_REMOTE': '1', 09:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:50 INFO - 'NO_EM_RESTART': '1', 09:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:50 INFO - 'PWD': '/builds/slave/test', 09:00:50 INFO - 'SHELL': '/bin/bash', 09:00:50 INFO - 'SHLVL': '1', 09:00:50 INFO - 'TERM': 'linux', 09:00:50 INFO - 'TMOUT': '86400', 09:00:50 INFO - 'USER': 'cltbld', 09:00:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:50 INFO - '_': '/tools/buildbot/bin/python'} 09:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:50 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 09:00:50 INFO - Cleaning up... 09:00:50 INFO - Return code: 0 09:00:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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', '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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:00: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', 'psutil>=0.7.1'] in /builds/slave/test/build 09:00: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 psutil>=0.7.1 09:00:50 INFO - Using env: {'DISPLAY': ':0', 09:00:50 INFO - 'HOME': '/home/cltbld', 09:00:50 INFO - 'LANG': 'en_US.UTF-8', 09:00:50 INFO - 'LANGUAGE': 'en_US:en', 09:00:50 INFO - 'LOGNAME': 'cltbld', 09:00:50 INFO - 'MAIL': '/var/mail/cltbld', 09:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:50 INFO - 'MOZ_NO_REMOTE': '1', 09:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:50 INFO - 'NO_EM_RESTART': '1', 09:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:50 INFO - 'PWD': '/builds/slave/test', 09:00:50 INFO - 'SHELL': '/bin/bash', 09:00:50 INFO - 'SHLVL': '1', 09:00:50 INFO - 'TERM': 'linux', 09:00:50 INFO - 'TMOUT': '86400', 09:00:50 INFO - 'USER': 'cltbld', 09:00:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:50 INFO - '_': '/tools/buildbot/bin/python'} 09:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:51 INFO - Downloading/unpacking psutil>=0.7.1 09:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:53 INFO - Installing collected packages: psutil 09:00:53 INFO - Running setup.py install for psutil 09:00:53 INFO - building 'psutil._psutil_linux' extension 09:00:53 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 09:00:53 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 09:00:53 INFO - building 'psutil._psutil_posix' extension 09:00:53 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 09:00:53 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 09:00:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:53 INFO - Successfully installed psutil 09:00:53 INFO - Cleaning up... 09:00:53 INFO - Return code: 0 09:00:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:00:53 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')]} 09:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:53 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')]} 09:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:53 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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:00:53 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 09:00:53 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 09:00:53 INFO - Using env: {'DISPLAY': ':0', 09:00:53 INFO - 'HOME': '/home/cltbld', 09:00:53 INFO - 'LANG': 'en_US.UTF-8', 09:00:53 INFO - 'LANGUAGE': 'en_US:en', 09:00:53 INFO - 'LOGNAME': 'cltbld', 09:00:53 INFO - 'MAIL': '/var/mail/cltbld', 09:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:53 INFO - 'MOZ_NO_REMOTE': '1', 09:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:53 INFO - 'NO_EM_RESTART': '1', 09:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:53 INFO - 'PWD': '/builds/slave/test', 09:00:53 INFO - 'SHELL': '/bin/bash', 09:00:53 INFO - 'SHLVL': '1', 09:00:53 INFO - 'TERM': 'linux', 09:00:53 INFO - 'TMOUT': '86400', 09:00:53 INFO - 'USER': 'cltbld', 09:00:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:53 INFO - '_': '/tools/buildbot/bin/python'} 09:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:00:55 INFO - Installing collected packages: mozsystemmonitor 09:00:55 INFO - Running setup.py install for mozsystemmonitor 09:00:55 INFO - Successfully installed mozsystemmonitor 09:00:55 INFO - Cleaning up... 09:00:55 INFO - Return code: 0 09:00:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:00:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:55 INFO - Using env: {'DISPLAY': ':0', 09:00:55 INFO - 'HOME': '/home/cltbld', 09:00:55 INFO - 'LANG': 'en_US.UTF-8', 09:00:55 INFO - 'LANGUAGE': 'en_US:en', 09:00:55 INFO - 'LOGNAME': 'cltbld', 09:00:55 INFO - 'MAIL': '/var/mail/cltbld', 09:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:55 INFO - 'MOZ_NO_REMOTE': '1', 09:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:55 INFO - 'NO_EM_RESTART': '1', 09:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:55 INFO - 'PWD': '/builds/slave/test', 09:00:55 INFO - 'SHELL': '/bin/bash', 09:00:55 INFO - 'SHLVL': '1', 09:00:55 INFO - 'TERM': 'linux', 09:00:55 INFO - 'TMOUT': '86400', 09:00:55 INFO - 'USER': 'cltbld', 09:00:55 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:55 INFO - '_': '/tools/buildbot/bin/python'} 09:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:56 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 09:00:56 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 09:00:56 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 09:00:56 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 09:00:56 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 09:00:56 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 09:00:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 INFO - Downloading docopt-0.6.1.tar.gz 09:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:58 INFO - Running setup.py install for blobuploader 09:00:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:58 INFO - Running setup.py install for requests 09:00:59 INFO - Running setup.py install for docopt 09:00:59 INFO - Successfully installed blobuploader requests docopt 09:00:59 INFO - Cleaning up... 09:00:59 INFO - Return code: 0 09:00:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:59 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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:00:59 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 09:00:59 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 09:00:59 INFO - Using env: {'DISPLAY': ':0', 09:00:59 INFO - 'HOME': '/home/cltbld', 09:00:59 INFO - 'LANG': 'en_US.UTF-8', 09:00:59 INFO - 'LANGUAGE': 'en_US:en', 09:00:59 INFO - 'LOGNAME': 'cltbld', 09:00:59 INFO - 'MAIL': '/var/mail/cltbld', 09:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:59 INFO - 'MOZ_NO_REMOTE': '1', 09:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:59 INFO - 'NO_EM_RESTART': '1', 09:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:59 INFO - 'PWD': '/builds/slave/test', 09:00:59 INFO - 'SHELL': '/bin/bash', 09:00:59 INFO - 'SHLVL': '1', 09:00:59 INFO - 'TERM': 'linux', 09:00:59 INFO - 'TMOUT': '86400', 09:00:59 INFO - 'USER': 'cltbld', 09:00:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:59 INFO - '_': '/tools/buildbot/bin/python'} 09:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:59 INFO - Running setup.py (path:/tmp/pip-0Kwo08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:59 INFO - Running setup.py (path:/tmp/pip-KzORws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:59 INFO - Running setup.py (path:/tmp/pip-jSPpi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:59 INFO - Running setup.py (path:/tmp/pip-IgdcVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:59 INFO - Running setup.py (path:/tmp/pip-P5nyeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:00 INFO - Running setup.py (path:/tmp/pip-3jsQvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:00 INFO - Running setup.py (path:/tmp/pip-mRurH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:00 INFO - Running setup.py (path:/tmp/pip-GOZyiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:00 INFO - Running setup.py (path:/tmp/pip-2cxezX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:00 INFO - Running setup.py (path:/tmp/pip-Gkkx1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:00 INFO - Running setup.py (path:/tmp/pip-MnL4Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:00 INFO - Running setup.py (path:/tmp/pip-WVARsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:00 INFO - Running setup.py (path:/tmp/pip-y4tGVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:00 INFO - Running setup.py (path:/tmp/pip-IJhTM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:00 INFO - Running setup.py (path:/tmp/pip-Y0fNoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:00 INFO - Running setup.py (path:/tmp/pip-SFgnFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:00 INFO - Running setup.py (path:/tmp/pip-A247dV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:00 INFO - Running setup.py install for manifestparser 09:01:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:00 INFO - Running setup.py install for mozcrash 09:01:01 INFO - Running setup.py install for mozdebug 09:01:01 INFO - Running setup.py install for mozdevice 09:01:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Running setup.py install for mozfile 09:01:01 INFO - Running setup.py install for mozhttpd 09:01:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Running setup.py install for mozinfo 09:01:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Running setup.py install for mozInstall 09:01:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Running setup.py install for mozleak 09:01:01 INFO - Running setup.py install for mozlog 09:01:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Running setup.py install for moznetwork 09:01:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:01 INFO - Running setup.py install for mozprocess 09:01:02 INFO - Running setup.py install for mozprofile 09:01:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:02 INFO - Running setup.py install for mozrunner 09:01:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:02 INFO - Running setup.py install for mozscreenshot 09:01:02 INFO - Running setup.py install for moztest 09:01:02 INFO - Running setup.py install for mozversion 09:01:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:02 INFO - Cleaning up... 09:01:02 INFO - Return code: 0 09:01:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:01:02 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 09:01:02 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 09:01:02 INFO - Using env: {'DISPLAY': ':0', 09:01:02 INFO - 'HOME': '/home/cltbld', 09:01:02 INFO - 'LANG': 'en_US.UTF-8', 09:01:02 INFO - 'LANGUAGE': 'en_US:en', 09:01:02 INFO - 'LOGNAME': 'cltbld', 09:01:02 INFO - 'MAIL': '/var/mail/cltbld', 09:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:02 INFO - 'MOZ_NO_REMOTE': '1', 09:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:02 INFO - 'NO_EM_RESTART': '1', 09:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:02 INFO - 'PWD': '/builds/slave/test', 09:01:02 INFO - 'SHELL': '/bin/bash', 09:01:02 INFO - 'SHLVL': '1', 09:01:02 INFO - 'TERM': 'linux', 09:01:02 INFO - 'TMOUT': '86400', 09:01:02 INFO - 'USER': 'cltbld', 09:01:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:02 INFO - '_': '/tools/buildbot/bin/python'} 09:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:02 INFO - Running setup.py (path:/tmp/pip-wyCWkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:02 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)) 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:02 INFO - Running setup.py (path:/tmp/pip-91tHDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:03 INFO - Running setup.py (path:/tmp/pip-fdOgA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:03 INFO - Running setup.py (path:/tmp/pip-QN_F_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:03 INFO - Running setup.py (path:/tmp/pip-YnbQpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:03 INFO - Running setup.py (path:/tmp/pip-Xf1EpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:03 INFO - Running setup.py (path:/tmp/pip-VQUtXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:03 INFO - Running setup.py (path:/tmp/pip-QeC_9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:03 INFO - Running setup.py (path:/tmp/pip-uiEc9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:03 INFO - Running setup.py (path:/tmp/pip-F7D49H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:03 INFO - Running setup.py (path:/tmp/pip-1axG1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:03 INFO - Running setup.py (path:/tmp/pip-On8btZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:03 INFO - Running setup.py (path:/tmp/pip-3r_cF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:03 INFO - Running setup.py (path:/tmp/pip-6P6Apv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:03 INFO - Running setup.py (path:/tmp/pip-HE_1ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:03 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)) 09:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:03 INFO - Running setup.py (path:/tmp/pip-AB1XEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:04 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)) 09:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:04 INFO - Running setup.py (path:/tmp/pip-HtfGTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:04 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)) 09:01:04 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)) 09:01:04 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)) 09:01:04 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)) 09:01:04 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)) 09:01:04 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)) 09:01:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:06 INFO - Downloading blessings-1.6.tar.gz 09:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:06 INFO - Installing collected packages: blessings 09:01:06 INFO - Running setup.py install for blessings 09:01:06 INFO - Successfully installed blessings 09:01:06 INFO - Cleaning up... 09:01:06 INFO - Return code: 0 09:01:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:06 INFO - Reading from file tmpfile_stdout 09:01:06 INFO - Current package versions: 09:01:06 INFO - argparse == 1.2.1 09:01:06 INFO - blessings == 1.6 09:01:06 INFO - blobuploader == 1.2.4 09:01:06 INFO - docopt == 0.6.1 09:01:06 INFO - manifestparser == 1.1 09:01:06 INFO - mozInstall == 1.12 09:01:06 INFO - mozcrash == 0.17 09:01:06 INFO - mozdebug == 0.1 09:01:06 INFO - mozdevice == 0.48 09:01:06 INFO - mozfile == 1.2 09:01:06 INFO - mozhttpd == 0.7 09:01:06 INFO - mozinfo == 0.9 09:01:06 INFO - mozleak == 0.1 09:01:06 INFO - mozlog == 3.1 09:01:06 INFO - moznetwork == 0.27 09:01:06 INFO - mozprocess == 0.22 09:01:06 INFO - mozprofile == 0.28 09:01:06 INFO - mozrunner == 6.11 09:01:06 INFO - mozscreenshot == 0.1 09:01:06 INFO - mozsystemmonitor == 0.0 09:01:06 INFO - moztest == 0.7 09:01:06 INFO - mozversion == 1.4 09:01:06 INFO - psutil == 3.1.1 09:01:06 INFO - requests == 1.2.3 09:01:06 INFO - wsgiref == 0.1.2 09:01:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:06 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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:01:06 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 09:01:06 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 09:01:06 INFO - Using env: {'DISPLAY': ':0', 09:01:06 INFO - 'HOME': '/home/cltbld', 09:01:06 INFO - 'LANG': 'en_US.UTF-8', 09:01:06 INFO - 'LANGUAGE': 'en_US:en', 09:01:06 INFO - 'LOGNAME': 'cltbld', 09:01:06 INFO - 'MAIL': '/var/mail/cltbld', 09:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:06 INFO - 'MOZ_NO_REMOTE': '1', 09:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:06 INFO - 'NO_EM_RESTART': '1', 09:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:06 INFO - 'PWD': '/builds/slave/test', 09:01:06 INFO - 'SHELL': '/bin/bash', 09:01:06 INFO - 'SHLVL': '1', 09:01:06 INFO - 'TERM': 'linux', 09:01:06 INFO - 'TMOUT': '86400', 09:01:06 INFO - 'USER': 'cltbld', 09:01:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:06 INFO - '_': '/tools/buildbot/bin/python'} 09:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 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)) 09:01:06 INFO - Cleaning up... 09:01:06 INFO - Return code: 0 09:01:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:06 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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:01:06 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 09:01:06 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 09:01:06 INFO - Using env: {'DISPLAY': ':0', 09:01:06 INFO - 'HOME': '/home/cltbld', 09:01:06 INFO - 'LANG': 'en_US.UTF-8', 09:01:06 INFO - 'LANGUAGE': 'en_US:en', 09:01:06 INFO - 'LOGNAME': 'cltbld', 09:01:06 INFO - 'MAIL': '/var/mail/cltbld', 09:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:06 INFO - 'MOZ_NO_REMOTE': '1', 09:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:06 INFO - 'NO_EM_RESTART': '1', 09:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:06 INFO - 'PWD': '/builds/slave/test', 09:01:06 INFO - 'SHELL': '/bin/bash', 09:01:06 INFO - 'SHLVL': '1', 09:01:06 INFO - 'TERM': 'linux', 09:01:06 INFO - 'TMOUT': '86400', 09:01:06 INFO - 'USER': 'cltbld', 09:01:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:06 INFO - '_': '/tools/buildbot/bin/python'} 09:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:06 INFO - Downloading/unpacking jsonschema 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:08 INFO - Downloading/unpacking functools32 (from jsonschema) 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:09 INFO - warning: no files found matching '*.txt' 09:01:09 INFO - no previously-included directories found matching 'build' 09:01:09 INFO - no previously-included directories found matching 'dist' 09:01:09 INFO - no previously-included directories found matching '.git*' 09:01:09 INFO - Installing collected packages: jsonschema, functools32 09:01:09 INFO - Running setup.py install for functools32 09:01:09 INFO - warning: no files found matching '*.txt' 09:01:09 INFO - no previously-included directories found matching 'build' 09:01:09 INFO - no previously-included directories found matching 'dist' 09:01:09 INFO - no previously-included directories found matching '.git*' 09:01:09 INFO - Successfully installed jsonschema functools32 09:01:09 INFO - Cleaning up... 09:01:09 INFO - Return code: 0 09:01:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:01:09 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')]} 09:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:09 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')]} 09:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:09 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 0x7fc98382cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, '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': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', '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 09:01:09 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 09:01:09 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 09:01:09 INFO - Using env: {'DISPLAY': ':0', 09:01:09 INFO - 'HOME': '/home/cltbld', 09:01:09 INFO - 'LANG': 'en_US.UTF-8', 09:01:09 INFO - 'LANGUAGE': 'en_US:en', 09:01:09 INFO - 'LOGNAME': 'cltbld', 09:01:09 INFO - 'MAIL': '/var/mail/cltbld', 09:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:09 INFO - 'MOZ_NO_REMOTE': '1', 09:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:09 INFO - 'NO_EM_RESTART': '1', 09:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:09 INFO - 'PWD': '/builds/slave/test', 09:01:09 INFO - 'SHELL': '/bin/bash', 09:01:09 INFO - 'SHLVL': '1', 09:01:09 INFO - 'TERM': 'linux', 09:01:09 INFO - 'TMOUT': '86400', 09:01:09 INFO - 'USER': 'cltbld', 09:01:09 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:09 INFO - '_': '/tools/buildbot/bin/python'} 09:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:09 INFO - Downloading/unpacking flake8 09:01:09 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 09:01:09 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 09:01:09 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 09:01:09 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 09:01:09 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 09:01:09 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 09:01:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:01:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:01:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:01:12 INFO - no previously-included directories found matching 'docs/_build' 09:01:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:01:12 INFO - Running setup.py install for pep8 09:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:01:12 INFO - no previously-included directories found matching 'docs/_build' 09:01:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:01:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:01:12 INFO - Cleaning up... 09:01:12 INFO - Return code: 0 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - Running post-action listener: _start_resource_monitoring 09:01:12 INFO - Starting resource monitoring. 09:01:12 INFO - ##### 09:01:12 INFO - ##### Running install step. 09:01:12 INFO - ##### 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: install 09:01:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:12 INFO - Reading from file tmpfile_stdout 09:01:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:12 INFO - Reading from file tmpfile_stdout 09:01:12 INFO - Output received: 09:01:12 INFO - Usage: mozinstall [options] installer 09:01:12 INFO - Options: 09:01:12 INFO - -h, --help show this help message and exit 09:01:12 INFO - -d DEST, --destination=DEST 09:01:12 INFO - Directory to install application into. [default: 09:01:12 INFO - "/builds/slave/test"] 09:01:12 INFO - --app=APP Application being installed. [default: firefox] 09:01:12 INFO - mkdir: /builds/slave/test/build/application 09:01:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:01:28 INFO - Reading from file tmpfile_stdout 09:01:28 INFO - Output received: 09:01:28 INFO - /builds/slave/test/build/application/firefox/firefox 09:01:28 INFO - Running post-action listener: _resource_record_post_action 09:01:28 INFO - ##### 09:01:28 INFO - ##### Running run-tests step. 09:01:28 INFO - ##### 09:01:28 INFO - Running pre-action listener: _resource_record_pre_action 09:01:28 INFO - Running main action method: run_tests 09:01:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:28 INFO - Python 2.7.3 09:01:28 INFO - Return code: 0 09:01:28 INFO - grabbing minidump binary from tooltool 09:01:28 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')]} 09:01:28 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 0x1662410>, '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 0x1663310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16637a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:28 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 09:01:28 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 09:01:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:28 INFO - Return code: 0 09:01:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:01:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:01:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:01:29 INFO - Return code: 0 09:01:29 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:01:29 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:01:29 INFO - Using env: {'DISPLAY': ':0', 09:01:29 INFO - 'HOME': '/home/cltbld', 09:01:29 INFO - 'LANG': 'en_US.UTF-8', 09:01:29 INFO - 'LANGUAGE': 'en_US:en', 09:01:29 INFO - 'LOGNAME': 'cltbld', 09:01:29 INFO - 'MAIL': '/var/mail/cltbld', 09:01:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:29 INFO - 'MOZ_NO_REMOTE': '1', 09:01:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:29 INFO - 'NO_EM_RESTART': '1', 09:01:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:29 INFO - 'PWD': '/builds/slave/test', 09:01:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:29 INFO - 'SHELL': '/bin/bash', 09:01:29 INFO - 'SHLVL': '1', 09:01:29 INFO - 'TERM': 'linux', 09:01:29 INFO - 'TMOUT': '86400', 09:01:29 INFO - 'USER': 'cltbld', 09:01:29 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464018583.524884-845511080', 09:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:29 INFO - '_': '/tools/buildbot/bin/python'} 09:01:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:01:29 INFO - mozversion application_buildid: 20160523061540 09:01:29 INFO - mozversion application_changeset: 641d8d468d540ede408c427b1840e35217e44e76 09:01:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:01:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:29 INFO - mozversion application_name: Firefox 09:01:29 INFO - mozversion application_remotingname: firefox-dev 09:01:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:01:29 INFO - mozversion application_vendor: Mozilla 09:01:29 INFO - mozversion application_version: 48.0a2 09:01:29 INFO - mozversion platform_buildid: 20160523061540 09:01:29 INFO - mozversion platform_changeset: 641d8d468d540ede408c427b1840e35217e44e76 09:01:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:01:29 INFO - mozversion platform_version: 48.0a2 09:01:29 INFO - using testdate: 1464019289 09:01:29 INFO - actual date: 1464019289 09:01:29 INFO - starting webserver on 'localhost:38693' 09:01:29 INFO - SUITE-START | Running 2 tests 09:01:29 INFO - TEST-START | dromaeo_css 09:01:29 INFO - operating with platform_type : linux_ 09:01:29 INFO - Initialising browser for dromaeo_css test... 09:01:29 INFO - TEST-INFO | started process 7753 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZCQHq9/profile http://localhost:38693/getInfo.html) 09:01:34 INFO - PROCESS | 7753 | __metrics Screen width/height:1600/1200 09:01:34 INFO - PROCESS | 7753 | colorDepth:24 09:01:34 INFO - PROCESS | 7753 | Browser inner width/height: 1024/697 09:01:34 INFO - PROCESS | 7753 | __metrics 09:01:35 ERROR - PROCESS | 7753 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:01:36 INFO - TEST-INFO | 7753: exit 0 09:01:36 INFO - Browser initialized. 09:01:36 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:36 INFO - TEST-INFO | started process 7845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZCQHq9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:48 INFO - PROCESS | 7845 | RSS: Main: 142241792 09:01:48 INFO - PROCESS | 7845 | 09:02:40 INFO - PROCESS | 7845 | 1464019360024 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] 09:04:47 INFO - PROCESS | 7845 | Cycle 1(1): loaded http://localhost:38693/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38693/tests/dromaeo/cssquery-ext.html) 09:04:47 INFO - PROCESS | 7845 | RSS: Main: 257904640 09:04:47 INFO - PROCESS | 7845 | 09:07:50 INFO - PROCESS | 7845 | Cycle 1(1): loaded http://localhost:38693/tests/dromaeo/cssquery-ext.html (next: http://localhost:38693/tests/dromaeo/cssquery-jquery.html) 09:07:50 INFO - PROCESS | 7845 | RSS: Main: 230633472 09:07:50 INFO - PROCESS | 7845 | 09:10:52 INFO - PROCESS | 7845 | Cycle 1(1): loaded http://localhost:38693/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38693/tests/dromaeo/cssquery-mootools.html) 09:10:53 INFO - PROCESS | 7845 | RSS: Main: 228651008 09:10:53 INFO - PROCESS | 7845 | 09:13:55 INFO - PROCESS | 7845 | Cycle 1(1): loaded http://localhost:38693/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38693/tests/dromaeo/cssquery-prototype.html) 09:13:55 INFO - PROCESS | 7845 | RSS: Main: 223088640 09:13:55 INFO - PROCESS | 7845 | 09:16:58 INFO - PROCESS | 7845 | Cycle 1(1): loaded http://localhost:38693/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38693/tests/dromaeo/cssquery-yui.html) 09:16:58 INFO - PROCESS | 7845 | RSS: Main: 212713472 09:16:58 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | Cycle 1(1): loaded http://localhost:38693/tests/dromaeo/cssquery-yui.html (next: http://localhost:38693/tests/dromaeo/cssquery-dojo.html) 09:20:01 INFO - PROCESS | 7845 | RSS: Main: 255459328 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | __start_tp_report 09:20:01 INFO - PROCESS | 7845 | _x_x_mozilla_page_load 09:20:01 INFO - PROCESS | 7845 | _x_x_mozilla_page_load_details 09:20:01 INFO - PROCESS | 7845 | |i|pagename|runs| 09:20:01 INFO - PROCESS | 7845 | |0;dojo.html;3315.983420082899;3367.595888493381;3376.628570857207;3404.0979140527747;3415.2486452980334;5490.972545137273;5515.310586176728;5515.972420137899;5522.199281104243;5523.502884740373;5506.091494903336;5577.832665020046;5577.8605534861645;5590.832275031756;5607.467290607396;4932.950670493285;4958.35541379621;5084.720340381277;5105.18317069269;5110.131277682803;4981.277714731362;5021.196608542634;5056.165732654108;5109.4379618241965;5123.180291153416;5017.824376146836;5095.210242412432;5138.3577052868395;5154.74226288686;5158.226266060084;5102.668326558762;5118.846434606968;5124.461931497213;5128.1794912813775;5162.303089082963;4749.4063242094735;4907.1657818171;4966;5013.774380152902;5022.773975171126;5309.256704061435;5318.042752304584;5323.281357016792;5328.040952628526;5330.973345133289;5695.658260504383;5738.6556806591325;5740.54075673945;5741.942580574183;5766.654000759968;5750.281214848144;5786.797462088806;5822.679752613604;5922.822315330547;5993;5417.160340147283;5417.891642167174;5418.729063546807;5435;5450.373207081197;5880.852978675482;5882.470577648032;5883.17635531026;5887.116932460165;5908.113782932594;5658.151277308354;5658.462446067617;5695.060315047971;5753.223314852525;5851.21008663825;5858.179854820329;5893.734781934823;5896.6756828374855;5900.114982752536;5914.76340946366;5863.472287494146;5871.060630299217;5871.442212989759;5872.060470324727;5872.383399742966;5846.181534585162;5879.7060146993335;5881.647101173943;5985.940140598538;5998.760049598054;4484.1031793641405;4485.0805584855025;4494.325851122358;4498.055408364215;4507.3689683444345;3980.0447892506168;4023;4028.4561584186345;4048.1903619276263;4058.1072164123843;5546.084895992197;5590.357108932443;5648.209250704905;5681.375048744635;5693.17448969897;5824.79613213544;5834.533237341087;5835.241418615553;5836.591438599257;5840.153177789197;5479.123340265538;5536.169574563767;5536.169574563848;5542.778288868481;5551.944480555224;4976.178930476503;4977.203647416396;5054.671446355975;5071.721055341918;5071.847844564669;5952.613080149777;5962.075878238793;5973.850653733604;5976.372480889444;5990.251218597676;5750.856228594319;5756.539476841759;5761.452661997104;5765.711714414347;5788.971055144697;6099.603525770811;6142.846428839315;6205.751769929152;6209.96895015522;6218.657973811484;5716.399778023248;5747.597668163188;5754.7122643866805;5757.280339957505;5783.739731712168;5769.192313076257;5847.912281315698;5848.298204215351;5849.64902105875;5853.560982926383;5798.797042103506;5850.824475265749;5861.912071318849;5874.706264686663;5875.294964604275;5756.798512052058;5760.481556659921;5764.135379692912;5764.596478246483;5770.567207459541;5607.243022191952;5635.126555383922;5682.715864206856;5686.2323586315315;5690.203371527907;5822.883542329045;5865.824025279248;5868.2077919480325;5904.675242861512;5906.114082887601;5759.712014399347;5760.567957403127;5762.164486149569;5762.884742305214;5798.072308430631 09:20:01 INFO - PROCESS | 7845 | |1;ext.html;619.8481372063842;661.5600625583985;671.4494114825843;672.7981605518344;692.2108795972589;32239.19402014951;33130.17174570637;33135.66864331353;33136.17159571009;33149.171270718245;41155.794221028926;41662.16675666485;41811.372829407504;41940.37089443661;41944.58055419445;8769.386142969995;8922.687705930293;8932.508712020835;8938.553072346356;8940.64237430502;8270.172982701712;8819.73540793774;8928.464292142493;10736.24846260762;10977.231593788438;10638.308509946828;10679.733006674856;10682.092022178123;10699.572017119306;10701.304415213026;10566.418846963414;10638;10639.202059845466;10648.41433721145;10679.25245232838;10472.68581942543;10582.841257381146;10590.576376944913;10597.417142057184;10598.364098154136;43280;44329.55670443286;44371.556284437065;44427.667169984954;44474;3855.306044911915;3924.1955399143108;3939.704522160821;4017.959820401788;4336.306191009455;34040.659593403994;34504.30991380158;34613.30773384543;34758;34841.82579087114;4432.822687092513;4490.259107247301;4542.727436353796;4549.431321084864;4663.044075964419;331899;332079;333394;333623;335148;39343.803280983986;39391.03044847746;39620.40569391462;63110.684446578394;63615.045774313425;38148;38257.426138607945;38342;38427;38431.61568384336;15965.36138554468;16082.276297566637;16082.597935051717;16091.919540402223;16092.919535402481;12429.937850310871;12610.49558017669;14322.713545729028;14384.568462946128;14427.92786036063;9285.675001374919;9388.483633400092;9402.200812930841;9458.1487666109;9492.481203007486;3541.769784964021;3574.3005988801983;3603.189282411489;3624.220792529566;3658.0671928658553;1069.705830896494;1081.5403453532374;1151.9287063031293;1161.1291531351487;1274.7705413025558;33652.3269534608;33742.15644608856;33902.830485847415;34274.48588271178;34314.48528272078;1257.2393701810456;1261.4512686981193;1305.3538498443331;1322.9140105893086;1398.175076704749;17464.912675436542;17846.375376861746;17984.820151798576;18111.72832407515;18195.727064094048;484880;489733;491239;491260;492178;60828.69585652043;62045;62145.378546214866;62175;62212.68893655503;1386.209860379574;1392.7562676531566;1436.6623843396794;1522.048719550281;1600.6238533944513;25058.37354066163;32550.83724581362;32600.184995375304;34181;35470;59333;60306.09540856803;61403;61442.69278653578;61546.69226653838;426322;428608;430163;430492;431803;39850.40224396694;46068.30897536473;46526.53473465222;49533.75233123956;50293.74853125711;49998.50001500084;50807.74596126996;51236.231456527436;54211.186832198335;54536.72731636475;39471.65262953389;41355;44036.559634403246;45009.32486012778;45583;43389.783051085804;43392.78303608462;43956;44440.263778046115;44790.3281450785;45390.31914521351;45813;46310.073798524514;46411;46748;37209.813950930074;39392;39642;39960;40109.1978160441;30209.395812084076;30266.54600181043;30356.24109397283;30544;30744.23139421443 09:20:01 INFO - PROCESS | 7845 | |2;jquery.html;429.3452484960436;435.25354017859365;439.76252823475306;440.84790747192216;444.86209275124696;18971.71542426863;19453.902730486345;19544.752353479354;20151;20580.588388232227;18513;18574.721379179322;18596.34912778053;18864.528386790338;19923.800761992377;5747.1666608341975;5807.39022402648;5990.97004514979;6002.699865006733;6004.969975150118;4283.550227226143;5948.464638182562;5971.641701497924;6290.622562646233;6606.2733099359275;6560.343965603449;6576.473882089422;6579.34206579343;6580.407763301302;6582.605043697394;6368.10846481493;6448.387403196689;6530.8040758777315;6551.606903585776;6554.442872355831;4071.6131967463043;5695.629784064023;5804.477597016289;5822.243108395924;5945.346011938684;22644.433889152737;22691.77308226913;23017.76982230173;23074;23080.65379019316;2524.9495010099877;2570.5244529761867;2646.0474229277443;2990.147807874767;3310.851011704455;13822.239776812272;13997.540959025988;14401.351939162763;14504.41982320706;14668.266586670623;2067.441790716498;2099.496120930981;2107.95784084319;2128.946776330589;2147.4738689021133;78341.60829195875;80626;81466.59266703628;82503.58748206281;86747.56626216891;11190.496427660612;11204.327740335606;11372.14708896836;11463.656090317303;11601.535938562363;15893.602659933364;16024.919875400548;16067.035977841368;16076;16099.436519721754;8993.23557497607;9119;9136.954315228515;9190.678326258549;9226;8923;9068.637254509746;9087.227585655293;9196.540172991323;9204.263658907272;7006.544574602634;7069;7074.787756367317;7093.929060709327;7129.750458733919;1750.3078012768367;1967.8819270843794;2003.5892642008353;2077.896105194734;2089.6595812689884;1033.4832583708146;1051.93688378696;1131.6209069961656;1220.713132413882;1242.5402601037492;18625.627487450445;18672.352494707127;20387.4903127423;20421.183152674024;20445.182192712124;1068.4871261794383;1088.7985722641179;1096.6326280695896;1107.5237647811518;1189.2270024484155;10486.580536778596;11829.822552661717;11840.585579504848;11908.404579770977;11929.821052684216;527361;546175;558636;560805;561719;85000.57499712585;87332.5633371829;90955;90975;91089.54455227681;1113.3486910157649;1118.625260537729;1121.3047910295668;1129.5989923577176;1142.8114361130486;17121.571960701083;17829.73255401144;17866.196021178817;17867.821321786614;17914.372996945043;37663.62336376601;38645.2270954585;39124.413133802445;39133.804330978164;39377.015574610865;49864;49999.50000500094;50158.749206253735;50335.24497132619;50884.49115508798;9137.223336016246;9371.437713737198;9399.10708482693;9583.856242156235;9614.18279446227;12056.457459414172;14018.369173387426;14073.155610663393;14240.786388204393;14268.14391136535;12810.423530941316;12844.743105137659;12904.290264035568;12967.546135885199;13199.868001319865;11988.280703157838;12274;12408.069394795608;12491;12691.238525688488;12495.125341226027;12541.184822986477;12605.487341519076;12623.81064284018;13012.8048079277;9179.632814687338;9237.861432078389;9328.207102396373;9397.953010234905;9502.097300756417;12033.578824741093;12224.938875305566;12306.323152226714;12309.199902006341;12363.69090772738 09:20:01 INFO - PROCESS | 7845 | |3;mootools.html;1387.9653008674788;1469.478335191007;1507.5100592307504;1702.4211767998877;1866.188208129463;8421.621027053781;8626.309895208384;8641.790149379076;8649.654013839454;8651.480911145329;8172.5505097219775;8316.83366332673;8367.83264334713;8374.623141958611;8381.203785640355;2012.5069358007308;2305.1010106058566;2314.2941402872116;2335.824813139013;2360.0559776089617;2408.036785285882;2427.320350301918;2427.599446091393;2434.306222726523;2438.231956933569;2279.623862062758;2350.5533948549805;2354.5997180479358;2354.811615070799;2357.068957761683;2007.7590689117253;2278.054607337953;2312.8034117099896;2317.073170731704;2319.8724070176136;2275.055851821492;2307.492351682627;2315.455867871049;2320.164740693349;2321.58211521926;8717.389782715212;8734.781630459225;8747.256483198937;8776.736697899074;8789.912100878973;5213.921791173136;5325.573954083664;5341.252224688546;5348.438413966555;5443.047466693352;3279.8032118072992;3284.868605255787;3320.020593924798;3347.581552305962;3371.174062354739;1087.9184061195442;1098.0831006109909;1104.7845670094334;1107.1309022417445;1129.8757136714955;11995.280283183038;12024.33866137371;12133.029357651367;12149.271043737315;12155.574554890536;4525.298578720303;4600.953990460052;4626.97686511572;4716.410448693913;4763.499832517536;10292.536835842406;10336.896631033593;10361.896381036093;10372.325798823053;10372.377657340432;4839.7096174229655;5209.322788037531;5252.789888404497;5258.39528454225;5262.578993680497;4758.571728544378;4827;4862.489438608965;4864.221724524059;4882.609391248691;4060;4088.1619268049976;4138.982139121558;4169.583041695867;4207.26372884744;1335.7729186038396;1357.172125003747;1365.5766712319212;1366.5353779714944;1368.5620601407454;999.430893497217;1004.9096730047834;1006.2402885821158;1009.3287963504334;1023;5973;6077.544184186204;6206.162168107338;6274.403931626488;6291.339409362043;432.7490055767647;435.5753140687804;437.61927123402205;439.8218721417831;446.3037661248463;4087.3868919662286;4120.381942708617;4131.111810960658;4132.111596006873;4153.501579810442;10403.47982600867;10631.681049568526;10861;10868.239223254455;10870.565177392973;10612.734681633021;10650.201234907412;10651.520681569347;10655.30740501865;10662.360258384522;8144.981877265342;8174.223448772357;8183.672653093929;8198.057223419264;8198.918010819816;5571.749271282721;5632.971835140962;5650.85872853182;5667.8866422672145;5804.303483581997;9268.165865071906;9350.579223935076;9515.476648784383;9531.714048578553;9667.46828924416;9457.19613832832;9524.285678573997;9663.9033609663;9684.225261979165;9729.416235025921;6808.693608787517;7509.7371591994015;7621.580813055335;7784.338331241908;7897.881531776913;8406.579671016547;8409.747707568782;8438.873416898874;8449.95775021121;8455.788605284917;7492.812679683051;7733.961330193313;7757.844843102994;7780.766577002699;7876.803079923047;7587.658555364911;7615.581143037186;7712.035995500562;7753.1859154787935;7798.610069496616;7472.738454154079;7488.400927925644;7513.699452021858;7529.510581812165;7531.284527969834;5130.12787826078;5340.279062326692;5424.321959755031;5465.72671366438;5469.644473109235;5350.357957045179;5399.541039011728;5513.421090785409;5558.277423934863;5591.049521581423 09:20:01 INFO - PROCESS | 7845 | |4;prototype.html;360.8520506592297;364.8941806876005;365.641671162261;367.4671725997305;375.0155840917593;6217.906731399027;6252.187215661963;6288.18253627029;6292.182016337881;6338.968305158468;6212.65830379329;6229.37706229378;6231.50147988161;6235.251769787632;6241.937580624204;4554.954450455502;4700.576948074672;4817.181079216524;4819.228923372261;4825.589824864891;4768.809247630108;4801.111794318045;4812.181929072049;4813.711177329372;4819.156647586676;4548.545145485458;4746.857594272177;4782.569568738812;4783.425988881322;4786.784594693229;4611.608013318872;4680.5085466026085;4684.531546845323;4769.642276829251;4776.402949631296;4171.645410140141;4182.707210495236;4195.685323600742;4197.097624010852;4197.328427451593;5986.73059712314;5990.7903223386975;5991.610545314541;6002.189704389895;6009.75960961561;5547.25112109864;5560.499555040065;5560.666360018371;5561.63849349791;5563.972180139114;6464.41820236181;6574.276829548746;6588.439982601484;6589.4069533742195;6600.60396376219;4689.460712018127;4696.577308042292;4697.295405689139;4701.929471057937;4704.858854234379;6836;6858.142732158481;6866.073080134168;6868.175818901764;6876.415504682108;4768.928466073012;4772.093302272557;4778.259369797687;4786.401699787527;4791.6406269529925;4847.830325938646;4848.321235027099;4851.611871050308;4852.51474852519;4855.635827312965;5538.584606154554;5544.972275138599;5558.360788509292;5563.749631266603;5566.332040155126;6467.51493637979;6617.86764264712;6618.139641846626;6618.801435956929;6627.5029372797235;1678.8572971297333;1685.4185306069387;1697.3041053168388;1698.9660206795795;1700.8299170082892;1091.0889407344955;1107.9778404432027;1142.1433924556582;1142.520141540555;1165.854268216473;6292.1505596743855;6297.874042519215;6314.778982735582;6315.052742088632;6316.11574379578;6901.689423975933;6905.861882762417;6914.135733033371;6922;6928.688209030605;6484.351564843478;6489.545731798728;6493.675316234169;6501.609903405811;6506.34936506355;3664;3703.9629603704157;3706.592274849764;3707.5550933887566;3709.3137769512728;7049.436045116378;7115.679794409264;7167.462440316997;7179.712811487587;7179.964100179465;7011.544249623758;7024.262452442522;7025.437964962791;7026.859462810818;7044.401225895754;7158.677859496335;7162.314132200425;7168.566286742568;7171.462140339496;7187.209406965229;6869.793906182623;6877.518573699793;6878.828029299308;6881.759138430131;6886.173659160932;6570.375814297634;6769.1876974763345;6818.284080171709;6818.386345228953;6835.14560679915;7176.641167941686;7178.928210717826;7181.425485961215;7188.784336469915;7194.928050719635;4693.272542755878;4730.101280756645;4738.597219236266;4738.981119059419;4739.952600473951;6899.102746744686;6940.063091482585;6970.302969702989;6971.616561088986;6981.0924579804305;6473.805785826433;6693.665316734046;6723.5293529452465;6725.293844146294;6752.729890804313;6699.598024118569;6706.765263215764;6708.42978346846;6760.188777346553;6776.28848970851;6544.83637909037;6551.4103730664465;6560.967195163994;6564.409203171737;6575.539712220098;6695.765648202289;6704.664766761545;6706.5640733352175;6709.7316107355155;6711.261761206166;6431.5819471734185;6545.5090868184125;6553.311902250386;6571.835704107436;6581.3747693969 09:20:01 INFO - PROCESS | 7845 | |5;yui.html;830.8836762853198;839.8782176584396;851.7742798158486;919.5402298850574;931.70185540627;14154.929225353872;14407;16861.241244144014;17360.652786944254;17456.825431745678;15457.84542154578;15603.531894043168;15608.531744047697;15612.453564125259;15703.84296157041;750.969961201554;919.5356345045776;921.345844450441;931.6646007437317;938.9342746007782;924.0759240759207;925.4447331601053;926.2266007883426;930.1489137439235;931.9254459643246;972.1396564040804;975.9853602195973;976.5312649928039;976.6191185437649;976.9706908792712;787.4330482052912;954.0745476887442;971.7522031881861;973.5862258540092;974.6101559376306;705.2806137739477;712.0671919785098;713.1869668577825;713.2582114600809;713.2795876165059;15379.307931143125;15415.460458883885;15423.614409639737;15447.61380965485;15482.61293467661;14170.07894486855;14221.360038798175;14244.21656808875;14572.562823115324;14665.266736663125;12007.879921200763;12294.508219671203;12296.569620063256;12511.624651260478;12631;4741.407324084489;4755.405574303212;4762.214234651313;4789.712617242976;4825.445073816521;53754.73122634401;53984.73007634976;54010.459895400934;54025;54419;11189.216754827085;11526.711832204262;11594.906123742248;11817.527298908119;11907.583234586747;20587.279445219647;20603.58792824135;20789.272375466786;20797.480062998548;20818.375448736562;8440.873386899202;8539.06070332263;8573.56832592743;8687.565621718888;8792.42849214799;4992.98514442645;5068.746562671926;5096.05722941257;5138.511841375063;5207.244949482304;3509.1402606361603;3559.964400356015;3561;3562.4834399012516;3566.28674265148;631.1195881745032;639.5874660843823;641.1344684675651;650.443870490733;661.867626474707;772.3628006894222;784.7763387434554;791.1337085891055;804.5092493578912;815.5962798414822;9291.117343852324;9440.339176257596;9536.952315238519;9645.662401816044;9674.177694896012;684.2165720250285;722.6459035072777;761.1873730115489;763.7326935572617;815.9551224682699;4297.8495752648505;4357.607815296638;4361.345798130305;4369.213541562485;4487.551244875552;64081.679591602675;64903.350966490674;65257.67371163209;65706.67146664236;67891;56650.716746416;58574;58728.41271587315;58946.70526647339;59223.70388148118;50718.746406268474;50977.23534146991;50989.745051274505;51315;52585;8466.74599762008;8643.438176518506;8725.363722848328;8892.599833007402;8971.865422018805;38407.23185536218;38608.22783544369;38991.415128772525;40802.183956320114;41135.7943210282;49030.5096949026;51732;51824;55383.446165537825;55454.72272638611;13216.537421190596;13233.86766132365;13265;13399.196048237138;13473.865261347653;14309.64225894361;14409;14485.348159332645;15103.093814371174;15228.847711522743;13687.452501899812;13910.165390076629;14065.296735163405;14236.35936382886;14318.14091154534;13414.195148290743;13698.931505342409;13699.011555060622;13741.587752367444;14212.365577958668;12383;12605.604142407868;12726.809097863354;13124.934375328063;13590.93204533971;10965.451727413756;11177;11289.435528223721;11441.42792860337;11704.94147529257;11016;11049.779004420026;11081.612143574936;11215.60745373908;11232.157588180755 09:20:01 INFO - PROCESS | 7845 | __end_tp_report 09:20:01 INFO - PROCESS | 7845 | __start_cc_report 09:20:01 INFO - PROCESS | 7845 | _x_x_mozilla_cycle_collect,762 09:20:01 INFO - PROCESS | 7845 | __end_cc_report 09:20:01 INFO - PROCESS | 7845 | __startTimestamp1464020401393__endTimestamp 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | ------- Summary: start ------- 09:20:01 INFO - PROCESS | 7845 | Number of tests: 6 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | [#0] dojo.html Cycles:165 Average:5464.43 Median:5695.06 stddev:587.54 (10.3%) stddev-sans-first:564.65 09:20:01 INFO - PROCESS | 7845 | Values: 3316.0 3367.6 3376.6 3404.1 3415.2 5491.0 5515.3 5516.0 5522.2 5523.5 5506.1 5577.8 5577.9 5590.8 5607.5 4933.0 4958.4 5084.7 5105.2 5110.1 4981.3 5021.2 5056.2 5109.4 5123.2 5017.8 5095.2 5138.4 5154.7 5158.2 5102.7 5118.8 5124.5 5128.2 5162.3 4749.4 4907.2 4966.0 5013.8 5022.8 5309.3 5318.0 5323.3 5328.0 5331.0 5695.7 5738.7 5740.5 5741.9 5766.7 5750.3 5786.8 5822.7 5922.8 5993.0 5417.2 5417.9 5418.7 5435.0 5450.4 5880.9 5882.5 5883.2 5887.1 5908.1 5658.2 5658.5 5695.1 5753.2 5851.2 5858.2 5893.7 5896.7 5900.1 5914.8 5863.5 5871.1 5871.4 5872.1 5872.4 5846.2 5879.7 5881.6 5985.9 5998.8 4484.1 4485.1 4494.3 4498.1 4507.4 3980.0 4023.0 4028.5 4048.2 4058.1 5546.1 5590.4 5648.2 5681.4 5693.2 5824.8 5834.5 5835.2 5836.6 5840.2 5479.1 5536.2 5536.2 5542.8 5551.9 4976.2 4977.2 5054.7 5071.7 5071.8 5952.6 5962.1 5973.9 5976.4 5990.3 5750.9 5756.5 5761.5 5765.7 5789.0 6099.6 6142.8 6205.8 6210.0 6218.7 5716.4 5747.6 5754.7 5757.3 5783.7 5769.2 5847.9 5848.3 5849.6 5853.6 5798.8 5850.8 5861.9 5874.7 5875.3 5756.8 5760.5 5764.1 5764.6 5770.6 5607.2 5635.1 5682.7 5686.2 5690.2 5822.9 5865.8 5868.2 5904.7 5906.1 5759.7 5760.6 5762.2 5762.9 5798.1 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | [#1] ext.html Cycles:180 Average:58542.76 Median:21641.75 stddev:111677.36 (516.0%) stddev-sans-first:111905.96 09:20:01 INFO - PROCESS | 7845 | Values: 619.8 661.6 671.4 672.8 692.2 32239.2 33130.2 33135.7 33136.2 33149.2 41155.8 41662.2 41811.4 41940.4 41944.6 8769.4 8922.7 8932.5 8938.6 8940.6 8270.2 8819.7 8928.5 10736.2 10977.2 10638.3 10679.7 10682.1 10699.6 10701.3 10566.4 10638.0 10639.2 10648.4 10679.3 10472.7 10582.8 10590.6 10597.4 10598.4 43280.0 44329.6 44371.6 44427.7 44474.0 3855.3 3924.2 3939.7 4018.0 4336.3 34040.7 34504.3 34613.3 34758.0 34841.8 4432.8 4490.3 4542.7 4549.4 4663.0 331899.0 332079.0 333394.0 333623.0 335148.0 39343.8 39391.0 39620.4 63110.7 63615.0 38148.0 38257.4 38342.0 38427.0 38431.6 15965.4 16082.3 16082.6 16091.9 16092.9 12429.9 12610.5 14322.7 14384.6 14427.9 9285.7 9388.5 9402.2 9458.1 9492.5 3541.8 3574.3 3603.2 3624.2 3658.1 1069.7 1081.5 1151.9 1161.1 1274.8 33652.3 33742.2 33902.8 34274.5 34314.5 1257.2 1261.5 1305.4 1322.9 1398.2 17464.9 17846.4 17984.8 18111.7 18195.7 484880.0 489733.0 491239.0 491260.0 492178.0 60828.7 62045.0 62145.4 62175.0 62212.7 1386.2 1392.8 1436.7 1522.0 1600.6 25058.4 32550.8 32600.2 34181.0 35470.0 59333.0 60306.1 61403.0 61442.7 61546.7 426322.0 428608.0 430163.0 430492.0 431803.0 39850.4 46068.3 46526.5 49533.8 50293.7 49998.5 50807.7 51236.2 54211.2 54536.7 39471.7 41355.0 44036.6 45009.3 45583.0 43389.8 43392.8 43956.0 44440.3 44790.3 45390.3 45813.0 46310.1 46411.0 46748.0 37209.8 39392.0 39642.0 39960.0 40109.2 30209.4 30266.5 30356.2 30544.0 30744.2 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | [#2] jquery.html Cycles:180 Average:30894.53 Median:22668.10 stddev:90336.49 (398.5%) stddev-sans-first:90560.94 09:20:01 INFO - PROCESS | 7845 | Values: 429.3 435.3 439.8 440.8 444.9 18971.7 19453.9 19544.8 20151.0 20580.6 18513.0 18574.7 18596.3 18864.5 19923.8 5747.2 5807.4 5991.0 6002.7 6005.0 4283.6 5948.5 5971.6 6290.6 6606.3 6560.3 6576.5 6579.3 6580.4 6582.6 6368.1 6448.4 6530.8 6551.6 6554.4 4071.6 5695.6 5804.5 5822.2 5945.3 22644.4 22691.8 23017.8 23074.0 23080.7 2524.9 2570.5 2646.0 2990.1 3310.9 13822.2 13997.5 14401.4 14504.4 14668.3 2067.4 2099.5 2108.0 2128.9 2147.5 78341.6 80626.0 81466.6 82503.6 86747.6 11190.5 11204.3 11372.1 11463.7 11601.5 15893.6 16024.9 16067.0 16076.0 16099.4 8993.2 9119.0 9137.0 9190.7 9226.0 8923.0 9068.6 9087.2 9196.5 9204.3 7006.5 7069.0 7074.8 7093.9 7129.8 1750.3 1967.9 2003.6 2077.9 2089.7 1033.5 1051.9 1131.6 1220.7 1242.5 18625.6 18672.4 20387.5 20421.2 20445.2 1068.5 1088.8 1096.6 1107.5 1189.2 10486.6 11829.8 11840.6 11908.4 11929.8 527361.0 546175.0 558636.0 560805.0 561719.0 85000.6 87332.6 90955.0 90975.0 91089.5 1113.3 1118.6 1121.3 1129.6 1142.8 17121.6 17829.7 17866.2 17867.8 17914.4 37663.6 38645.2 39124.4 39133.8 39377.0 49864.0 49999.5 50158.7 50335.2 50884.5 9137.2 9371.4 9399.1 9583.9 9614.2 12056.5 14018.4 14073.2 14240.8 14268.1 12810.4 12844.7 12904.3 12967.5 13199.9 11988.3 12274.0 12408.1 12491.0 12691.2 12495.1 12541.2 12605.5 12623.8 13012.8 9179.6 9237.9 9328.2 9398.0 9502.1 12033.6 12224.9 12306.3 12309.2 12363.7 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | [#3] mootools.html Cycles:180 Average:5748.80 Median:4833.35 stddev:3223.26 (66.7%) stddev-sans-first:3215.64 09:20:01 INFO - PROCESS | 7845 | Values: 1388.0 1469.5 1507.5 1702.4 1866.2 8421.6 8626.3 8641.8 8649.7 8651.5 8172.6 8316.8 8367.8 8374.6 8381.2 2012.5 2305.1 2314.3 2335.8 2360.1 2408.0 2427.3 2427.6 2434.3 2438.2 2279.6 2350.6 2354.6 2354.8 2357.1 2007.8 2278.1 2312.8 2317.1 2319.9 2275.1 2307.5 2315.5 2320.2 2321.6 8717.4 8734.8 8747.3 8776.7 8789.9 5213.9 5325.6 5341.3 5348.4 5443.0 3279.8 3284.9 3320.0 3347.6 3371.2 1087.9 1098.1 1104.8 1107.1 1129.9 11995.3 12024.3 12133.0 12149.3 12155.6 4525.3 4601.0 4627.0 4716.4 4763.5 10292.5 10336.9 10361.9 10372.3 10372.4 4839.7 5209.3 5252.8 5258.4 5262.6 4758.6 4827.0 4862.5 4864.2 4882.6 4060.0 4088.2 4139.0 4169.6 4207.3 1335.8 1357.2 1365.6 1366.5 1368.6 999.4 1004.9 1006.2 1009.3 1023.0 5973.0 6077.5 6206.2 6274.4 6291.3 432.7 435.6 437.6 439.8 446.3 4087.4 4120.4 4131.1 4132.1 4153.5 10403.5 10631.7 10861.0 10868.2 10870.6 10612.7 10650.2 10651.5 10655.3 10662.4 8145.0 8174.2 8183.7 8198.1 8198.9 5571.7 5633.0 5650.9 5667.9 5804.3 9268.2 9350.6 9515.5 9531.7 9667.5 9457.2 9524.3 9663.9 9684.2 9729.4 6808.7 7509.7 7621.6 7784.3 7897.9 8406.6 8409.7 8438.9 8450.0 8455.8 7492.8 7734.0 7757.8 7780.8 7876.8 7587.7 7615.6 7712.0 7753.2 7798.6 7472.7 7488.4 7513.7 7529.5 7531.3 5130.1 5340.3 5424.3 5465.7 5469.6 5350.4 5399.5 5513.4 5558.3 5591.0 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | [#4] prototype.html Cycles:180 Average:5577.23 Median:6306.33 stddev:1681.41 (26.7%) stddev-sans-first:1639.91 09:20:01 INFO - PROCESS | 7845 | Values: 360.9 364.9 365.6 367.5 375.0 6217.9 6252.2 6288.2 6292.2 6339.0 6212.7 6229.4 6231.5 6235.3 6241.9 4555.0 4700.6 4817.2 4819.2 4825.6 4768.8 4801.1 4812.2 4813.7 4819.2 4548.5 4746.9 4782.6 4783.4 4786.8 4611.6 4680.5 4684.5 4769.6 4776.4 4171.6 4182.7 4195.7 4197.1 4197.3 5986.7 5990.8 5991.6 6002.2 6009.8 5547.3 5560.5 5560.7 5561.6 5564.0 6464.4 6574.3 6588.4 6589.4 6600.6 4689.5 4696.6 4697.3 4701.9 4704.9 6836.0 6858.1 6866.1 6868.2 6876.4 4768.9 4772.1 4778.3 4786.4 4791.6 4847.8 4848.3 4851.6 4852.5 4855.6 5538.6 5545.0 5558.4 5563.7 5566.3 6467.5 6617.9 6618.1 6618.8 6627.5 1678.9 1685.4 1697.3 1699.0 1700.8 1091.1 1108.0 1142.1 1142.5 1165.9 6292.2 6297.9 6314.8 6315.1 6316.1 6901.7 6905.9 6914.1 6922.0 6928.7 6484.4 6489.5 6493.7 6501.6 6506.3 3664.0 3704.0 3706.6 3707.6 3709.3 7049.4 7115.7 7167.5 7179.7 7180.0 7011.5 7024.3 7025.4 7026.9 7044.4 7158.7 7162.3 7168.6 7171.5 7187.2 6869.8 6877.5 6878.8 6881.8 6886.2 6570.4 6769.2 6818.3 6818.4 6835.1 7176.6 7178.9 7181.4 7188.8 7194.9 4693.3 4730.1 4738.6 4739.0 4740.0 6899.1 6940.1 6970.3 6971.6 6981.1 6473.8 6693.7 6723.5 6725.3 6752.7 6699.6 6706.8 6708.4 6760.2 6776.3 6544.8 6551.4 6561.0 6564.4 6575.5 6695.8 6704.7 6706.6 6709.7 6711.3 6431.6 6545.5 6553.3 6571.8 6581.4 09:20:01 INFO - PROCESS | 7845 | 09:20:01 INFO - PROCESS | 7845 | [#5] yui.html Cycles:180 Average:15862.73 Median:27011.75 stddev:18117.89 (67.1%) stddev-sans-first:18133.55 09:20:01 INFO - PROCESS | 7845 | Values: 830.9 839.9 851.8 919.5 931.7 14154.9 14407.0 16861.2 17360.7 17456.8 15457.8 15603.5 15608.5 15612.5 15703.8 751.0 919.5 921.3 931.7 938.9 924.1 925.4 926.2 930.1 931.9 972.1 976.0 976.5 976.6 977.0 787.4 954.1 971.8 973.6 974.6 705.3 712.1 713.2 713.3 713.3 15379.3 15415.5 15423.6 15447.6 15482.6 14170.1 14221.4 14244.2 14572.6 14665.3 12007.9 12294.5 12296.6 12511.6 12631.0 4741.4 4755.4 4762.2 4789.7 4825.4 53754.7 53984.7 54010.5 54025.0 54419.0 11189.2 11526.7 11594.9 11817.5 11907.6 20587.3 20603.6 20789.3 20797.5 20818.4 8440.9 8539.1 8573.6 8687.6 8792.4 4993.0 5068.7 5096.1 5138.5 5207.2 3509.1 3560.0 3561.0 3562.5 3566.3 631.1 639.6 641.1 650.4 661.9 772.4 784.8 791.1 804.5 815.6 9291.1 9440.3 9537.0 9645.7 9674.2 684.2 722.6 761.2 763.7 816.0 4297.8 4357.6 4361.3 4369.2 4487.6 64081.7 64903.4 65257.7 65706.7 67891.0 56650.7 58574.0 58728.4 58946.7 59223.7 50718.7 50977.2 50989.7 51315.0 52585.0 8466.7 8643.4 8725.4 8892.6 8971.9 38407.2 38608.2 38991.4 40802.2 41135.8 49030.5 51732.0 51824.0 55383.4 55454.7 13216.5 13233.9 13265.0 13399.2 13473.9 14309.6 14409.0 14485.3 15103.1 15228.8 13687.5 13910.2 14065.3 14236.4 14318.1 13414.2 13698.9 13699.0 13741.6 14212.4 12383.0 12605.6 12726.8 13124.9 13590.9 10965.5 11177.0 11289.4 11441.4 11704.9 11016.0 11049.8 11081.6 11215.6 11232.2 09:20:01 INFO - PROCESS | 7845 | -------- Summary: end -------- 09:20:01 INFO - PROCESS | 7845 | 09:20:02 INFO - TEST-INFO | 7845: exit 0 09:20:02 INFO - TEST-OK | dromaeo_css | took 1112571ms 09:20:02 INFO - TEST-START | kraken 09:20:02 INFO - operating with platform_type : linux_ 09:20:02 INFO - Initialising browser for kraken test... 09:20:02 INFO - TEST-INFO | started process 8035 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgR1jpb/profile http://localhost:38693/getInfo.html) 09:20:06 INFO - PROCESS | 8035 | __metrics Screen width/height:1600/1200 09:20:06 INFO - PROCESS | 8035 | colorDepth:24 09:20:06 INFO - PROCESS | 8035 | Browser inner width/height: 1024/697 09:20:06 INFO - PROCESS | 8035 | __metrics 09:20:07 ERROR - PROCESS | 8035 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:20:08 INFO - TEST-INFO | 8035: exit 0 09:20:08 INFO - Browser initialized. 09:20:08 INFO - Running cycle 1/1 for kraken test... 09:20:08 INFO - TEST-INFO | started process 8123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgR1jpb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:20 INFO - PROCESS | 8123 | RSS: Main: 142110720 09:20:20 INFO - PROCESS | 8123 | 09:20:23 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38693/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:23 INFO - PROCESS | 8123 | RSS: Main: 197025792 09:20:23 INFO - PROCESS | 8123 | 09:20:26 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38693/tests/kraken/driver.html?testName=audio-dft) 09:20:27 INFO - PROCESS | 8123 | RSS: Main: 182947840 09:20:27 INFO - PROCESS | 8123 | 09:20:30 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38693/tests/kraken/driver.html?testName=audio-fft) 09:20:30 INFO - PROCESS | 8123 | RSS: Main: 272736256 09:20:30 INFO - PROCESS | 8123 | 09:20:32 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38693/tests/kraken/driver.html?testName=audio-oscillator) 09:20:33 INFO - PROCESS | 8123 | RSS: Main: 179912704 09:20:33 INFO - PROCESS | 8123 | 09:20:35 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38693/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:36 INFO - PROCESS | 8123 | RSS: Main: 177754112 09:20:36 INFO - PROCESS | 8123 | 09:20:39 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38693/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:39 INFO - PROCESS | 8123 | RSS: Main: 212533248 09:20:39 INFO - PROCESS | 8123 | 09:20:43 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38693/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:43 INFO - PROCESS | 8123 | RSS: Main: 212324352 09:20:43 INFO - PROCESS | 8123 | 09:20:46 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38693/tests/kraken/driver.html?testName=json-parse-financial) 09:20:46 INFO - PROCESS | 8123 | RSS: Main: 212025344 09:20:46 INFO - PROCESS | 8123 | 09:20:48 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38693/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:49 INFO - PROCESS | 8123 | RSS: Main: 191438848 09:20:49 INFO - PROCESS | 8123 | 09:20:50 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:51 INFO - PROCESS | 8123 | RSS: Main: 195723264 09:20:51 INFO - PROCESS | 8123 | 09:20:53 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:54 INFO - PROCESS | 8123 | RSS: Main: 210128896 09:20:54 INFO - PROCESS | 8123 | 09:20:56 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:56 INFO - PROCESS | 8123 | RSS: Main: 213725184 09:20:56 INFO - PROCESS | 8123 | 09:20:59 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:00 INFO - PROCESS | 8123 | RSS: Main: 209100800 09:21:00 INFO - PROCESS | 8123 | 09:21:01 INFO - PROCESS | 8123 | Cycle 1(1): loaded http://localhost:38693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38693/tests/kraken/driver.html?testName=ai-astar) 09:21:02 INFO - PROCESS | 8123 | RSS: Main: 272396288 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | __start_tp_report 09:21:02 INFO - PROCESS | 8123 | _x_x_mozilla_page_load 09:21:02 INFO - PROCESS | 8123 | _x_x_mozilla_page_load_details 09:21:02 INFO - PROCESS | 8123 | |i|pagename|runs| 09:21:02 INFO - PROCESS | 8123 | |0;ai-astar;111;110;110;109;110;111;106;109;108;110 09:21:02 INFO - PROCESS | 8123 | |1;audio-beat-detection;150;127;161;182;126;162;158;129;185;127 09:21:02 INFO - PROCESS | 8123 | |2;audio-dft;160;161;161;161;160;159;162;160;161;161 09:21:02 INFO - PROCESS | 8123 | |3;audio-fft;75;76;77;172;75;73;78;78;168;76 09:21:02 INFO - PROCESS | 8123 | |4;audio-oscillator;92;92;156;93;94;93;154;92;94;149 09:21:02 INFO - PROCESS | 8123 | |5;imaging-gaussian-blur;116;117;116;117;117;117;117;117;117;116 09:21:02 INFO - PROCESS | 8123 | |6;imaging-darkroom;145;146;146;146;146;146;146;145;146;145 09:21:02 INFO - PROCESS | 8123 | |7;imaging-desaturate;94;95;94;95;95;95;94;95;95;95 09:21:02 INFO - PROCESS | 8123 | |8;json-parse-financial;57;55;55;55;54;55;58;76;55;53 09:21:02 INFO - PROCESS | 8123 | |9;json-stringify-tinderbox;58;58;57;58;59;58;58;58;57;57 09:21:02 INFO - PROCESS | 8123 | |10;stanford-crypto-aes;73;73;71;73;72;72;78;74;72;75 09:21:02 INFO - PROCESS | 8123 | |11;stanford-crypto-ccm;120;113;113;116;110;109;111;110;126;112 09:21:02 INFO - PROCESS | 8123 | |12;stanford-crypto-pbkdf2;160;160;158;158;158;161;163;160;162;158 09:21:02 INFO - PROCESS | 8123 | |13;stanford-crypto-sha256-iterative;66;67;68;67;69;67;67;67;65;64 09:21:02 INFO - PROCESS | 8123 | __end_tp_report 09:21:02 INFO - PROCESS | 8123 | __start_cc_report 09:21:02 INFO - PROCESS | 8123 | _x_x_mozilla_cycle_collect,1595 09:21:02 INFO - PROCESS | 8123 | __end_cc_report 09:21:02 INFO - PROCESS | 8123 | __startTimestamp1464020462219__endTimestamp 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | ------- Summary: start ------- 09:21:02 INFO - PROCESS | 8123 | Number of tests: 14 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:1.51 (1.4%) stddev-sans-first:1.48 09:21:02 INFO - PROCESS | 8123 | Values: 111.0 110.0 110.0 109.0 110.0 111.0 106.0 109.0 108.0 110.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:159.50 stddev:22.73 (14.2%) stddev-sans-first:24.10 09:21:02 INFO - PROCESS | 8123 | Values: 150.0 127.0 161.0 182.0 126.0 162.0 158.0 129.0 185.0 127.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 09:21:02 INFO - PROCESS | 8123 | Values: 160.0 161.0 161.0 161.0 160.0 159.0 162.0 160.0 161.0 161.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#3] audio-fft Cycles:10 Average:94.80 Median:76.00 stddev:39.67 (52.2%) stddev-sans-first:41.43 09:21:02 INFO - PROCESS | 8123 | Values: 75.0 76.0 77.0 172.0 75.0 73.0 78.0 78.0 168.0 76.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#4] audio-oscillator Cycles:10 Average:110.90 Median:92.50 stddev:29.11 (31.5%) stddev-sans-first:30.06 09:21:02 INFO - PROCESS | 8123 | Values: 92.0 92.0 156.0 93.0 94.0 93.0 154.0 92.0 94.0 149.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 09:21:02 INFO - PROCESS | 8123 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 09:21:02 INFO - PROCESS | 8123 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 09:21:02 INFO - PROCESS | 8123 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:55.00 stddev:6.72 (12.2%) stddev-sans-first:7.12 09:21:02 INFO - PROCESS | 8123 | Values: 57.0 55.0 55.0 55.0 54.0 55.0 58.0 76.0 55.0 53.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:58.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 09:21:02 INFO - PROCESS | 8123 | Values: 58.0 58.0 57.0 58.0 59.0 58.0 58.0 58.0 57.0 57.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:2.00 (2.7%) stddev-sans-first:2.12 09:21:02 INFO - PROCESS | 8123 | Values: 73.0 73.0 71.0 73.0 72.0 72.0 78.0 74.0 72.0 75.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#11] stanford-crypto-ccm Cycles:10 Average:114.00 Median:113.00 stddev:5.33 (4.7%) stddev-sans-first:5.20 09:21:02 INFO - PROCESS | 8123 | Values: 120.0 113.0 113.0 116.0 110.0 109.0 111.0 110.0 126.0 112.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:160.00 stddev:1.81 (1.1%) stddev-sans-first:1.92 09:21:02 INFO - PROCESS | 8123 | Values: 160.0 160.0 158.0 158.0 158.0 161.0 163.0 160.0 162.0 158.0 09:21:02 INFO - PROCESS | 8123 | 09:21:02 INFO - PROCESS | 8123 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.42 (2.1%) stddev-sans-first:1.48 09:21:02 INFO - PROCESS | 8123 | Values: 66.0 67.0 68.0 67.0 69.0 67.0 67.0 67.0 65.0 64.0 09:21:02 INFO - PROCESS | 8123 | -------- Summary: end -------- 09:21:02 INFO - PROCESS | 8123 | 09:21:03 INFO - TEST-INFO | 8123: exit 0 09:21:03 INFO - TEST-OK | kraken | took 61129ms 09:21:03 INFO - SUITE-END | took 1173s 09:21:03 INFO - Completed test suite (00:19:33) 09:21:03 INFO - javascript benchmark 09:21:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.983420082899, 3367.595888493381, 3376.628570857207, 3404.0979140527747, 3415.2486452980334, 5490.972545137273, 5515.310586176728, 5515.972420137899, 5522.199281104243, 5523.502884740373, 5506.091494903336, 5577.832665020046, 5577.8605534861645, 5590.832275031756, 5607.467290607396, 4932.950670493285, 4958.35541379621, 5084.720340381277, 5105.18317069269, 5110.131277682803, 4981.277714731362, 5021.196608542634, 5056.165732654108, 5109.4379618241965, 5123.180291153416, 5017.824376146836, 5095.210242412432, 5138.3577052868395, 5154.74226288686, 5158.226266060084, 5102.668326558762, 5118.846434606968, 5124.461931497213, 5128.1794912813775, 5162.303089082963, 4749.4063242094735, 4907.1657818171, 4966.0, 5013.774380152902, 5022.773975171126, 5309.256704061435, 5318.042752304584, 5323.281357016792, 5328.040952628526, 5330.973345133289, 5695.658260504383, 5738.6556806591325, 5740.54075673945, 5741.942580574183, 5766.654000759968, 5750.281214848144, 5786.797462088806, 5822.679752613604, 5922.822315330547, 5993.0, 5417.160340147283, 5417.891642167174, 5418.729063546807, 5435.0, 5450.373207081197, 5880.852978675482, 5882.470577648032, 5883.17635531026, 5887.116932460165, 5908.113782932594, 5658.151277308354, 5658.462446067617, 5695.060315047971, 5753.223314852525, 5851.21008663825, 5858.179854820329, 5893.734781934823, 5896.6756828374855, 5900.114982752536, 5914.76340946366, 5863.472287494146, 5871.060630299217, 5871.442212989759, 5872.060470324727, 5872.383399742966, 5846.181534585162, 5879.7060146993335, 5881.647101173943, 5985.940140598538, 5998.760049598054, 4484.1031793641405, 4485.0805584855025, 4494.325851122358, 4498.055408364215, 4507.3689683444345, 3980.0447892506168, 4023.0, 4028.4561584186345, 4048.1903619276263, 4058.1072164123843, 5546.084895992197, 5590.357108932443, 5648.209250704905, 5681.375048744635, 5693.17448969897, 5824.79613213544, 5834.533237341087, 5835.241418615553, 5836.591438599257, 5840.153177789197, 5479.123340265538, 5536.169574563767, 5536.169574563848, 5542.778288868481, 5551.944480555224, 4976.178930476503, 4977.203647416396, 5054.671446355975, 5071.721055341918, 5071.847844564669, 5952.613080149777, 5962.075878238793, 5973.850653733604, 5976.372480889444, 5990.251218597676, 5750.856228594319, 5756.539476841759, 5761.452661997104, 5765.711714414347, 5788.971055144697, 6099.603525770811, 6142.846428839315, 6205.751769929152, 6209.96895015522, 6218.657973811484, 5716.399778023248, 5747.597668163188, 5754.7122643866805, 5757.280339957505, 5783.739731712168, 5769.192313076257, 5847.912281315698, 5848.298204215351, 5849.64902105875, 5853.560982926383, 5798.797042103506, 5850.824475265749, 5861.912071318849, 5874.706264686663, 5875.294964604275, 5756.798512052058, 5760.481556659921, 5764.135379692912, 5764.596478246483, 5770.567207459541, 5607.243022191952, 5635.126555383922, 5682.715864206856, 5686.2323586315315, 5690.203371527907, 5822.883542329045, 5865.824025279248, 5868.2077919480325, 5904.675242861512, 5906.114082887601, 5759.712014399347, 5760.567957403127, 5762.164486149569, 5762.884742305214, 5798.072308430631], "value": 5427.581903187187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.8481372063842, 661.5600625583985, 671.4494114825843, 672.7981605518344, 692.2108795972589, 32239.19402014951, 33130.17174570637, 33135.66864331353, 33136.17159571009, 33149.171270718245, 41155.794221028926, 41662.16675666485, 41811.372829407504, 41940.37089443661, 41944.58055419445, 8769.386142969995, 8922.687705930293, 8932.508712020835, 8938.553072346356, 8940.64237430502, 8270.172982701712, 8819.73540793774, 8928.464292142493, 10736.24846260762, 10977.231593788438, 10638.308509946828, 10679.733006674856, 10682.092022178123, 10699.572017119306, 10701.304415213026, 10566.418846963414, 10638.0, 10639.202059845466, 10648.41433721145, 10679.25245232838, 10472.68581942543, 10582.841257381146, 10590.576376944913, 10597.417142057184, 10598.364098154136, 43280.0, 44329.55670443286, 44371.556284437065, 44427.667169984954, 44474.0, 3855.306044911915, 3924.1955399143108, 3939.704522160821, 4017.959820401788, 4336.306191009455, 34040.659593403994, 34504.30991380158, 34613.30773384543, 34758.0, 34841.82579087114, 4432.822687092513, 4490.259107247301, 4542.727436353796, 4549.431321084864, 4663.044075964419, 331899.0, 332079.0, 333394.0, 333623.0, 335148.0, 39343.803280983986, 39391.03044847746, 39620.40569391462, 63110.684446578394, 63615.045774313425, 38148.0, 38257.426138607945, 38342.0, 38427.0, 38431.61568384336, 15965.36138554468, 16082.276297566637, 16082.597935051717, 16091.919540402223, 16092.919535402481, 12429.937850310871, 12610.49558017669, 14322.713545729028, 14384.568462946128, 14427.92786036063, 9285.675001374919, 9388.483633400092, 9402.200812930841, 9458.1487666109, 9492.481203007486, 3541.769784964021, 3574.3005988801983, 3603.189282411489, 3624.220792529566, 3658.0671928658553, 1069.705830896494, 1081.5403453532374, 1151.9287063031293, 1161.1291531351487, 1274.7705413025558, 33652.3269534608, 33742.15644608856, 33902.830485847415, 34274.48588271178, 34314.48528272078, 1257.2393701810456, 1261.4512686981193, 1305.3538498443331, 1322.9140105893086, 1398.175076704749, 17464.912675436542, 17846.375376861746, 17984.820151798576, 18111.72832407515, 18195.727064094048, 484880.0, 489733.0, 491239.0, 491260.0, 492178.0, 60828.69585652043, 62045.0, 62145.378546214866, 62175.0, 62212.68893655503, 1386.209860379574, 1392.7562676531566, 1436.6623843396794, 1522.048719550281, 1600.6238533944513, 25058.37354066163, 32550.83724581362, 32600.184995375304, 34181.0, 35470.0, 59333.0, 60306.09540856803, 61403.0, 61442.69278653578, 61546.69226653838, 426322.0, 428608.0, 430163.0, 430492.0, 431803.0, 39850.40224396694, 46068.30897536473, 46526.53473465222, 49533.75233123956, 50293.74853125711, 49998.50001500084, 50807.74596126996, 51236.231456527436, 54211.186832198335, 54536.72731636475, 39471.65262953389, 41355.0, 44036.559634403246, 45009.32486012778, 45583.0, 43389.783051085804, 43392.78303608462, 43956.0, 44440.263778046115, 44790.3281450785, 45390.31914521351, 45813.0, 46310.073798524514, 46411.0, 46748.0, 37209.813950930074, 39392.0, 39642.0, 39960.0, 40109.1978160441, 30209.395812084076, 30266.54600181043, 30356.24109397283, 30544.0, 30744.23139421443], "value": 20229.706160745543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.3452484960436, 435.25354017859365, 439.76252823475306, 440.84790747192216, 444.86209275124696, 18971.71542426863, 19453.902730486345, 19544.752353479354, 20151.0, 20580.588388232227, 18513.0, 18574.721379179322, 18596.34912778053, 18864.528386790338, 19923.800761992377, 5747.1666608341975, 5807.39022402648, 5990.97004514979, 6002.699865006733, 6004.969975150118, 4283.550227226143, 5948.464638182562, 5971.641701497924, 6290.622562646233, 6606.2733099359275, 6560.343965603449, 6576.473882089422, 6579.34206579343, 6580.407763301302, 6582.605043697394, 6368.10846481493, 6448.387403196689, 6530.8040758777315, 6551.606903585776, 6554.442872355831, 4071.6131967463043, 5695.629784064023, 5804.477597016289, 5822.243108395924, 5945.346011938684, 22644.433889152737, 22691.77308226913, 23017.76982230173, 23074.0, 23080.65379019316, 2524.9495010099877, 2570.5244529761867, 2646.0474229277443, 2990.147807874767, 3310.851011704455, 13822.239776812272, 13997.540959025988, 14401.351939162763, 14504.41982320706, 14668.266586670623, 2067.441790716498, 2099.496120930981, 2107.95784084319, 2128.946776330589, 2147.4738689021133, 78341.60829195875, 80626.0, 81466.59266703628, 82503.58748206281, 86747.56626216891, 11190.496427660612, 11204.327740335606, 11372.14708896836, 11463.656090317303, 11601.535938562363, 15893.602659933364, 16024.919875400548, 16067.035977841368, 16076.0, 16099.436519721754, 8993.23557497607, 9119.0, 9136.954315228515, 9190.678326258549, 9226.0, 8923.0, 9068.637254509746, 9087.227585655293, 9196.540172991323, 9204.263658907272, 7006.544574602634, 7069.0, 7074.787756367317, 7093.929060709327, 7129.750458733919, 1750.3078012768367, 1967.8819270843794, 2003.5892642008353, 2077.896105194734, 2089.6595812689884, 1033.4832583708146, 1051.93688378696, 1131.6209069961656, 1220.713132413882, 1242.5402601037492, 18625.627487450445, 18672.352494707127, 20387.4903127423, 20421.183152674024, 20445.182192712124, 1068.4871261794383, 1088.7985722641179, 1096.6326280695896, 1107.5237647811518, 1189.2270024484155, 10486.580536778596, 11829.822552661717, 11840.585579504848, 11908.404579770977, 11929.821052684216, 527361.0, 546175.0, 558636.0, 560805.0, 561719.0, 85000.57499712585, 87332.5633371829, 90955.0, 90975.0, 91089.54455227681, 1113.3486910157649, 1118.625260537729, 1121.3047910295668, 1129.5989923577176, 1142.8114361130486, 17121.571960701083, 17829.73255401144, 17866.196021178817, 17867.821321786614, 17914.372996945043, 37663.62336376601, 38645.2270954585, 39124.413133802445, 39133.804330978164, 39377.015574610865, 49864.0, 49999.50000500094, 50158.749206253735, 50335.24497132619, 50884.49115508798, 9137.223336016246, 9371.437713737198, 9399.10708482693, 9583.856242156235, 9614.18279446227, 12056.457459414172, 14018.369173387426, 14073.155610663393, 14240.786388204393, 14268.14391136535, 12810.423530941316, 12844.743105137659, 12904.290264035568, 12967.546135885199, 13199.868001319865, 11988.280703157838, 12274.0, 12408.069394795608, 12491.0, 12691.238525688488, 12495.125341226027, 12541.184822986477, 12605.487341519076, 12623.81064284018, 13012.8048079277, 9179.632814687338, 9237.861432078389, 9328.207102396373, 9397.953010234905, 9502.097300756417, 12033.578824741093, 12224.938875305566, 12306.323152226714, 12309.199902006341, 12363.69090772738], "value": 9913.126323063856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.9653008674788, 1469.478335191007, 1507.5100592307504, 1702.4211767998877, 1866.188208129463, 8421.621027053781, 8626.309895208384, 8641.790149379076, 8649.654013839454, 8651.480911145329, 8172.5505097219775, 8316.83366332673, 8367.83264334713, 8374.623141958611, 8381.203785640355, 2012.5069358007308, 2305.1010106058566, 2314.2941402872116, 2335.824813139013, 2360.0559776089617, 2408.036785285882, 2427.320350301918, 2427.599446091393, 2434.306222726523, 2438.231956933569, 2279.623862062758, 2350.5533948549805, 2354.5997180479358, 2354.811615070799, 2357.068957761683, 2007.7590689117253, 2278.054607337953, 2312.8034117099896, 2317.073170731704, 2319.8724070176136, 2275.055851821492, 2307.492351682627, 2315.455867871049, 2320.164740693349, 2321.58211521926, 8717.389782715212, 8734.781630459225, 8747.256483198937, 8776.736697899074, 8789.912100878973, 5213.921791173136, 5325.573954083664, 5341.252224688546, 5348.438413966555, 5443.047466693352, 3279.8032118072992, 3284.868605255787, 3320.020593924798, 3347.581552305962, 3371.174062354739, 1087.9184061195442, 1098.0831006109909, 1104.7845670094334, 1107.1309022417445, 1129.8757136714955, 11995.280283183038, 12024.33866137371, 12133.029357651367, 12149.271043737315, 12155.574554890536, 4525.298578720303, 4600.953990460052, 4626.97686511572, 4716.410448693913, 4763.499832517536, 10292.536835842406, 10336.896631033593, 10361.896381036093, 10372.325798823053, 10372.377657340432, 4839.7096174229655, 5209.322788037531, 5252.789888404497, 5258.39528454225, 5262.578993680497, 4758.571728544378, 4827.0, 4862.489438608965, 4864.221724524059, 4882.609391248691, 4060.0, 4088.1619268049976, 4138.982139121558, 4169.583041695867, 4207.26372884744, 1335.7729186038396, 1357.172125003747, 1365.5766712319212, 1366.5353779714944, 1368.5620601407454, 999.430893497217, 1004.9096730047834, 1006.2402885821158, 1009.3287963504334, 1023.0, 5973.0, 6077.544184186204, 6206.162168107338, 6274.403931626488, 6291.339409362043, 432.7490055767647, 435.5753140687804, 437.61927123402205, 439.8218721417831, 446.3037661248463, 4087.3868919662286, 4120.381942708617, 4131.111810960658, 4132.111596006873, 4153.501579810442, 10403.47982600867, 10631.681049568526, 10861.0, 10868.239223254455, 10870.565177392973, 10612.734681633021, 10650.201234907412, 10651.520681569347, 10655.30740501865, 10662.360258384522, 8144.981877265342, 8174.223448772357, 8183.672653093929, 8198.057223419264, 8198.918010819816, 5571.749271282721, 5632.971835140962, 5650.85872853182, 5667.8866422672145, 5804.303483581997, 9268.165865071906, 9350.579223935076, 9515.476648784383, 9531.714048578553, 9667.46828924416, 9457.19613832832, 9524.285678573997, 9663.9033609663, 9684.225261979165, 9729.416235025921, 6808.693608787517, 7509.7371591994015, 7621.580813055335, 7784.338331241908, 7897.881531776913, 8406.579671016547, 8409.747707568782, 8438.873416898874, 8449.95775021121, 8455.788605284917, 7492.812679683051, 7733.961330193313, 7757.844843102994, 7780.766577002699, 7876.803079923047, 7587.658555364911, 7615.581143037186, 7712.035995500562, 7753.1859154787935, 7798.610069496616, 7472.738454154079, 7488.400927925644, 7513.699452021858, 7529.510581812165, 7531.284527969834, 5130.12787826078, 5340.279062326692, 5424.321959755031, 5465.72671366438, 5469.644473109235, 5350.357957045179, 5399.541039011728, 5513.421090785409, 5558.277423934863, 5591.049521581423], "value": 4547.84253855894, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.8520506592297, 364.8941806876005, 365.641671162261, 367.4671725997305, 375.0155840917593, 6217.906731399027, 6252.187215661963, 6288.18253627029, 6292.182016337881, 6338.968305158468, 6212.65830379329, 6229.37706229378, 6231.50147988161, 6235.251769787632, 6241.937580624204, 4554.954450455502, 4700.576948074672, 4817.181079216524, 4819.228923372261, 4825.589824864891, 4768.809247630108, 4801.111794318045, 4812.181929072049, 4813.711177329372, 4819.156647586676, 4548.545145485458, 4746.857594272177, 4782.569568738812, 4783.425988881322, 4786.784594693229, 4611.608013318872, 4680.5085466026085, 4684.531546845323, 4769.642276829251, 4776.402949631296, 4171.645410140141, 4182.707210495236, 4195.685323600742, 4197.097624010852, 4197.328427451593, 5986.73059712314, 5990.7903223386975, 5991.610545314541, 6002.189704389895, 6009.75960961561, 5547.25112109864, 5560.499555040065, 5560.666360018371, 5561.63849349791, 5563.972180139114, 6464.41820236181, 6574.276829548746, 6588.439982601484, 6589.4069533742195, 6600.60396376219, 4689.460712018127, 4696.577308042292, 4697.295405689139, 4701.929471057937, 4704.858854234379, 6836.0, 6858.142732158481, 6866.073080134168, 6868.175818901764, 6876.415504682108, 4768.928466073012, 4772.093302272557, 4778.259369797687, 4786.401699787527, 4791.6406269529925, 4847.830325938646, 4848.321235027099, 4851.611871050308, 4852.51474852519, 4855.635827312965, 5538.584606154554, 5544.972275138599, 5558.360788509292, 5563.749631266603, 5566.332040155126, 6467.51493637979, 6617.86764264712, 6618.139641846626, 6618.801435956929, 6627.5029372797235, 1678.8572971297333, 1685.4185306069387, 1697.3041053168388, 1698.9660206795795, 1700.8299170082892, 1091.0889407344955, 1107.9778404432027, 1142.1433924556582, 1142.520141540555, 1165.854268216473, 6292.1505596743855, 6297.874042519215, 6314.778982735582, 6315.052742088632, 6316.11574379578, 6901.689423975933, 6905.861882762417, 6914.135733033371, 6922.0, 6928.688209030605, 6484.351564843478, 6489.545731798728, 6493.675316234169, 6501.609903405811, 6506.34936506355, 3664.0, 3703.9629603704157, 3706.592274849764, 3707.5550933887566, 3709.3137769512728, 7049.436045116378, 7115.679794409264, 7167.462440316997, 7179.712811487587, 7179.964100179465, 7011.544249623758, 7024.262452442522, 7025.437964962791, 7026.859462810818, 7044.401225895754, 7158.677859496335, 7162.314132200425, 7168.566286742568, 7171.462140339496, 7187.209406965229, 6869.793906182623, 6877.518573699793, 6878.828029299308, 6881.759138430131, 6886.173659160932, 6570.375814297634, 6769.1876974763345, 6818.284080171709, 6818.386345228953, 6835.14560679915, 7176.641167941686, 7178.928210717826, 7181.425485961215, 7188.784336469915, 7194.928050719635, 4693.272542755878, 4730.101280756645, 4738.597219236266, 4738.981119059419, 4739.952600473951, 6899.102746744686, 6940.063091482585, 6970.302969702989, 6971.616561088986, 6981.0924579804305, 6473.805785826433, 6693.665316734046, 6723.5293529452465, 6725.293844146294, 6752.729890804313, 6699.598024118569, 6706.765263215764, 6708.42978346846, 6760.188777346553, 6776.28848970851, 6544.83637909037, 6551.4103730664465, 6560.967195163994, 6564.409203171737, 6575.539712220098, 6695.765648202289, 6704.664766761545, 6706.5640733352175, 6709.7316107355155, 6711.261761206166, 6431.5819471734185, 6545.5090868184125, 6553.311902250386, 6571.835704107436, 6581.3747693969], "value": 5034.8181378717445, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.8836762853198, 839.8782176584396, 851.7742798158486, 919.5402298850574, 931.70185540627, 14154.929225353872, 14407.0, 16861.241244144014, 17360.652786944254, 17456.825431745678, 15457.84542154578, 15603.531894043168, 15608.531744047697, 15612.453564125259, 15703.84296157041, 750.969961201554, 919.5356345045776, 921.345844450441, 931.6646007437317, 938.9342746007782, 924.0759240759207, 925.4447331601053, 926.2266007883426, 930.1489137439235, 931.9254459643246, 972.1396564040804, 975.9853602195973, 976.5312649928039, 976.6191185437649, 976.9706908792712, 787.4330482052912, 954.0745476887442, 971.7522031881861, 973.5862258540092, 974.6101559376306, 705.2806137739477, 712.0671919785098, 713.1869668577825, 713.2582114600809, 713.2795876165059, 15379.307931143125, 15415.460458883885, 15423.614409639737, 15447.61380965485, 15482.61293467661, 14170.07894486855, 14221.360038798175, 14244.21656808875, 14572.562823115324, 14665.266736663125, 12007.879921200763, 12294.508219671203, 12296.569620063256, 12511.624651260478, 12631.0, 4741.407324084489, 4755.405574303212, 4762.214234651313, 4789.712617242976, 4825.445073816521, 53754.73122634401, 53984.73007634976, 54010.459895400934, 54025.0, 54419.0, 11189.216754827085, 11526.711832204262, 11594.906123742248, 11817.527298908119, 11907.583234586747, 20587.279445219647, 20603.58792824135, 20789.272375466786, 20797.480062998548, 20818.375448736562, 8440.873386899202, 8539.06070332263, 8573.56832592743, 8687.565621718888, 8792.42849214799, 4992.98514442645, 5068.746562671926, 5096.05722941257, 5138.511841375063, 5207.244949482304, 3509.1402606361603, 3559.964400356015, 3561.0, 3562.4834399012516, 3566.28674265148, 631.1195881745032, 639.5874660843823, 641.1344684675651, 650.443870490733, 661.867626474707, 772.3628006894222, 784.7763387434554, 791.1337085891055, 804.5092493578912, 815.5962798414822, 9291.117343852324, 9440.339176257596, 9536.952315238519, 9645.662401816044, 9674.177694896012, 684.2165720250285, 722.6459035072777, 761.1873730115489, 763.7326935572617, 815.9551224682699, 4297.8495752648505, 4357.607815296638, 4361.345798130305, 4369.213541562485, 4487.551244875552, 64081.679591602675, 64903.350966490674, 65257.67371163209, 65706.67146664236, 67891.0, 56650.716746416, 58574.0, 58728.41271587315, 58946.70526647339, 59223.70388148118, 50718.746406268474, 50977.23534146991, 50989.745051274505, 51315.0, 52585.0, 8466.74599762008, 8643.438176518506, 8725.363722848328, 8892.599833007402, 8971.865422018805, 38407.23185536218, 38608.22783544369, 38991.415128772525, 40802.183956320114, 41135.7943210282, 49030.5096949026, 51732.0, 51824.0, 55383.446165537825, 55454.72272638611, 13216.537421190596, 13233.86766132365, 13265.0, 13399.196048237138, 13473.865261347653, 14309.64225894361, 14409.0, 14485.348159332645, 15103.093814371174, 15228.847711522743, 13687.452501899812, 13910.165390076629, 14065.296735163405, 14236.35936382886, 14318.14091154534, 13414.195148290743, 13698.931505342409, 13699.011555060622, 13741.587752367444, 14212.365577958668, 12383.0, 12605.604142407868, 12726.809097863354, 13124.934375328063, 13590.93204533971, 10965.451727413756, 11177.0, 11289.435528223721, 11441.42792860337, 11704.94147529257, 11016.0, 11049.779004420026, 11081.612143574936, 11215.60745373908, 11232.157588180755], "value": 7282.257206786258, "unit": "score"}], "name": "dromaeo_css", "value": 7524.604874058978, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 110.0, 109.0, 110.0, 111.0, 106.0, 109.0, 108.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 127.0, 161.0, 182.0, 126.0, 162.0, 158.0, 129.0, 185.0, 127.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 160.0, 159.0, 162.0, 160.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 172.0, 75.0, 73.0, 78.0, 78.0, 168.0, 76.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 156.0, 93.0, 94.0, 93.0, 154.0, 92.0, 94.0, 149.0], "value": 110.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 55.0, 54.0, 55.0, 58.0, 76.0, 55.0, 53.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 58.0, 59.0, 58.0, 58.0, 58.0, 57.0, 57.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 71.0, 73.0, 72.0, 72.0, 78.0, 74.0, 72.0, 75.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 113.0, 113.0, 116.0, 110.0, 109.0, 111.0, 110.0, 126.0, 112.0], "value": 114.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 158.0, 158.0, 158.0, 161.0, 163.0, 160.0, 162.0, 158.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 69.0, 67.0, 67.0, 67.0, 65.0, 64.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1512.3999999999999, "alertThreshold": 2.0}]} 09:21:03 INFO - Return code: 0 09:21:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:21:03 ERROR - # TBPL SUCCESS # 09:21:03 INFO - Running post-action listener: _resource_record_post_action 09:21:03 INFO - Running post-run listener: _resource_record_post_run 09:21:04 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 496644096; Read time: 12; Write time: 1334536 09:21:04 INFO - install - Wall time: 16s; CPU: 21.0%; Read bytes: 0; Write bytes: 249524224; Read time: 0; Write time: 1034824 09:21:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 247119872; Read time: 12; Write time: 299712 09:21:04 INFO - Running post-run listener: _upload_blobber_files 09:21:04 INFO - Blob upload gear active. 09:21:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:21:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:21:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:21:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:21:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:06 INFO - (blobuploader) - INFO - Done attempting. 09:21:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:21:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:21:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:06 INFO - (blobuploader) - INFO - Done attempting. 09:21:06 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:06 INFO - Return code: 0 09:21:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32be7a2b5aab06602117bd5dd03b24a1bf8aa2b7580b906ea97c6196dca341aff270e3c6eb6cbbce09a9d0296aa9b78c8d4b4f370242d8194caf891be69ba7be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92ce6d0eda55c673cd32a35a43888ed65d43a1596b79b821cc3af2e76d787bcc7af1da1a889761ab360347d46d5366209d752e7bc12622abcc89c6a3e08c97bb"} 09:21:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:06 INFO - Contents: 09:21:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32be7a2b5aab06602117bd5dd03b24a1bf8aa2b7580b906ea97c6196dca341aff270e3c6eb6cbbce09a9d0296aa9b78c8d4b4f370242d8194caf891be69ba7be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92ce6d0eda55c673cd32a35a43888ed65d43a1596b79b821cc3af2e76d787bcc7af1da1a889761ab360347d46d5366209d752e7bc12622abcc89c6a3e08c97bb"} 09:21:06 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:06 INFO - Copying logs to upload dir... 09:21:06 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.963728 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-23 09:21:06.557144) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 09:21:06.561123) ========= 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=df17445c3590ff7897617a0e00000225-1464018583.524884-845511080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32be7a2b5aab06602117bd5dd03b24a1bf8aa2b7580b906ea97c6196dca341aff270e3c6eb6cbbce09a9d0296aa9b78c8d4b4f370242d8194caf891be69ba7be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92ce6d0eda55c673cd32a35a43888ed65d43a1596b79b821cc3af2e76d787bcc7af1da1a889761ab360347d46d5366209d752e7bc12622abcc89c6a3e08c97bb"} build_url:https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011449 build_url: 'https://queue.taskcluster.net/v1/task/JEGWcK-xQt2K-cj6Ur8Uxg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32be7a2b5aab06602117bd5dd03b24a1bf8aa2b7580b906ea97c6196dca341aff270e3c6eb6cbbce09a9d0296aa9b78c8d4b4f370242d8194caf891be69ba7be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92ce6d0eda55c673cd32a35a43888ed65d43a1596b79b821cc3af2e76d787bcc7af1da1a889761ab360347d46d5366209d752e7bc12622abcc89c6a3e08c97bb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 09:21:06.587409) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:21:06.587728) ========= 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=df17445c3590ff7897617a0e00000225-1464018583.524884-845511080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004373 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 09:21:06.671220) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 09:21:06.671605) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 09:21:06.671914) ========= ========= Total master_lag: 0.17 =========