builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1462533144.7 results: success (0) buildid: 20160506013623 builduid: 3e330e94e72a44aabac68afc380e9ed1 revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.696349) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.696865) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.697153) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.725321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.725595) ========= 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=1861a34ebfea852630dec091000001fa-1462533029.343642-541288447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-06 04:12:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-05-06 04:12:27 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110419 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.853138) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.853445) ========= 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=1861a34ebfea852630dec091000001fa-1462533029.343642-541288447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016203 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:24.903293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 04:12:24.903663) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f --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 b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f --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=1861a34ebfea852630dec091000001fa-1462533029.343642-541288447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-06 04:12:27,377 truncating revision to first 12 chars 2016-05-06 04:12:27,377 Setting DEBUG logging. 2016-05-06 04:12:27,377 attempt 1/10 2016-05-06 04:12:27,377 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b61ad3a6049c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 04:12:28,457 unpacking tar archive at: mozilla-aurora-b61ad3a6049c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370877 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 04:12:26.287309) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:26.287613) ========= script_repo_revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:26.288005) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:26.288267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:12:26.296480) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-06 04:12:26.296890) ========= /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=1861a34ebfea852630dec091000001fa-1462533029.343642-541288447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:12:28 INFO - MultiFileLogger online at 20160506 04:12:28 in /builds/slave/test 04:12:28 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 04:12:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:12:28 INFO - {'append_to_log': False, 04:12:28 INFO - 'base_work_dir': '/builds/slave/test', 04:12:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:12:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:12:28 INFO - 'branch': 'Mozilla-Aurora', 04:12:28 INFO - 'buildbot_json_path': 'buildprops.json', 04:12:28 INFO - 'config_files': ('talos/linux_config.py',), 04:12:28 INFO - 'default_actions': ('clobber', 04:12:28 INFO - 'read-buildbot-config', 04:12:28 INFO - 'download-and-extract', 04:12:28 INFO - 'populate-webroot', 04:12:28 INFO - 'create-virtualenv', 04:12:28 INFO - 'install', 04:12:28 INFO - 'run-tests'), 04:12:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:12:28 INFO - 'download_minidump_stackwalk': True, 04:12:28 INFO - 'download_symbols': 'ondemand', 04:12:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:12:28 INFO - 'tooltool.py': '/tools/tooltool.py', 04:12:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:12:28 INFO - '/tools/misc-python/virtualenv.py')}, 04:12:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:12:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:12:28 INFO - 'installer_path': 'installer.exe', 04:12:28 INFO - 'log_level': 'info', 04:12:28 INFO - 'log_name': 'talos', 04:12:28 INFO - 'log_to_console': True, 04:12:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:12:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:12:28 INFO - 'opt_config_files': (), 04:12:28 INFO - 'pip_index': False, 04:12:28 INFO - 'sps_profile': False, 04:12:28 INFO - 'sps_profile_interval': 0, 04:12:28 INFO - 'suite': 'dromaeojs', 04:12:28 INFO - 'system_bits': '32', 04:12:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:12:28 INFO - 'title': 'talos-linux64-ix-069', 04:12:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:12:28 INFO - 'use_talos_json': True, 04:12:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:12:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:12:28 INFO - 'work_dir': 'build'} 04:12:28 INFO - ##### 04:12:28 INFO - ##### Running clobber step. 04:12:28 INFO - ##### 04:12:28 INFO - Running pre-action listener: _resource_record_pre_action 04:12:28 INFO - Running main action method: clobber 04:12:28 INFO - rmtree: /builds/slave/test/build 04:12:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:12:29 INFO - Running post-action listener: _resource_record_post_action 04:12:29 INFO - ##### 04:12:29 INFO - ##### Running read-buildbot-config step. 04:12:29 INFO - ##### 04:12:29 INFO - Running pre-action listener: _resource_record_pre_action 04:12:29 INFO - Running main action method: read_buildbot_config 04:12:29 INFO - Using buildbot properties: 04:12:29 INFO - { 04:12:29 INFO - "project": "", 04:12:29 INFO - "product": "firefox", 04:12:29 INFO - "script_repo_revision": "production", 04:12:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:12:29 INFO - "repository": "", 04:12:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:12:29 INFO - "buildid": "20160506013623", 04:12:29 INFO - "pgo_build": "True", 04:12:29 INFO - "basedir": "/builds/slave/test", 04:12:29 INFO - "buildnumber": 489, 04:12:29 INFO - "slavename": "talos-linux64-ix-069", 04:12:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:12:29 INFO - "platform": "ubuntu64_hw", 04:12:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:12:29 INFO - "branch": "mozilla-aurora", 04:12:29 INFO - "repo_path": "releases/mozilla-aurora", 04:12:29 INFO - "revision": "b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f", 04:12:29 INFO - "stage_platform": "linux64-pgo", 04:12:29 INFO - "builduid": "3e330e94e72a44aabac68afc380e9ed1", 04:12:29 INFO - "slavebuilddir": "test" 04:12:29 INFO - } 04:12:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 04:12:29 INFO - Running post-action listener: _resource_record_post_action 04:12:29 INFO - ##### 04:12:29 INFO - ##### Running download-and-extract step. 04:12:29 INFO - ##### 04:12:29 INFO - Running pre-action listener: _resource_record_pre_action 04:12:29 INFO - Running main action method: download_and_extract 04:12:29 INFO - mkdir: /builds/slave/test/build/tests 04:12:29 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')]} 04:12:29 INFO - https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:12:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 04:12:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 04:12:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:31 INFO - Downloaded 1361 bytes. 04:12:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 04:12:31 INFO - Using the following test package requirements: 04:12:31 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 04:12:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:12:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:12:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'jsshell-linux-x86_64.zip'], 04:12:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:12:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 04:12:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:12:31 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:12:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:12:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 04:12:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:12:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:12: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')]} 04:12:31 INFO - https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:12:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 04:12:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 04:12:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:33 INFO - Downloaded 23799377 bytes. 04:12: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'] 04:12: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 04:12:34 INFO - Return code: 0 04:12:34 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 04:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:34 INFO - https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:12:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 04:12:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 04:12:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:38 INFO - Downloaded 23799377 bytes. 04:12:38 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'] 04:12:38 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 04:12:39 INFO - Return code: 0 04:12:39 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')]} 04:12:39 INFO - https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:12:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 04:12:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 04:12:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:12:39 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 04:12:39 INFO - Running command: ['nslookup', u'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 04:12:39 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 04:12:39 INFO - Server: 10.26.75.40 04:12:39 INFO - Address: 10.26.75.40#53 04:12:39 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 04:12:39 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 04:12:39 INFO - Address: 10.26.48.46 04:12:39 INFO - Return code: 0 04:12:39 INFO - retry: attempt #1 caught exception: 04:12:39 INFO - retry: Failed, sleeping 30 seconds before retrying 04:13:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 #2 04:13:11 INFO - Downloaded 10840145 bytes. 04:13:11 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'] 04:13:11 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 04:13:11 INFO - Return code: 0 04:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:11 INFO - https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:13:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 04:13:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 04:13:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:13:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZULdrJzSvuOvylse2l50g/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 04:13:12 INFO - Downloaded 62706998 bytes. 04:13:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 04:13:12 INFO - mkdir: /builds/slave/test/properties 04:13:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:12 INFO - Contents: 04:13:12 INFO - build_url:https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 04:13:13 INFO - Running post-action listener: _resource_record_post_action 04:13:13 INFO - Running post-action listener: set_extra_try_arguments 04:13:13 INFO - ##### 04:13:13 INFO - ##### Running populate-webroot step. 04:13:13 INFO - ##### 04:13:13 INFO - Running pre-action listener: _resource_record_pre_action 04:13:13 INFO - Running main action method: populate_webroot 04:13:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:13 INFO - '--tppagecycles', 04:13:13 INFO - '7'], 04:13:13 INFO - 'tests': ['tsvgm']}}, 04:13:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['damp', 'tps']}, 04:13:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'tests': ['damp', 'tps']}, 04:13:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['dromaeo_dom']}, 04:13:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['a11yr', 04:13:13 INFO - 'ts_paint', 04:13:13 INFO - 'tpaint', 04:13:13 INFO - 'sessionrestore', 04:13:13 INFO - 'sessionrestore_no_auto_restore', 04:13:13 INFO - 'tabpaint']}, 04:13:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:13 INFO - 'ts_paint', 04:13:13 INFO - 'tpaint', 04:13:13 INFO - 'sessionrestore', 04:13:13 INFO - 'sessionrestore_no_auto_restore', 04:13:13 INFO - 'tabpaint']}, 04:13:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:13 INFO - 'ts_paint', 04:13:13 INFO - 'tpaint', 04:13:13 INFO - 'sessionrestore', 04:13:13 INFO - 'sessionrestore_no_auto_restore', 04:13:13 INFO - 'tabpaint']}, 04:13:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:13 INFO - 'ts_paint', 04:13:13 INFO - 'tpaint', 04:13:13 INFO - 'sessionrestore', 04:13:13 INFO - 'sessionrestore_no_auto_restore', 04:13:13 INFO - 'tabpaint']}, 04:13:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['a11yr', 04:13:13 INFO - 'ts_paint', 04:13:13 INFO - 'tpaint', 04:13:13 INFO - 'sessionrestore', 04:13:13 INFO - 'sessionrestore_no_auto_restore', 04:13:13 INFO - 'tabpaint']}, 04:13:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['a11yr', 04:13:13 INFO - 'ts_paint', 04:13:13 INFO - 'tpaint', 04:13:13 INFO - 'sessionrestore', 04:13:13 INFO - 'sessionrestore_no_auto_restore', 04:13:13 INFO - 'tabpaint']}, 04:13:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['tsvgx', 04:13:13 INFO - 'tsvgr_opacity', 04:13:13 INFO - 'tart', 04:13:13 INFO - 'tscrollx', 04:13:13 INFO - 'cart']}, 04:13:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:13 INFO - 'tsvgr_opacity', 04:13:13 INFO - 'tart', 04:13:13 INFO - 'tscrollx', 04:13:13 INFO - 'cart']}, 04:13:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'talos_options': ['--disable-e10s'], 04:13:13 INFO - 'tests': ['tp5o']}, 04:13:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'tests': ['tp5o']}, 04:13:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'talos_options': ['--disable-e10s', 04:13:13 INFO - '--xperf_path', 04:13:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:13 INFO - 'tests': ['tp5n']}, 04:13:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:13 INFO - 'talos_options': ['--xperf_path', 04:13:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:13 INFO - 'tests': ['tp5n']}}, 04:13:13 INFO - 'talos.zip': {'path': '', 04:13:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:13 INFO - Running post-action listener: _resource_record_post_action 04:13:13 INFO - ##### 04:13:13 INFO - ##### Running create-virtualenv step. 04:13:13 INFO - ##### 04:13:13 INFO - Running pre-action listener: _resource_record_pre_action 04:13:13 INFO - Running main action method: create_virtualenv 04:13:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:13 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 04:13:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:13 INFO - Using real prefix '/usr' 04:13:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:16 INFO - Installing pip.................done. 04:13:16 INFO - Return code: 0 04:13:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:16 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')]} 04:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:16 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')]} 04:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:16 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:16 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 04:13:16 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 04:13:16 INFO - Using env: {'DISPLAY': ':0', 04:13:16 INFO - 'HOME': '/home/cltbld', 04:13:16 INFO - 'LANG': 'en_US.UTF-8', 04:13:16 INFO - 'LANGUAGE': 'en_US:en', 04:13:16 INFO - 'LOGNAME': 'cltbld', 04:13:16 INFO - 'MAIL': '/var/mail/cltbld', 04:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:16 INFO - 'MOZ_NO_REMOTE': '1', 04:13:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:16 INFO - 'NO_EM_RESTART': '1', 04:13:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:16 INFO - 'PWD': '/builds/slave/test', 04:13:16 INFO - 'SHELL': '/bin/bash', 04:13:16 INFO - 'SHLVL': '1', 04:13:16 INFO - 'TERM': 'linux', 04:13:16 INFO - 'TMOUT': '86400', 04:13:16 INFO - 'USER': 'cltbld', 04:13:16 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:16 INFO - '_': '/tools/buildbot/bin/python'} 04:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:16 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 04:13:16 INFO - Cleaning up... 04:13:16 INFO - Return code: 0 04:13:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:13:16 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')]} 04:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:16 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')]} 04:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:16 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:16 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 04:13:16 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 04:13:16 INFO - Using env: {'DISPLAY': ':0', 04:13:16 INFO - 'HOME': '/home/cltbld', 04:13:16 INFO - 'LANG': 'en_US.UTF-8', 04:13:16 INFO - 'LANGUAGE': 'en_US:en', 04:13:16 INFO - 'LOGNAME': 'cltbld', 04:13:16 INFO - 'MAIL': '/var/mail/cltbld', 04:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:16 INFO - 'MOZ_NO_REMOTE': '1', 04:13:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:16 INFO - 'NO_EM_RESTART': '1', 04:13:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:16 INFO - 'PWD': '/builds/slave/test', 04:13:16 INFO - 'SHELL': '/bin/bash', 04:13:16 INFO - 'SHLVL': '1', 04:13:16 INFO - 'TERM': 'linux', 04:13:16 INFO - 'TMOUT': '86400', 04:13:16 INFO - 'USER': 'cltbld', 04:13:16 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:16 INFO - '_': '/tools/buildbot/bin/python'} 04:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:16 INFO - Downloading/unpacking psutil>=0.7.1 04:13:16 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 04:13:16 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 04:13:16 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 04:13:16 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 04:13:16 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 04:13:16 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 04:13:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:18 INFO - Installing collected packages: psutil 04:13:18 INFO - Running setup.py install for psutil 04:13:18 INFO - building 'psutil._psutil_linux' extension 04:13:18 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 04:13:18 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 04:13:18 INFO - building 'psutil._psutil_posix' extension 04:13:18 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 04:13:19 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 04:13:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:19 INFO - Successfully installed psutil 04:13:19 INFO - Cleaning up... 04:13:19 INFO - Return code: 0 04:13:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:19 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 04:13:19 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 04:13:19 INFO - Using env: {'DISPLAY': ':0', 04:13:19 INFO - 'HOME': '/home/cltbld', 04:13:19 INFO - 'LANG': 'en_US.UTF-8', 04:13:19 INFO - 'LANGUAGE': 'en_US:en', 04:13:19 INFO - 'LOGNAME': 'cltbld', 04:13:19 INFO - 'MAIL': '/var/mail/cltbld', 04:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:19 INFO - 'MOZ_NO_REMOTE': '1', 04:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:19 INFO - 'NO_EM_RESTART': '1', 04:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:19 INFO - 'PWD': '/builds/slave/test', 04:13:19 INFO - 'SHELL': '/bin/bash', 04:13:19 INFO - 'SHLVL': '1', 04:13:19 INFO - 'TERM': 'linux', 04:13:19 INFO - 'TMOUT': '86400', 04:13:19 INFO - 'USER': 'cltbld', 04:13:19 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:19 INFO - '_': '/tools/buildbot/bin/python'} 04:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:13:21 INFO - Installing collected packages: mozsystemmonitor 04:13:21 INFO - Running setup.py install for mozsystemmonitor 04:13:21 INFO - Successfully installed mozsystemmonitor 04:13:21 INFO - Cleaning up... 04:13:21 INFO - Return code: 0 04:13:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:21 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 04:13:21 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 04:13:21 INFO - Using env: {'DISPLAY': ':0', 04:13:21 INFO - 'HOME': '/home/cltbld', 04:13:21 INFO - 'LANG': 'en_US.UTF-8', 04:13:21 INFO - 'LANGUAGE': 'en_US:en', 04:13:21 INFO - 'LOGNAME': 'cltbld', 04:13:21 INFO - 'MAIL': '/var/mail/cltbld', 04:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:21 INFO - 'MOZ_NO_REMOTE': '1', 04:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:21 INFO - 'NO_EM_RESTART': '1', 04:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:21 INFO - 'PWD': '/builds/slave/test', 04:13:21 INFO - 'SHELL': '/bin/bash', 04:13:21 INFO - 'SHLVL': '1', 04:13:21 INFO - 'TERM': 'linux', 04:13:21 INFO - 'TMOUT': '86400', 04:13:21 INFO - 'USER': 'cltbld', 04:13:21 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:21 INFO - '_': '/tools/buildbot/bin/python'} 04:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:21 INFO - Downloading/unpacking blobuploader==1.2.4 04:13:21 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 04:13:21 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 04:13:21 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 04:13:21 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 04:13:21 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 04:13:21 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 04:13:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:23 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 04:13:23 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 04:13:23 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 04:13:23 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 04:13:23 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 04:13:23 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 04:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 INFO - Downloading docopt-0.6.1.tar.gz 04:13:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:24 INFO - Running setup.py install for blobuploader 04:13:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:24 INFO - Running setup.py install for requests 04:13:24 INFO - Running setup.py install for docopt 04:13:24 INFO - Successfully installed blobuploader requests docopt 04:13:24 INFO - Cleaning up... 04:13:24 INFO - Return code: 0 04:13:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:24 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 04:13:24 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 04:13:24 INFO - Using env: {'DISPLAY': ':0', 04:13:24 INFO - 'HOME': '/home/cltbld', 04:13:24 INFO - 'LANG': 'en_US.UTF-8', 04:13:24 INFO - 'LANGUAGE': 'en_US:en', 04:13:24 INFO - 'LOGNAME': 'cltbld', 04:13:24 INFO - 'MAIL': '/var/mail/cltbld', 04:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:24 INFO - 'MOZ_NO_REMOTE': '1', 04:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:24 INFO - 'NO_EM_RESTART': '1', 04:13:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:24 INFO - 'PWD': '/builds/slave/test', 04:13:24 INFO - 'SHELL': '/bin/bash', 04:13:24 INFO - 'SHLVL': '1', 04:13:24 INFO - 'TERM': 'linux', 04:13:24 INFO - 'TMOUT': '86400', 04:13:24 INFO - 'USER': 'cltbld', 04:13:24 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:24 INFO - '_': '/tools/buildbot/bin/python'} 04:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:25 INFO - Running setup.py (path:/tmp/pip-7qjCDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:25 INFO - Running setup.py (path:/tmp/pip-rToaIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:25 INFO - Running setup.py (path:/tmp/pip-XeIQLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:25 INFO - Running setup.py (path:/tmp/pip-nY8woc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:25 INFO - Running setup.py (path:/tmp/pip-RCBSzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:25 INFO - Running setup.py (path:/tmp/pip-lmccvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:25 INFO - Running setup.py (path:/tmp/pip-nqJgI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:25 INFO - Running setup.py (path:/tmp/pip-WD_xBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:25 INFO - Running setup.py (path:/tmp/pip-aGQjmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:25 INFO - Running setup.py (path:/tmp/pip-TG8jGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:25 INFO - Running setup.py (path:/tmp/pip-y1Pi6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:25 INFO - Running setup.py (path:/tmp/pip-ImqPQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:26 INFO - Running setup.py (path:/tmp/pip-ulZ2KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:26 INFO - Running setup.py (path:/tmp/pip-_yMq00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:26 INFO - Running setup.py (path:/tmp/pip-KtidjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:26 INFO - Running setup.py (path:/tmp/pip-cJ9DA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:26 INFO - Running setup.py (path:/tmp/pip-jsOqkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:26 INFO - Running setup.py install for manifestparser 04:13:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:26 INFO - Running setup.py install for mozcrash 04:13:26 INFO - Running setup.py install for mozdebug 04:13:26 INFO - Running setup.py install for mozdevice 04:13:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:26 INFO - Running setup.py install for mozfile 04:13:26 INFO - Running setup.py install for mozhttpd 04:13:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for mozinfo 04:13:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for mozInstall 04:13:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for mozleak 04:13:27 INFO - Running setup.py install for mozlog 04:13:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for moznetwork 04:13:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for mozprocess 04:13:27 INFO - Running setup.py install for mozprofile 04:13:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for mozrunner 04:13:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Running setup.py install for mozscreenshot 04:13:27 INFO - Running setup.py install for moztest 04:13:28 INFO - Running setup.py install for mozversion 04:13:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:28 INFO - Cleaning up... 04:13:28 INFO - Return code: 0 04:13:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:28 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:28 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 04:13:28 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 04:13:28 INFO - Using env: {'DISPLAY': ':0', 04:13:28 INFO - 'HOME': '/home/cltbld', 04:13:28 INFO - 'LANG': 'en_US.UTF-8', 04:13:28 INFO - 'LANGUAGE': 'en_US:en', 04:13:28 INFO - 'LOGNAME': 'cltbld', 04:13:28 INFO - 'MAIL': '/var/mail/cltbld', 04:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:28 INFO - 'MOZ_NO_REMOTE': '1', 04:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:28 INFO - 'NO_EM_RESTART': '1', 04:13:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:28 INFO - 'PWD': '/builds/slave/test', 04:13:28 INFO - 'SHELL': '/bin/bash', 04:13:28 INFO - 'SHLVL': '1', 04:13:28 INFO - 'TERM': 'linux', 04:13:28 INFO - 'TMOUT': '86400', 04:13:28 INFO - 'USER': 'cltbld', 04:13:28 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:28 INFO - '_': '/tools/buildbot/bin/python'} 04:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:28 INFO - Running setup.py (path:/tmp/pip-7QwASK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:28 INFO - Running setup.py (path:/tmp/pip-LtHo5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:28 INFO - Running setup.py (path:/tmp/pip-9fhcEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:28 INFO - Running setup.py (path:/tmp/pip-V_W9EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:28 INFO - Running setup.py (path:/tmp/pip-1gbAvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:28 INFO - Running setup.py (path:/tmp/pip-K2kotW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:28 INFO - Running setup.py (path:/tmp/pip-LaTwuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:28 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)) 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:28 INFO - Running setup.py (path:/tmp/pip-HfixRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:29 INFO - Running setup.py (path:/tmp/pip-AiCprO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:29 INFO - Running setup.py (path:/tmp/pip-7QuUKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:29 INFO - Running setup.py (path:/tmp/pip-eaDpuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:29 INFO - Running setup.py (path:/tmp/pip-qgTynO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:29 INFO - Running setup.py (path:/tmp/pip-GXaRBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:29 INFO - Running setup.py (path:/tmp/pip-wxKEs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:29 INFO - Running setup.py (path:/tmp/pip-5dRwgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:29 INFO - Running setup.py (path:/tmp/pip-R5ObAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:29 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)) 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:29 INFO - Running setup.py (path:/tmp/pip-8NTQwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:29 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)) 04:13:29 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)) 04:13:29 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)) 04:13:29 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)) 04:13:29 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)) 04:13:29 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)) 04:13:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:29 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 04:13:29 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 04:13:29 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 04:13:29 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 04:13:29 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 04:13:29 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 04:13:31 INFO - Downloading blessings-1.6.tar.gz 04:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:31 INFO - Installing collected packages: blessings 04:13:31 INFO - Running setup.py install for blessings 04:13:31 INFO - Successfully installed blessings 04:13:31 INFO - Cleaning up... 04:13:31 INFO - Return code: 0 04:13:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:31 INFO - Reading from file tmpfile_stdout 04:13:31 INFO - Current package versions: 04:13:31 INFO - argparse == 1.2.1 04:13:31 INFO - blessings == 1.6 04:13:31 INFO - blobuploader == 1.2.4 04:13:31 INFO - docopt == 0.6.1 04:13:31 INFO - manifestparser == 1.1 04:13:31 INFO - mozInstall == 1.12 04:13:31 INFO - mozcrash == 0.17 04:13:31 INFO - mozdebug == 0.1 04:13:31 INFO - mozdevice == 0.48 04:13:31 INFO - mozfile == 1.2 04:13:31 INFO - mozhttpd == 0.7 04:13:31 INFO - mozinfo == 0.9 04:13:31 INFO - mozleak == 0.1 04:13:31 INFO - mozlog == 3.1 04:13:31 INFO - moznetwork == 0.27 04:13:31 INFO - mozprocess == 0.22 04:13:31 INFO - mozprofile == 0.28 04:13:31 INFO - mozrunner == 6.11 04:13:31 INFO - mozscreenshot == 0.1 04:13:31 INFO - mozsystemmonitor == 0.0 04:13:31 INFO - moztest == 0.7 04:13:31 INFO - mozversion == 1.4 04:13:31 INFO - psutil == 3.1.1 04:13:31 INFO - requests == 1.2.3 04:13:31 INFO - wsgiref == 0.1.2 04:13:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:32 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:32 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 04:13:32 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 04:13:32 INFO - Using env: {'DISPLAY': ':0', 04:13:32 INFO - 'HOME': '/home/cltbld', 04:13:32 INFO - 'LANG': 'en_US.UTF-8', 04:13:32 INFO - 'LANGUAGE': 'en_US:en', 04:13:32 INFO - 'LOGNAME': 'cltbld', 04:13:32 INFO - 'MAIL': '/var/mail/cltbld', 04:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:32 INFO - 'MOZ_NO_REMOTE': '1', 04:13:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:32 INFO - 'NO_EM_RESTART': '1', 04:13:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:32 INFO - 'PWD': '/builds/slave/test', 04:13:32 INFO - 'SHELL': '/bin/bash', 04:13:32 INFO - 'SHLVL': '1', 04:13:32 INFO - 'TERM': 'linux', 04:13:32 INFO - 'TMOUT': '86400', 04:13:32 INFO - 'USER': 'cltbld', 04:13:32 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:32 INFO - '_': '/tools/buildbot/bin/python'} 04:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 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)) 04:13:32 INFO - Cleaning up... 04:13:32 INFO - Return code: 0 04:13:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:13:32 INFO - Using env: {'DISPLAY': ':0', 04:13:32 INFO - 'HOME': '/home/cltbld', 04:13:32 INFO - 'LANG': 'en_US.UTF-8', 04:13:32 INFO - 'LANGUAGE': 'en_US:en', 04:13:32 INFO - 'LOGNAME': 'cltbld', 04:13:32 INFO - 'MAIL': '/var/mail/cltbld', 04:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:32 INFO - 'MOZ_NO_REMOTE': '1', 04:13:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:32 INFO - 'NO_EM_RESTART': '1', 04:13:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:32 INFO - 'PWD': '/builds/slave/test', 04:13:32 INFO - 'SHELL': '/bin/bash', 04:13:32 INFO - 'SHLVL': '1', 04:13:32 INFO - 'TERM': 'linux', 04:13:32 INFO - 'TMOUT': '86400', 04:13:32 INFO - 'USER': 'cltbld', 04:13:32 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:32 INFO - '_': '/tools/buildbot/bin/python'} 04:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:32 INFO - Downloading/unpacking jsonschema 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:34 INFO - Downloading/unpacking functools32 (from jsonschema) 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:34 INFO - warning: no files found matching '*.txt' 04:13:34 INFO - no previously-included directories found matching 'build' 04:13:34 INFO - no previously-included directories found matching 'dist' 04:13:34 INFO - no previously-included directories found matching '.git*' 04:13:34 INFO - Installing collected packages: jsonschema, functools32 04:13:34 INFO - Running setup.py install for functools32 04:13:34 INFO - warning: no files found matching '*.txt' 04:13:34 INFO - no previously-included directories found matching 'build' 04:13:34 INFO - no previously-included directories found matching 'dist' 04:13:34 INFO - no previously-included directories found matching '.git*' 04:13:34 INFO - Successfully installed jsonschema functools32 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 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 0x7fe89e465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, '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': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', '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 04:13:34 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 04:13:34 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 04:13:34 INFO - Using env: {'DISPLAY': ':0', 04:13:34 INFO - 'HOME': '/home/cltbld', 04:13:34 INFO - 'LANG': 'en_US.UTF-8', 04:13:34 INFO - 'LANGUAGE': 'en_US:en', 04:13:34 INFO - 'LOGNAME': 'cltbld', 04:13:34 INFO - 'MAIL': '/var/mail/cltbld', 04:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:34 INFO - 'MOZ_NO_REMOTE': '1', 04:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:34 INFO - 'NO_EM_RESTART': '1', 04:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:34 INFO - 'PWD': '/builds/slave/test', 04:13:34 INFO - 'SHELL': '/bin/bash', 04:13:34 INFO - 'SHLVL': '1', 04:13:34 INFO - 'TERM': 'linux', 04:13:34 INFO - 'TMOUT': '86400', 04:13:34 INFO - 'USER': 'cltbld', 04:13:34 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:34 INFO - '_': '/tools/buildbot/bin/python'} 04:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:35 INFO - Downloading/unpacking flake8 04:13:35 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 04:13:35 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 04:13:35 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 04:13:35 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 04:13:35 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 04:13:35 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 04:13:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:13:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:13:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 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 04:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:13:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:13:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:13:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:13:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:13:37 INFO - no previously-included directories found matching 'docs/_build' 04:13:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:13:37 INFO - Running setup.py install for pep8 04:13:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:13:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:13:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:13:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:13:37 INFO - no previously-included directories found matching 'docs/_build' 04:13:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:13:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:13:37 INFO - Cleaning up... 04:13:37 INFO - Return code: 0 04:13:37 INFO - Running post-action listener: _resource_record_post_action 04:13:37 INFO - Running post-action listener: _start_resource_monitoring 04:13:37 INFO - Starting resource monitoring. 04:13:37 INFO - ##### 04:13:37 INFO - ##### Running install step. 04:13:37 INFO - ##### 04:13:37 INFO - Running pre-action listener: _resource_record_pre_action 04:13:37 INFO - Running main action method: install 04:13:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:38 INFO - Reading from file tmpfile_stdout 04:13:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:38 INFO - Reading from file tmpfile_stdout 04:13:38 INFO - Output received: 04:13:38 INFO - Usage: mozinstall [options] installer 04:13:38 INFO - Options: 04:13:38 INFO - -h, --help show this help message and exit 04:13:38 INFO - -d DEST, --destination=DEST 04:13:38 INFO - Directory to install application into. [default: 04:13:38 INFO - "/builds/slave/test"] 04:13:38 INFO - --app=APP Application being installed. [default: firefox] 04:13:38 INFO - mkdir: /builds/slave/test/build/application 04:13:38 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'] 04:13:38 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 04:13:53 INFO - Reading from file tmpfile_stdout 04:13:53 INFO - Output received: 04:13:53 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:53 INFO - Running post-action listener: _resource_record_post_action 04:13:53 INFO - ##### 04:13:53 INFO - ##### Running run-tests step. 04:13:53 INFO - ##### 04:13:53 INFO - Running pre-action listener: _resource_record_pre_action 04:13:53 INFO - Running main action method: run_tests 04:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:53 INFO - Python 2.7.3 04:13:53 INFO - Return code: 0 04:13:53 INFO - grabbing minidump binary from tooltool 04:13: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')]} 04:13:53 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 0x2cc4410>, '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 0x2cc5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc57a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:53 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 04:13:53 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 04:13:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:53 INFO - Return code: 0 04:13:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:13:54 INFO - Return code: 0 04:13:54 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/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 04:13:54 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/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 04:13:54 INFO - Using env: {'DISPLAY': ':0', 04:13:54 INFO - 'HOME': '/home/cltbld', 04:13:54 INFO - 'LANG': 'en_US.UTF-8', 04:13:54 INFO - 'LANGUAGE': 'en_US:en', 04:13:54 INFO - 'LOGNAME': 'cltbld', 04:13:54 INFO - 'MAIL': '/var/mail/cltbld', 04:13:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:54 INFO - 'MOZ_NO_REMOTE': '1', 04:13:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:54 INFO - 'NO_EM_RESTART': '1', 04:13:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:54 INFO - 'PWD': '/builds/slave/test', 04:13:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:54 INFO - 'SHELL': '/bin/bash', 04:13:54 INFO - 'SHLVL': '1', 04:13:54 INFO - 'TERM': 'linux', 04:13:54 INFO - 'TMOUT': '86400', 04:13:54 INFO - 'USER': 'cltbld', 04:13:54 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1462533029.343642-541288447', 04:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:54 INFO - '_': '/tools/buildbot/bin/python'} 04:13:54 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/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 04:13:54 INFO - mozversion application_buildid: 20160506013623 04:13:54 INFO - mozversion application_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:13:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:13:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:54 INFO - mozversion application_name: Firefox 04:13:54 INFO - mozversion application_remotingname: firefox-dev 04:13:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:54 INFO - mozversion application_vendor: Mozilla 04:13:54 INFO - mozversion application_version: 48.0a2 04:13:54 INFO - mozversion platform_buildid: 20160506013623 04:13:54 INFO - mozversion platform_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:13:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:54 INFO - mozversion platform_version: 48.0a2 04:13:54 INFO - using testdate: 1462533234 04:13:54 INFO - actual date: 1462533234 04:13:54 INFO - starting webserver on 'localhost:60496' 04:13:54 INFO - SUITE-START | Running 2 tests 04:13:54 INFO - TEST-START | dromaeo_css 04:13:54 INFO - operating with platform_type : linux_ 04:13:54 INFO - Initialising browser for dromaeo_css test... 04:13:54 INFO - TEST-INFO | started process 4246 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCIt3Fu/profile http://localhost:60496/getInfo.html) 04:13:59 INFO - PROCESS | 4246 | __metrics Screen width/height:1600/1200 04:13:59 INFO - PROCESS | 4246 | colorDepth:24 04:13:59 INFO - PROCESS | 4246 | Browser inner width/height: 1024/697 04:13:59 INFO - PROCESS | 4246 | __metrics 04:14:00 ERROR - PROCESS | 4246 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:14:01 INFO - TEST-INFO | 4246: exit 0 04:14:01 INFO - Browser initialized. 04:14:01 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:01 INFO - TEST-INFO | started process 4338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCIt3Fu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:13 INFO - PROCESS | 4338 | RSS: Main: 142204928 04:14:13 INFO - PROCESS | 4338 | 04:15:04 INFO - PROCESS | 4338 | 1462533304960 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] 04:17:11 INFO - PROCESS | 4338 | Cycle 1(1): loaded http://localhost:60496/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60496/tests/dromaeo/cssquery-ext.html) 04:17:13 INFO - PROCESS | 4338 | RSS: Main: 255283200 04:17:13 INFO - PROCESS | 4338 | 04:20:15 INFO - PROCESS | 4338 | Cycle 1(1): loaded http://localhost:60496/tests/dromaeo/cssquery-ext.html (next: http://localhost:60496/tests/dromaeo/cssquery-jquery.html) 04:20:15 INFO - PROCESS | 4338 | RSS: Main: 232628224 04:20:15 INFO - PROCESS | 4338 | 04:23:17 INFO - PROCESS | 4338 | Cycle 1(1): loaded http://localhost:60496/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60496/tests/dromaeo/cssquery-mootools.html) 04:23:18 INFO - PROCESS | 4338 | RSS: Main: 232521728 04:23:18 INFO - PROCESS | 4338 | 04:26:20 INFO - PROCESS | 4338 | Cycle 1(1): loaded http://localhost:60496/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60496/tests/dromaeo/cssquery-prototype.html) 04:26:20 INFO - PROCESS | 4338 | RSS: Main: 224432128 04:26:20 INFO - PROCESS | 4338 | 04:29:23 INFO - PROCESS | 4338 | Cycle 1(1): loaded http://localhost:60496/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60496/tests/dromaeo/cssquery-yui.html) 04:29:23 INFO - PROCESS | 4338 | RSS: Main: 211988480 04:29:23 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | Cycle 1(1): loaded http://localhost:60496/tests/dromaeo/cssquery-yui.html (next: http://localhost:60496/tests/dromaeo/cssquery-dojo.html) 04:32:26 INFO - PROCESS | 4338 | RSS: Main: 247730176 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | __start_tp_report 04:32:26 INFO - PROCESS | 4338 | _x_x_mozilla_page_load 04:32:26 INFO - PROCESS | 4338 | _x_x_mozilla_page_load_details 04:32:26 INFO - PROCESS | 4338 | |i|pagename|runs| 04:32:26 INFO - PROCESS | 4338 | |0;dojo.html;3287.4411350070486;3302.3725492156486;3304.173956510872;3309.3050459403526;3313;5554.472325129112;5560.193771903077;5580.525655319292;5583;5594;5572.442755724425;5587.804426845051;5594.188842617818;5603.355614104369;5626.746796394162;4995.825146119886;5065.113605119108;5140.794368225285;5200.68795872247;5221.347331583552;5009.423916249623;5025.773840177201;5049.747512624373;5105.897882042357;5149.17613181891;5146.279520867063;5190.195519694454;5193.688378697271;5198;5198.09033419152;5098.949010509885;5116.334876466073;5178.430372659004;5193.896122077556;5195.610329225323;4889.242167464049;5035.899282014376;5093.668911520739;5098;5129.358830146231;5445.264889239941;5457.099578569531;5469.371022332443;5473.151661492475;5477.643953143033;5874.588778785486;5913.881722365529;5922.437368449991;5923.970380148115;5927.199828023205;5793.942060579382;5840.766369345221;5845.795397161079;5869.9706501467645;5882.23530940975;5629.324481062299;5630.296212973338;5630.380658127603;5632.71836408182;5653.378128405872;6017.368176341509;6073.1497590337385;6116.816495505053;6132.202813634288;6149.292831324366;5730.7421166047625;5733.569982251348;5736.254286942754;5743.425657434307;5772.538196944234;5801;5814.418558144236;5815.41845815415;5817.76728930838;5823.097419899922;5771.163181338767;5774.480296773227;5824.621399609012;5908.911366329508;5999.850003749942;5984.102384642251;5989.341172471112;5991.191189189491;5992.910106348409;5997.880042399128;4463.754493502824;4539.954600454019;4545.636349092065;4546.340780586796;4581.679282450196;3978.482797236341;3987.8205480753436;3989.162275922031;4004.5795191505053;4066.02415420297;5530.751116199781;5537.390887002426;5540.168974653753;5542.39033706292;5547.472990065938;5798.217240672455;5805.82582522525;5811.535077193815;5818.679972601463;5838.737256823453;5444;5610.831675049755;5616.803411880564;5619.8595035123635;5636.830895073154;5081.0854046272025;5106.821261255913;5110;5120.743962801845;5139.665921715076;5930.584859059911;6125.693715314216;6145.701690665064;6159.630422174684;6183.041628547582;5967.731452084667;5986.2517185351835;6004.8799024020145;6013.098035294741;6032;6117.082437634391;6133.877322453437;6134.4172303631085;6137.723802428812;6142.109394137914;5635.859103522281;5674.319081710221;5812.94187058141;5817;5821.94178058214;5934.287885453773;5955.821325360246;5959.046552551571;5964.582479226413;5998.670073146019;5788.971055144697;5831.979403604437;5832.708364581838;5834.15404766315;5835.299764028344;5633.3521645009705;5790.971045144747;5792.102224155263;5797.768089276382;5871.1486834409625;5765.048766953406;5769.192313076089;5778.971105144616;5791.971040144772;5792.0443126885275;5872.236609240771;5894.0274854648505;5896.55775816824;5913.408659134052;5917.437843404869;5667.149927510906;5715.828525144252;5754.309482862083;5806.4193580641595;5842.065269556851 04:32:26 INFO - PROCESS | 4338 | |1;ext.html;668.9431398331142;677.2888467109535;690.5925503952667;742.6138408027825;760.0005992120365;32025.35949281013;32443.351132977357;32453;32626;38547.22905541887;42767.57232427675;43259.56740432595;43284.783576082074;43312.78343608286;43364.56635433645;8651.653933842654;8876.378653494257;8887.733367998939;8888.244499217575;8889.377743557954;8342.708005219818;10710.303830251049;11004.944975275112;11013.118950483982;11015;10550.314229575091;10628.46857657114;10633.46832658368;10639.52122154501;10654.041136297732;8312.8337433251;10538.47307634619;10625.203109766799;10631.468426578678;10732.302400344031;10350.844737328945;10509.632162874339;10510.947445262726;10514;10516.68449946503;38615.80692096549;39052;39055.4141687872;39064.804675976724;39085;3922.8626998054933;3974.5429275633383;3980.7810570418615;4414.757188354639;4439.711418757771;33210;33916.321673566636;34071;34504.137396565035;34519.82740086308;4388.692791504596;4445.888852778674;4473.194824931511;4520.638348932078;4565.977170114162;320123;320793;320853;321655;323131.9999999977;39420;40195.79902100471;60140.39859601348;60214.39785602088;60610.39389606136;40244.59755402408;40284;40431.19137617231;40482.39276410856;40583.59416405857;17610.911945440446;17787.822121778874;17807.643847122985;17811.821881781016;17819.19813608391;12479.75040499185;14511.492097776527;14722;14768.852311476963;14780.852191478163;9254.907450925539;9260.21288190498;9262.675806346746;9323.813523729623;9346.719598412059;3575.4636804479014;3630.8184590770356;3631.891043268706;3658.8902332930056;3661.7802931824176;1153.4694040741354;1175.7119505720982;1179.1745777955293;1198.3169593331718;1233.5682511121036;33792.49311260333;33871.49192762111;33876.49185262223;34195.31609367799;34389.828050859585;1246.3954981833804;1253.5048655780915;1269.6127681057183;1289.6517940156104;1292.8642492538147;17867.553311167067;17929.551761205814;18151;18285.908570457064;18303.26786928508;446596;450689;450880;453646;455323;62803.6859815698;63038.68480657567;63076.36923630705;63593.68203159047;63670.68164659147;1371.9657008574666;1441.7549016667192;1496.2518740629685;1507.8341382447923;1536.1320853717614;32005.6799432012;34084.488732668535;34767.13082172966;34921.8253908739;35369.82315088408;49668.25497617466;50058.49941500684;50115.74942125266;50218.748906255234;50255.49744502654;305971;311666;313034;313405;313646;26744.59883101794;30418.087457376314;30593.54109688401;31082.844585776926;31425.214369640027;44385.77807110944;44885.551144488134;44923.550764491934;45942.770286148356;46114.308285375075;41572.792136040334;42091.368629471195;42812.143757125305;43357;43750.78124609357;40441.19117617572;41881;41889.37165942453;42588;43036.35445468383;42502.36246456244;43167.35248971331;43558;44074.559254407046;44152.77923610361;35673.46489802707;36711.816440917624;37180.44229336508;37534.8123259382;37972.81013594914;28369.148925532267;28412.43175136527;28624.85687571549;28742.856285718437;28869.27826804347 04:32:26 INFO - PROCESS | 4338 | |2;jquery.html;422.647089680117;423.88131323229504;425.8126424373274;433.07106257078567;442.637037629144;18736.90631546842;19124.80875191248;19572.706409403854;19602.803971960293;19989.900050499764;12243.632691019262;12595.622131336051;13130.737385252289;18934.053297335147;18940.337088201915;5217.660852044614;5313.8937221255555;5346.438623944488;5352.652077614962;5373.194020896877;4150.128473020669;5756.625819321752;5815.011448053841;6006.609570377933;6027.336992930774;6002.189704389895;6005.159277701126;6005.909911351333;6025.276966763994;6029.849253768647;5859.8535036624;6531.0856480092825;6533.411992920636;6533.9872319790275;6537.215534135934;5820.854478638025;5833.125031245305;5861.824145275648;5862.941370586283;5872.412758724136;13969.301534923214;14419.279036048156;14442.638934026629;14450.132992020512;14461.566153015427;2433.683621129242;2537.581299085649;2555.3100662820934;2573.125137453275;2974.3307755755;10340.793184136275;10421.374717516974;10558.841617375745;10887.346759194394;10894.782104357948;1987.2419069573373;2009.2364901337544;2093.5812837432572;2097.0982477534653;2102.306238941161;75244;75610.62194689046;78284.60857695733;79926.6003669978;80757;11292.71768205785;11384.886151138548;11514.827277590843;11520.251183673034;11680.182387232811;15896.602584935237;15969.680606387807;15975.12136832462;15995.200239987955;16143.112128833027;8644.13558644143;8658.740237792876;8957.14907083826;8961.731148065568;9017.053209412918;8600.483970961763;8730.956345218234;8752.212300892823;8770.210681038738;8924.8661270081;4913.083897280064;4962.305277261186;4989.476105008995;4992.275875815987;5018.698878067327;1768.942141851671;1914.5979344337845;2040.326692191603;2067.077617815416;2069.027557048185;1022.7340891368298;1057.381431862369;1059.8516207730925;1060.188955449082;1096.8628921384827;18011.459656210336;18154.273829046953;18159.909200453912;18946.24215031411;19823.30618428377;1077.1382893685177;1101.212632967416;1109.778044391125;1124.578283143821;1155.5204590095184;10606.310589811637;10713.464326783629;11153.553857845758;11352.54589816398;11386.373749443861;362768;374283;376556;377819;383668;93537.53231233801;93583.53208233915;94011.52994234985;94555.52722236345;95058;1125.8536390269212;1136.244397475686;1196.0431654676224;1213.8239955206445;1214.4838443661408;17123;17374.305027798746;17715.557111072325;17786.199621017346;18023.2790688371;41082.79458602688;41095.7945210272;41469.585304146574;41652.79173604234;42602.78698606611;58233;58555.41444585499;58576.4142358571;58940.11589826242;59009;9038;9152.267818574632;9167.862482062908;9382.10869967352;9408.43549387039;13300.401481933142;13773.862261377259;13913.304334783423;13923.16461012302;14136.222507762172;12153.69615759613;12210.38948052576;12274.631761046825;12331.445084971378;12681.619551413472;11738.178327516991;11861.822072668745;11979.161458697807;12046.457909393923;12278.815817762563;12163.452644630835;12351.444185011516;12660.810087848506;12877.935610321889;13028.739425211632;8993.370464067453;9163.358564900393;9185.016754427945;9313.720588382359;9328.300377471853;9353.485558294358;12407.441665125272;12755.362231888183;12771.233725976472;12783.105182637126 04:32:26 INFO - PROCESS | 4338 | |3;mootools.html;1406.430395689746;1544.2201688147488;1551.8137823461186;1732.445617402431;1865.2818664814042;8335.333173346133;8539.188777066169;8544.273736732384;8592.828143437127;8598;8256.133106023872;8435.62039708213;8455.112213217615;8482.363822713305;8527.91472085279;2098;2337.275444612167;2389.6893403857475;2390.9043638254448;2413.7706917842775;2372.596658568039;2380.523895220963;2381.9285421437385;2384.9046038158453;2386.916457923973;2334.4980829121737;2420.6732091167733;2423.5758742220128;2423.8303318767694;2424.115197952752;2077.2521892118825;2328.7787660172257;2377.1442280778906;2381.2975172324204;2385.1413491143176;2317.9304620861403;2326.988365058181;2327.94180145496;2331.2889568681544;2387.212219967407;8636.82726345476;8716.128387161296;8738.126187381211;8740.388172827905;8742.169493898133;5301.920471192935;5372.489613486751;5422.2951016368015;5429.104197807357;5438.293021907166;3453.861845526176;3479;3509.78941263525;3587.551556055493;3621.130928577149;1091.2252300866394;1096.714854137922;1112.0825319111766;1127.0645364347572;1159.4029075026367;12329.136960412865;12420.62738117858;12420.62738117858;12433.129680922339;12447.875521244761;4881.316615673809;4888.315635810989;4914.361133052752;4926.482719314492;4970.577500912391;10468.842967355497;10515.94742026285;10522.842157367646;10525.631602893862;10537.473126343652;4815.494373090775;5418.431064738145;5498.367687715884;5499.092649712753;5504.91742623861;4912.312276281324;4975.203967365204;5010.398752149693;5024.899502009939;5045.344105266292;4049.271131196413;4067.247559201557;4086.693497987663;4140.296149654566;4152.356384760367;1297.9740405191844;1309.0901823232762;1315.3817705678316;1317.4861803896488;1324.5562736483387;1008.5310330696115;1020.132887046005;1023.247912784103;1024.364893765855;1039.3400190878695;5715.5427565794635;5718.170865224519;5755.3381361144;5771.826845194651;5834.620749651259;933.3466573398513;975.48299401316;977.5210147027905;1032.0041160280366;1053.3627155570923;3809.4095415210686;3816.4084566892175;3819.3698039823676;3841.058940559525;3872.5740168581433;10246.924072972379;10576.471176441148;10627.574897004188;10648.893511064945;10661.200409969284;10429.634962776267;10468.581256749798;10564.471776411148;10781;10861.945690271497;8268.462549934235;8359.623816928253;8432.747017589481;8482.618282177316;8486.19381158789;5568;5580.581456390868;5643.294588176478;5731.971340143273;5797.275340582427;9515.952420237854;9785.510724463891;9820.312578119463;9995.100440960057;10024.899751002687;9359.672411465566;9796.608135674493;9957.502124893872;10000.249981251582;10030.699079027641;6987.650617469005;7493.512921660293;7721.961390193238;7920.445568810128;8065.314448271728;8344.374171937008;8348.749537513884;8381.664733410595;8414.032386275656;8424.28393586552;7488.775336739906;7703.614819259126;7720.53676779395;7734.5359278443475;7768.961155194188;7442.776716698508;7508.549487030577;7534.39724822002;7611.771646850603;7638.694452221849;7117.8576428470105;7165.928340716526;7252.4560657949805;7308.415326773739;7313.451491138079;5116.513931176532;5151.2273159026445;5207.531322181022;5239.895202096013;5265.894682106413;5133.998870220421;5222.55608273301;5243.344581927259;5251.291075704731;5360.2227676987395 04:32:26 INFO - PROCESS | 4338 | |4;prototype.html;355.9181388280695;363.3514177193709;363.87082585682083;369.9167687270363;370.01206777903013;6207.844803879905;6393.136926514919;6439.162908821852;6449.322821103787;6494.642794646293;6243.5629505934485;6340.4927605791545;6347.206599175103;6359;6363.936360636392;4617.3074038894265;4713.316569097479;4813.157697402958;4842.370491836056;4859.902801943959;4820.132376172288;4825.396825396825;4834.4923783002805;4853.466118726955;4861.076395484864;4749.810007599709;4753.928691069636;4756.952430475703;4762.047590481918;4775.3553270308585;4561.247394179973;4643.535646435364;4699.835505757316;4700.2479603263655;4770.332153498513;4120.608542188487;4122.381642753581;4127.855525056639;4135.193637240739;4135.503739551243;5987.91018134724;6033.577649564532;6045.848853778647;6049.395060493959;6055.636661800262;5523.889522209573;5527.115661494142;5532.059549876531;5539.169124631298;5541.639793413456;6626.966865165691;6627.536072474929;6638.336166383371;6639.070530125786;6642.501812364092;4474.105178964188;4569.200389931749;4577.542245775396;4600.17196904557;4632.467266264389;6900.137482814648;6954.3045695430055;6959.721611135548;6976.441884649216;6979.092717946634;4646.233371493733;4648.907021859543;4655.045715628288;4656.464506581787;4657.953420465819;4718.646101542398;4719.292106184032;4719.882002949954;4728.1252968201;4729.574338309569;5487.094629386186;5490.862728431741;5492.066348720727;5501.779928802803;5502.9174562381595;6476.676166191766;6632.9336706633285;6639.502037347218;6704.932950670528;6718.697658605374;1652.4133932454047;1666.691662042526;1679.4373884748502;1680.126334306154;1680.823513531086;1076.682378698286;1095.370162156763;1107.983380249297;1110.0841805510486;1112.5994641928899;6297.685115744286;6410;6502.252240992347;6514.055461958084;6560.311167327457;6992.860142797115;7002.7899163025195;7005.299470052954;7015.824604384829;7020.368166865007;6570.967145164243;6571.967140164268;6580.144581204413;6586.407223349922;6591.6374599397495;3714.368557345204;3759.548854137521;3764.2847858907267;3764.4729737836724;3769.1142581493327;6951.826204344932;7179.317964793337;7181.102362204724;7226.891596626055;7244.420446364278;7063.752768653072;7096.396806271525;7111.502194846415;7112.608806515691;7127.857442851114;7254.020707204565;7255.854882902312;7260.745873894389;7263.055802745609;7272.600006999666;6983.755568555076;6991.475639327173;6996.580205187705;7002.894956575554;7007.649617519206;6714.496412769159;6894.276101009322;6898.413634841095;6905.274946130785;6939.965300173467;7237.421006319377;7265.818354541178;7275.708971641075;7280.271972802678;7284;4716.504766999416;4760.166970780169;4761.381020467317;4761.547652972962;4768.189407800613;6975.546589471668;6977.79066628002;6992.475564332593;6994.405475534637;7002.579845209304;6667.066610674607;6769.559978601375;6793.150856142982;6815.488838337045;6823.931760682528;6741.898871517029;6756.493263005393;6765.797026089225;6773.898391524032;6774.796756097325;6662.700178492077;6663.233728121134;6667.233268174028;6669.032990216295;6675.666216689243;6786.948023056434;6826.795196144124;6827.146606674166;6830.829229269308;6839.521233513805;6342.873142537216;6577.572457790228;6623.6688165592495;6652.401283884474;6667.266600674019 04:32:26 INFO - PROCESS | 4338 | |5;yui.html;878.9912100878992;916.5371487398867;919.2645883293363;932.6362718539771;939.0327962198937;15007.024543404685;15131.31909064092;15159.393624255044;15173.317200725965;15340.38638454462;16050.678986420295;16381.262843172057;16431.83568164318;16508.75236871448;16530.752038719427;760.8250102476434;891.1623074310128;891.31814162166;908.5094049213417;914.2092090341873;897.824924139793;903.0833703790657;904.2720609909021;906.6192199276287;907.346710368537;937.1940131486916;945.6595625574788;945.862849886766;946.1815529566944;946.4699768129869;789.6407134753673;927.2026057590467;932.8320902237595;945.7966537194536;946.7917058247243;690.5614934516568;693.2062788107639;693.7571849852561;695.3637421759084;696.3384784454788;14858.554243372608;14932.552023439313;14954.40182392703;14964.925175374163;14986.70026599462;12727.299998500079;13116.409761560657;14062.296885155702;14337.56987290383;14370.92814535931;12106.152569320151;12132.393380331036;12987.350632468433;13121.212727236301;13142.751438613412;4087.1008378156757;4144.8756537303925;4148.253314403406;4182.2890108681595;4246.044639956016;61742;61872.071918921254;61896.69051654713;62566;62732;11964.700882478008;12077.818832717516;12087.456064477119;12330.19853709506;12570.245785252913;22178.44553886166;22182.223622173147;22211.66682499764;22232.444188895406;22285.554288914132;9109.544522773967;9113.40762850426;9188.95405522968;9214.262858971266;9444.433333999981;5112.897742045138;5134.460881607452;5158.226266060121;5256.1327380982475;5322.654027488201;3696.815159242027;3750.362438385481;3765.548134223856;3770.057485628593;3771.962280377216;635.9554831161867;654.5876098058288;654.9246836613755;662.6289278004236;678.6742363665468;687.4775170870174;742.7103429662436;746.2425637977457;759.2407592407592;761.1741260732057;9634.470104144199;9658.323917325719;9752.171065459515;9988.500574971222;10054.59781608742;654.9312322206194;730.5689643110591;786.5595266650694;795.347516034033;804.4288555125809;4482.462104547475;4590.632749380042;4592.7933243004145;4648.163330600524;4730.361401210861;67162.32837671658;68018.65990670114;68157;68190;68198.65900670465;57701.711491443115;59699;60379.396206037374;60698.69650651718;60707.39292607106;52121.47878521242;54607.18089228665;54777;55045.44954550403;55704.72147639236;9329;9460.432374057578;9477.383970041925;9509.952450237704;9564.856527151958;40170.59829401669;41144.38283425811;41284.1743165141;41634.79182604067;41858.1628367437;46813;47166.29250561176;47720.7613961928;52791.47208528019;53022.73488632532;12119.757604847677;12736.299503527025;13013.804792927924;13115.08194426381;13287.335633218494;11594.536218551164;13745.862541374858;14219.443626067667;14362.856371436152;14697.63478364277;13230;13482.93258533701;13574.389152488358;13596.184228946295;13887.513937012156;13642.522511712043;13662.453501859814;14065.367058482188;14210.857891420954;14460.349284282021;13428.127171733806;13603.727925441633;13725.450981961008;13891.444342226197;14241.715165696834;10594.735131621772;10735.087517561095;10756.515956781806;10818.170073638401;10949.069329106796;10205.74485637865;10229.232807539316;10317.226208034277;10342.431166285927;10624.946875265574 04:32:26 INFO - PROCESS | 4338 | __end_tp_report 04:32:26 INFO - PROCESS | 4338 | __start_cc_report 04:32:26 INFO - PROCESS | 4338 | _x_x_mozilla_cycle_collect,921 04:32:26 INFO - PROCESS | 4338 | __end_cc_report 04:32:26 INFO - PROCESS | 4338 | __startTimestamp1462534346378__endTimestamp 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | ------- Summary: start ------- 04:32:26 INFO - PROCESS | 4338 | Number of tests: 6 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | [#0] dojo.html Cycles:165 Average:5515.35 Median:5736.25 stddev:606.07 (10.6%) stddev-sans-first:582.18 04:32:26 INFO - PROCESS | 4338 | Values: 3287.4 3302.4 3304.2 3309.3 3313.0 5554.5 5560.2 5580.5 5583.0 5594.0 5572.4 5587.8 5594.2 5603.4 5626.7 4995.8 5065.1 5140.8 5200.7 5221.3 5009.4 5025.8 5049.7 5105.9 5149.2 5146.3 5190.2 5193.7 5198.0 5198.1 5098.9 5116.3 5178.4 5193.9 5195.6 4889.2 5035.9 5093.7 5098.0 5129.4 5445.3 5457.1 5469.4 5473.2 5477.6 5874.6 5913.9 5922.4 5924.0 5927.2 5793.9 5840.8 5845.8 5870.0 5882.2 5629.3 5630.3 5630.4 5632.7 5653.4 6017.4 6073.1 6116.8 6132.2 6149.3 5730.7 5733.6 5736.3 5743.4 5772.5 5801.0 5814.4 5815.4 5817.8 5823.1 5771.2 5774.5 5824.6 5908.9 5999.9 5984.1 5989.3 5991.2 5992.9 5997.9 4463.8 4540.0 4545.6 4546.3 4581.7 3978.5 3987.8 3989.2 4004.6 4066.0 5530.8 5537.4 5540.2 5542.4 5547.5 5798.2 5805.8 5811.5 5818.7 5838.7 5444.0 5610.8 5616.8 5619.9 5636.8 5081.1 5106.8 5110.0 5120.7 5139.7 5930.6 6125.7 6145.7 6159.6 6183.0 5967.7 5986.3 6004.9 6013.1 6032.0 6117.1 6133.9 6134.4 6137.7 6142.1 5635.9 5674.3 5812.9 5817.0 5821.9 5934.3 5955.8 5959.0 5964.6 5998.7 5789.0 5832.0 5832.7 5834.2 5835.3 5633.4 5791.0 5792.1 5797.8 5871.1 5765.0 5769.2 5779.0 5792.0 5792.0 5872.2 5894.0 5896.6 5913.4 5917.4 5667.1 5715.8 5754.3 5806.4 5842.1 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | [#1] ext.html Cycles:180 Average:52892.44 Median:3645.39 stddev:96659.97 (2651.6%) stddev-sans-first:96851.60 04:32:26 INFO - PROCESS | 4338 | Values: 668.9 677.3 690.6 742.6 760.0 32025.4 32443.4 32453.0 32626.0 38547.2 42767.6 43259.6 43284.8 43312.8 43364.6 8651.7 8876.4 8887.7 8888.2 8889.4 8342.7 10710.3 11004.9 11013.1 11015.0 10550.3 10628.5 10633.5 10639.5 10654.0 8312.8 10538.5 10625.2 10631.5 10732.3 10350.8 10509.6 10510.9 10514.0 10516.7 38615.8 39052.0 39055.4 39064.8 39085.0 3922.9 3974.5 3980.8 4414.8 4439.7 33210.0 33916.3 34071.0 34504.1 34519.8 4388.7 4445.9 4473.2 4520.6 4566.0 320123.0 320793.0 320853.0 321655.0 323132.0 39420.0 40195.8 60140.4 60214.4 60610.4 40244.6 40284.0 40431.2 40482.4 40583.6 17610.9 17787.8 17807.6 17811.8 17819.2 12479.8 14511.5 14722.0 14768.9 14780.9 9254.9 9260.2 9262.7 9323.8 9346.7 3575.5 3630.8 3631.9 3658.9 3661.8 1153.5 1175.7 1179.2 1198.3 1233.6 33792.5 33871.5 33876.5 34195.3 34389.8 1246.4 1253.5 1269.6 1289.7 1292.9 17867.6 17929.6 18151.0 18285.9 18303.3 446596.0 450689.0 450880.0 453646.0 455323.0 62803.7 63038.7 63076.4 63593.7 63670.7 1372.0 1441.8 1496.3 1507.8 1536.1 32005.7 34084.5 34767.1 34921.8 35369.8 49668.3 50058.5 50115.7 50218.7 50255.5 305971.0 311666.0 313034.0 313405.0 313646.0 26744.6 30418.1 30593.5 31082.8 31425.2 44385.8 44885.6 44923.6 45942.8 46114.3 41572.8 42091.4 42812.1 43357.0 43750.8 40441.2 41881.0 41889.4 42588.0 43036.4 42502.4 43167.4 43558.0 44074.6 44152.8 35673.5 36711.8 37180.4 37534.8 37972.8 28369.1 28412.4 28624.9 28742.9 28869.3 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | [#2] jquery.html Cycles:180 Average:25693.78 Median:2081.30 stddev:62640.23 (3009.7%) stddev-sans-first:62787.21 04:32:26 INFO - PROCESS | 4338 | Values: 422.6 423.9 425.8 433.1 442.6 18736.9 19124.8 19572.7 19602.8 19989.9 12243.6 12595.6 13130.7 18934.1 18940.3 5217.7 5313.9 5346.4 5352.7 5373.2 4150.1 5756.6 5815.0 6006.6 6027.3 6002.2 6005.2 6005.9 6025.3 6029.8 5859.9 6531.1 6533.4 6534.0 6537.2 5820.9 5833.1 5861.8 5862.9 5872.4 13969.3 14419.3 14442.6 14450.1 14461.6 2433.7 2537.6 2555.3 2573.1 2974.3 10340.8 10421.4 10558.8 10887.3 10894.8 1987.2 2009.2 2093.6 2097.1 2102.3 75244.0 75610.6 78284.6 79926.6 80757.0 11292.7 11384.9 11514.8 11520.3 11680.2 15896.6 15969.7 15975.1 15995.2 16143.1 8644.1 8658.7 8957.1 8961.7 9017.1 8600.5 8731.0 8752.2 8770.2 8924.9 4913.1 4962.3 4989.5 4992.3 5018.7 1768.9 1914.6 2040.3 2067.1 2069.0 1022.7 1057.4 1059.9 1060.2 1096.9 18011.5 18154.3 18159.9 18946.2 19823.3 1077.1 1101.2 1109.8 1124.6 1155.5 10606.3 10713.5 11153.6 11352.5 11386.4 362768.0 374283.0 376556.0 377819.0 383668.0 93537.5 93583.5 94011.5 94555.5 95058.0 1125.9 1136.2 1196.0 1213.8 1214.5 17123.0 17374.3 17715.6 17786.2 18023.3 41082.8 41095.8 41469.6 41652.8 42602.8 58233.0 58555.4 58576.4 58940.1 59009.0 9038.0 9152.3 9167.9 9382.1 9408.4 13300.4 13773.9 13913.3 13923.2 14136.2 12153.7 12210.4 12274.6 12331.4 12681.6 11738.2 11861.8 11979.2 12046.5 12278.8 12163.5 12351.4 12660.8 12877.9 13028.7 8993.4 9163.4 9185.0 9313.7 9328.3 9353.5 12407.4 12755.4 12771.2 12783.1 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | [#3] mootools.html Cycles:180 Average:5780.92 Median:4948.53 stddev:3224.89 (65.2%) stddev-sans-first:3217.18 04:32:26 INFO - PROCESS | 4338 | Values: 1406.4 1544.2 1551.8 1732.4 1865.3 8335.3 8539.2 8544.3 8592.8 8598.0 8256.1 8435.6 8455.1 8482.4 8527.9 2098.0 2337.3 2389.7 2390.9 2413.8 2372.6 2380.5 2381.9 2384.9 2386.9 2334.5 2420.7 2423.6 2423.8 2424.1 2077.3 2328.8 2377.1 2381.3 2385.1 2317.9 2327.0 2327.9 2331.3 2387.2 8636.8 8716.1 8738.1 8740.4 8742.2 5301.9 5372.5 5422.3 5429.1 5438.3 3453.9 3479.0 3509.8 3587.6 3621.1 1091.2 1096.7 1112.1 1127.1 1159.4 12329.1 12420.6 12420.6 12433.1 12447.9 4881.3 4888.3 4914.4 4926.5 4970.6 10468.8 10515.9 10522.8 10525.6 10537.5 4815.5 5418.4 5498.4 5499.1 5504.9 4912.3 4975.2 5010.4 5024.9 5045.3 4049.3 4067.2 4086.7 4140.3 4152.4 1298.0 1309.1 1315.4 1317.5 1324.6 1008.5 1020.1 1023.2 1024.4 1039.3 5715.5 5718.2 5755.3 5771.8 5834.6 933.3 975.5 977.5 1032.0 1053.4 3809.4 3816.4 3819.4 3841.1 3872.6 10246.9 10576.5 10627.6 10648.9 10661.2 10429.6 10468.6 10564.5 10781.0 10861.9 8268.5 8359.6 8432.7 8482.6 8486.2 5568.0 5580.6 5643.3 5732.0 5797.3 9516.0 9785.5 9820.3 9995.1 10024.9 9359.7 9796.6 9957.5 10000.2 10030.7 6987.7 7493.5 7722.0 7920.4 8065.3 8344.4 8348.7 8381.7 8414.0 8424.3 7488.8 7703.6 7720.5 7734.5 7769.0 7442.8 7508.5 7534.4 7611.8 7638.7 7117.9 7165.9 7252.5 7308.4 7313.5 5116.5 5151.2 5207.5 5239.9 5265.9 5134.0 5222.6 5243.3 5251.3 5360.2 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | [#4] prototype.html Cycles:180 Average:5612.89 Median:6444.24 stddev:1721.19 (26.7%) stddev-sans-first:1680.18 04:32:26 INFO - PROCESS | 4338 | Values: 355.9 363.4 363.9 369.9 370.0 6207.8 6393.1 6439.2 6449.3 6494.6 6243.6 6340.5 6347.2 6359.0 6363.9 4617.3 4713.3 4813.2 4842.4 4859.9 4820.1 4825.4 4834.5 4853.5 4861.1 4749.8 4753.9 4757.0 4762.0 4775.4 4561.2 4643.5 4699.8 4700.2 4770.3 4120.6 4122.4 4127.9 4135.2 4135.5 5987.9 6033.6 6045.8 6049.4 6055.6 5523.9 5527.1 5532.1 5539.2 5541.6 6627.0 6627.5 6638.3 6639.1 6642.5 4474.1 4569.2 4577.5 4600.2 4632.5 6900.1 6954.3 6959.7 6976.4 6979.1 4646.2 4648.9 4655.0 4656.5 4658.0 4718.6 4719.3 4719.9 4728.1 4729.6 5487.1 5490.9 5492.1 5501.8 5502.9 6476.7 6632.9 6639.5 6704.9 6718.7 1652.4 1666.7 1679.4 1680.1 1680.8 1076.7 1095.4 1108.0 1110.1 1112.6 6297.7 6410.0 6502.3 6514.1 6560.3 6992.9 7002.8 7005.3 7015.8 7020.4 6571.0 6572.0 6580.1 6586.4 6591.6 3714.4 3759.5 3764.3 3764.5 3769.1 6951.8 7179.3 7181.1 7226.9 7244.4 7063.8 7096.4 7111.5 7112.6 7127.9 7254.0 7255.9 7260.7 7263.1 7272.6 6983.8 6991.5 6996.6 7002.9 7007.6 6714.5 6894.3 6898.4 6905.3 6940.0 7237.4 7265.8 7275.7 7280.3 7284.0 4716.5 4760.2 4761.4 4761.5 4768.2 6975.5 6977.8 6992.5 6994.4 7002.6 6667.1 6769.6 6793.2 6815.5 6823.9 6741.9 6756.5 6765.8 6773.9 6774.8 6662.7 6663.2 6667.2 6669.0 6675.7 6786.9 6826.8 6827.1 6830.8 6839.5 6342.9 6577.6 6623.7 6652.4 6667.3 04:32:26 INFO - PROCESS | 4338 | 04:32:26 INFO - PROCESS | 4338 | [#5] yui.html Cycles:180 Average:16252.46 Median:46989.65 stddev:18980.22 (40.4%) stddev-sans-first:18998.35 04:32:26 INFO - PROCESS | 4338 | Values: 879.0 916.5 919.3 932.6 939.0 15007.0 15131.3 15159.4 15173.3 15340.4 16050.7 16381.3 16431.8 16508.8 16530.8 760.8 891.2 891.3 908.5 914.2 897.8 903.1 904.3 906.6 907.3 937.2 945.7 945.9 946.2 946.5 789.6 927.2 932.8 945.8 946.8 690.6 693.2 693.8 695.4 696.3 14858.6 14932.6 14954.4 14964.9 14986.7 12727.3 13116.4 14062.3 14337.6 14370.9 12106.2 12132.4 12987.4 13121.2 13142.8 4087.1 4144.9 4148.3 4182.3 4246.0 61742.0 61872.1 61896.7 62566.0 62732.0 11964.7 12077.8 12087.5 12330.2 12570.2 22178.4 22182.2 22211.7 22232.4 22285.6 9109.5 9113.4 9189.0 9214.3 9444.4 5112.9 5134.5 5158.2 5256.1 5322.7 3696.8 3750.4 3765.5 3770.1 3772.0 636.0 654.6 654.9 662.6 678.7 687.5 742.7 746.2 759.2 761.2 9634.5 9658.3 9752.2 9988.5 10054.6 654.9 730.6 786.6 795.3 804.4 4482.5 4590.6 4592.8 4648.2 4730.4 67162.3 68018.7 68157.0 68190.0 68198.7 57701.7 59699.0 60379.4 60698.7 60707.4 52121.5 54607.2 54777.0 55045.4 55704.7 9329.0 9460.4 9477.4 9510.0 9564.9 40170.6 41144.4 41284.2 41634.8 41858.2 46813.0 47166.3 47720.8 52791.5 53022.7 12119.8 12736.3 13013.8 13115.1 13287.3 11594.5 13745.9 14219.4 14362.9 14697.6 13230.0 13482.9 13574.4 13596.2 13887.5 13642.5 13662.5 14065.4 14210.9 14460.3 13428.1 13603.7 13725.5 13891.4 14241.7 10594.7 10735.1 10756.5 10818.2 10949.1 10205.7 10229.2 10317.2 10342.4 10624.9 04:32:26 INFO - PROCESS | 4338 | -------- Summary: end -------- 04:32:26 INFO - PROCESS | 4338 | 04:32:27 INFO - TEST-INFO | 4338: exit 0 04:32:27 INFO - TEST-OK | dromaeo_css | took 1112132ms 04:32:27 INFO - TEST-START | kraken 04:32:27 INFO - operating with platform_type : linux_ 04:32:27 INFO - Initialising browser for kraken test... 04:32:27 INFO - TEST-INFO | started process 4533 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKRJjJd/profile http://localhost:60496/getInfo.html) 04:32:31 INFO - PROCESS | 4533 | __metrics Screen width/height:1600/1200 04:32:31 INFO - PROCESS | 4533 | colorDepth:24 04:32:31 INFO - PROCESS | 4533 | Browser inner width/height: 1024/697 04:32:31 INFO - PROCESS | 4533 | __metrics 04:32:32 ERROR - PROCESS | 4533 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:32:33 INFO - TEST-INFO | 4533: exit 0 04:32:33 INFO - Browser initialized. 04:32:33 INFO - Running cycle 1/1 for kraken test... 04:32:33 INFO - TEST-INFO | started process 4630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKRJjJd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:45 INFO - PROCESS | 4630 | RSS: Main: 141824000 04:32:45 INFO - PROCESS | 4630 | 04:32:47 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60496/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:48 INFO - PROCESS | 4630 | RSS: Main: 196669440 04:32:48 INFO - PROCESS | 4630 | 04:32:51 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60496/tests/kraken/driver.html?testName=audio-dft) 04:32:51 INFO - PROCESS | 4630 | RSS: Main: 182284288 04:32:51 INFO - PROCESS | 4630 | 04:32:54 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60496/tests/kraken/driver.html?testName=audio-fft) 04:32:55 INFO - PROCESS | 4630 | RSS: Main: 272715776 04:32:55 INFO - PROCESS | 4630 | 04:32:57 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60496/tests/kraken/driver.html?testName=audio-oscillator) 04:32:57 INFO - PROCESS | 4630 | RSS: Main: 178573312 04:32:57 INFO - PROCESS | 4630 | 04:33:00 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60496/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:00 INFO - PROCESS | 4630 | RSS: Main: 177557504 04:33:00 INFO - PROCESS | 4630 | 04:33:03 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60496/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:04 INFO - PROCESS | 4630 | RSS: Main: 212258816 04:33:04 INFO - PROCESS | 4630 | 04:33:07 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60496/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:08 INFO - PROCESS | 4630 | RSS: Main: 213012480 04:33:08 INFO - PROCESS | 4630 | 04:33:11 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60496/tests/kraken/driver.html?testName=json-parse-financial) 04:33:11 INFO - PROCESS | 4630 | RSS: Main: 212430848 04:33:11 INFO - PROCESS | 4630 | 04:33:13 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60496/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:13 INFO - PROCESS | 4630 | RSS: Main: 194076672 04:33:13 INFO - PROCESS | 4630 | 04:33:15 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:16 INFO - PROCESS | 4630 | RSS: Main: 196210688 04:33:16 INFO - PROCESS | 4630 | 04:33:18 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:18 INFO - PROCESS | 4630 | RSS: Main: 210997248 04:33:18 INFO - PROCESS | 4630 | 04:33:21 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:21 INFO - PROCESS | 4630 | RSS: Main: 215674880 04:33:21 INFO - PROCESS | 4630 | 04:33:24 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:24 INFO - PROCESS | 4630 | RSS: Main: 210755584 04:33:24 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | Cycle 1(1): loaded http://localhost:60496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60496/tests/kraken/driver.html?testName=ai-astar) 04:33:26 INFO - PROCESS | 4630 | RSS: Main: 274808832 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | __start_tp_report 04:33:26 INFO - PROCESS | 4630 | _x_x_mozilla_page_load 04:33:26 INFO - PROCESS | 4630 | _x_x_mozilla_page_load_details 04:33:26 INFO - PROCESS | 4630 | |i|pagename|runs| 04:33:26 INFO - PROCESS | 4630 | |0;ai-astar;111;110;112;110;111;111;106;110;110;109 04:33:26 INFO - PROCESS | 4630 | |1;audio-beat-detection;155;129;160;170;129;156;158;128;182;127 04:33:26 INFO - PROCESS | 4630 | |2;audio-dft;160;161;162;162;160;161;161;160;159;161 04:33:26 INFO - PROCESS | 4630 | |3;audio-fft;81;73;76;176;77;77;76;77;157;74 04:33:26 INFO - PROCESS | 4630 | |4;audio-oscillator;91;92;151;92;92;92;151;92;91;158 04:33:26 INFO - PROCESS | 4630 | |5;imaging-gaussian-blur;117;117;117;118;118;120;117;116;117;117 04:33:26 INFO - PROCESS | 4630 | |6;imaging-darkroom;145;145;145;146;146;146;146;146;145;145 04:33:26 INFO - PROCESS | 4630 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;94;95 04:33:26 INFO - PROCESS | 4630 | |8;json-parse-financial;53;54;55;55;54;56;55;77;54;53 04:33:26 INFO - PROCESS | 4630 | |9;json-stringify-tinderbox;58;58;58;58;62;57;58;58;58;58 04:33:26 INFO - PROCESS | 4630 | |10;stanford-crypto-aes;72;72;72;71;72;73;74;81;73;72 04:33:26 INFO - PROCESS | 4630 | |11;stanford-crypto-ccm;123;117;114;111;112;119;119;114;128;116 04:33:26 INFO - PROCESS | 4630 | |12;stanford-crypto-pbkdf2;160;156;156;160;157;162;157;158;156;157 04:33:26 INFO - PROCESS | 4630 | |13;stanford-crypto-sha256-iterative;70;67;65;66;65;68;67;66;67;65 04:33:26 INFO - PROCESS | 4630 | __end_tp_report 04:33:26 INFO - PROCESS | 4630 | __start_cc_report 04:33:26 INFO - PROCESS | 4630 | _x_x_mozilla_cycle_collect,1604 04:33:26 INFO - PROCESS | 4630 | __end_cc_report 04:33:26 INFO - PROCESS | 4630 | __startTimestamp1462534406902__endTimestamp 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | ------- Summary: start ------- 04:33:26 INFO - PROCESS | 4630 | Number of tests: 14 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.50 stddev:1.63 (1.5%) stddev-sans-first:1.69 04:33:26 INFO - PROCESS | 4630 | Values: 111.0 110.0 112.0 110.0 111.0 111.0 106.0 110.0 110.0 109.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:157.00 stddev:19.83 (12.6%) stddev-sans-first:20.93 04:33:26 INFO - PROCESS | 4630 | Values: 155.0 129.0 160.0 170.0 129.0 156.0 158.0 128.0 182.0 127.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 04:33:26 INFO - PROCESS | 4630 | Values: 160.0 161.0 162.0 162.0 160.0 161.0 161.0 160.0 159.0 161.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#3] audio-fft Cycles:10 Average:94.40 Median:76.50 stddev:38.32 (50.1%) stddev-sans-first:40.34 04:33:26 INFO - PROCESS | 4630 | Values: 81.0 73.0 76.0 176.0 77.0 77.0 76.0 77.0 157.0 74.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:92.00 stddev:29.83 (32.4%) stddev-sans-first:30.82 04:33:26 INFO - PROCESS | 4630 | Values: 91.0 92.0 151.0 92.0 92.0 92.0 151.0 92.0 91.0 158.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:1.07 (0.9%) stddev-sans-first:1.13 04:33:26 INFO - PROCESS | 4630 | Values: 117.0 117.0 117.0 118.0 118.0 120.0 117.0 116.0 117.0 117.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 04:33:26 INFO - PROCESS | 4630 | Values: 145.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:33:26 INFO - PROCESS | 4630 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:55.00 stddev:7.23 (13.1%) stddev-sans-first:7.55 04:33:26 INFO - PROCESS | 4630 | Values: 53.0 54.0 55.0 55.0 54.0 56.0 55.0 77.0 54.0 53.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.41 04:33:26 INFO - PROCESS | 4630 | Values: 58.0 58.0 58.0 58.0 62.0 57.0 58.0 58.0 58.0 58.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:72.50 stddev:2.86 (3.9%) stddev-sans-first:3.00 04:33:26 INFO - PROCESS | 4630 | Values: 72.0 72.0 72.0 71.0 72.0 73.0 74.0 81.0 73.0 72.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#11] stanford-crypto-ccm Cycles:10 Average:117.30 Median:118.00 stddev:5.21 (4.4%) stddev-sans-first:5.10 04:33:26 INFO - PROCESS | 4630 | Values: 123.0 117.0 114.0 111.0 112.0 119.0 119.0 114.0 128.0 116.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:157.50 stddev:2.08 (1.3%) stddev-sans-first:2.06 04:33:26 INFO - PROCESS | 4630 | Values: 160.0 156.0 156.0 160.0 157.0 162.0 157.0 158.0 156.0 157.0 04:33:26 INFO - PROCESS | 4630 | 04:33:26 INFO - PROCESS | 4630 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.58 (2.4%) stddev-sans-first:1.09 04:33:26 INFO - PROCESS | 4630 | Values: 70.0 67.0 65.0 66.0 65.0 68.0 67.0 66.0 67.0 65.0 04:33:26 INFO - PROCESS | 4630 | -------- Summary: end -------- 04:33:26 INFO - PROCESS | 4630 | 04:33:27 INFO - TEST-INFO | 4630: exit 0 04:33:27 INFO - TEST-OK | kraken | took 60781ms 04:33:27 INFO - SUITE-END | took 1172s 04:33:28 INFO - Completed test suite (00:19:33) 04:33:28 INFO - javascript benchmark 04:33:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.4411350070486, 3302.3725492156486, 3304.173956510872, 3309.3050459403526, 3313.0, 5554.472325129112, 5560.193771903077, 5580.525655319292, 5583.0, 5594.0, 5572.442755724425, 5587.804426845051, 5594.188842617818, 5603.355614104369, 5626.746796394162, 4995.825146119886, 5065.113605119108, 5140.794368225285, 5200.68795872247, 5221.347331583552, 5009.423916249623, 5025.773840177201, 5049.747512624373, 5105.897882042357, 5149.17613181891, 5146.279520867063, 5190.195519694454, 5193.688378697271, 5198.0, 5198.09033419152, 5098.949010509885, 5116.334876466073, 5178.430372659004, 5193.896122077556, 5195.610329225323, 4889.242167464049, 5035.899282014376, 5093.668911520739, 5098.0, 5129.358830146231, 5445.264889239941, 5457.099578569531, 5469.371022332443, 5473.151661492475, 5477.643953143033, 5874.588778785486, 5913.881722365529, 5922.437368449991, 5923.970380148115, 5927.199828023205, 5793.942060579382, 5840.766369345221, 5845.795397161079, 5869.9706501467645, 5882.23530940975, 5629.324481062299, 5630.296212973338, 5630.380658127603, 5632.71836408182, 5653.378128405872, 6017.368176341509, 6073.1497590337385, 6116.816495505053, 6132.202813634288, 6149.292831324366, 5730.7421166047625, 5733.569982251348, 5736.254286942754, 5743.425657434307, 5772.538196944234, 5801.0, 5814.418558144236, 5815.41845815415, 5817.76728930838, 5823.097419899922, 5771.163181338767, 5774.480296773227, 5824.621399609012, 5908.911366329508, 5999.850003749942, 5984.102384642251, 5989.341172471112, 5991.191189189491, 5992.910106348409, 5997.880042399128, 4463.754493502824, 4539.954600454019, 4545.636349092065, 4546.340780586796, 4581.679282450196, 3978.482797236341, 3987.8205480753436, 3989.162275922031, 4004.5795191505053, 4066.02415420297, 5530.751116199781, 5537.390887002426, 5540.168974653753, 5542.39033706292, 5547.472990065938, 5798.217240672455, 5805.82582522525, 5811.535077193815, 5818.679972601463, 5838.737256823453, 5444.0, 5610.831675049755, 5616.803411880564, 5619.8595035123635, 5636.830895073154, 5081.0854046272025, 5106.821261255913, 5110.0, 5120.743962801845, 5139.665921715076, 5930.584859059911, 6125.693715314216, 6145.701690665064, 6159.630422174684, 6183.041628547582, 5967.731452084667, 5986.2517185351835, 6004.8799024020145, 6013.098035294741, 6032.0, 6117.082437634391, 6133.877322453437, 6134.4172303631085, 6137.723802428812, 6142.109394137914, 5635.859103522281, 5674.319081710221, 5812.94187058141, 5817.0, 5821.94178058214, 5934.287885453773, 5955.821325360246, 5959.046552551571, 5964.582479226413, 5998.670073146019, 5788.971055144697, 5831.979403604437, 5832.708364581838, 5834.15404766315, 5835.299764028344, 5633.3521645009705, 5790.971045144747, 5792.102224155263, 5797.768089276382, 5871.1486834409625, 5765.048766953406, 5769.192313076089, 5778.971105144616, 5791.971040144772, 5792.0443126885275, 5872.236609240771, 5894.0274854648505, 5896.55775816824, 5913.408659134052, 5917.437843404869, 5667.149927510906, 5715.828525144252, 5754.309482862083, 5806.4193580641595, 5842.065269556851], "value": 5475.841875121205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.9431398331142, 677.2888467109535, 690.5925503952667, 742.6138408027825, 760.0005992120365, 32025.35949281013, 32443.351132977357, 32453.0, 32626.0, 38547.22905541887, 42767.57232427675, 43259.56740432595, 43284.783576082074, 43312.78343608286, 43364.56635433645, 8651.653933842654, 8876.378653494257, 8887.733367998939, 8888.244499217575, 8889.377743557954, 8342.708005219818, 10710.303830251049, 11004.944975275112, 11013.118950483982, 11015.0, 10550.314229575091, 10628.46857657114, 10633.46832658368, 10639.52122154501, 10654.041136297732, 8312.8337433251, 10538.47307634619, 10625.203109766799, 10631.468426578678, 10732.302400344031, 10350.844737328945, 10509.632162874339, 10510.947445262726, 10514.0, 10516.68449946503, 38615.80692096549, 39052.0, 39055.4141687872, 39064.804675976724, 39085.0, 3922.8626998054933, 3974.5429275633383, 3980.7810570418615, 4414.757188354639, 4439.711418757771, 33210.0, 33916.321673566636, 34071.0, 34504.137396565035, 34519.82740086308, 4388.692791504596, 4445.888852778674, 4473.194824931511, 4520.638348932078, 4565.977170114162, 320123.0, 320793.0, 320853.0, 321655.0, 323131.9999999977, 39420.0, 40195.79902100471, 60140.39859601348, 60214.39785602088, 60610.39389606136, 40244.59755402408, 40284.0, 40431.19137617231, 40482.39276410856, 40583.59416405857, 17610.911945440446, 17787.822121778874, 17807.643847122985, 17811.821881781016, 17819.19813608391, 12479.75040499185, 14511.492097776527, 14722.0, 14768.852311476963, 14780.852191478163, 9254.907450925539, 9260.21288190498, 9262.675806346746, 9323.813523729623, 9346.719598412059, 3575.4636804479014, 3630.8184590770356, 3631.891043268706, 3658.8902332930056, 3661.7802931824176, 1153.4694040741354, 1175.7119505720982, 1179.1745777955293, 1198.3169593331718, 1233.5682511121036, 33792.49311260333, 33871.49192762111, 33876.49185262223, 34195.31609367799, 34389.828050859585, 1246.3954981833804, 1253.5048655780915, 1269.6127681057183, 1289.6517940156104, 1292.8642492538147, 17867.553311167067, 17929.551761205814, 18151.0, 18285.908570457064, 18303.26786928508, 446596.0, 450689.0, 450880.0, 453646.0, 455323.0, 62803.6859815698, 63038.68480657567, 63076.36923630705, 63593.68203159047, 63670.68164659147, 1371.9657008574666, 1441.7549016667192, 1496.2518740629685, 1507.8341382447923, 1536.1320853717614, 32005.6799432012, 34084.488732668535, 34767.13082172966, 34921.8253908739, 35369.82315088408, 49668.25497617466, 50058.49941500684, 50115.74942125266, 50218.748906255234, 50255.49744502654, 305971.0, 311666.0, 313034.0, 313405.0, 313646.0, 26744.59883101794, 30418.087457376314, 30593.54109688401, 31082.844585776926, 31425.214369640027, 44385.77807110944, 44885.551144488134, 44923.550764491934, 45942.770286148356, 46114.308285375075, 41572.792136040334, 42091.368629471195, 42812.143757125305, 43357.0, 43750.78124609357, 40441.19117617572, 41881.0, 41889.37165942453, 42588.0, 43036.35445468383, 42502.36246456244, 43167.35248971331, 43558.0, 44074.559254407046, 44152.77923610361, 35673.46489802707, 36711.816440917624, 37180.44229336508, 37534.8123259382, 37972.81013594914, 28369.148925532267, 28412.43175136527, 28624.85687571549, 28742.856285718437, 28869.27826804347], "value": 19641.72009644037, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.647089680117, 423.88131323229504, 425.8126424373274, 433.07106257078567, 442.637037629144, 18736.90631546842, 19124.80875191248, 19572.706409403854, 19602.803971960293, 19989.900050499764, 12243.632691019262, 12595.622131336051, 13130.737385252289, 18934.053297335147, 18940.337088201915, 5217.660852044614, 5313.8937221255555, 5346.438623944488, 5352.652077614962, 5373.194020896877, 4150.128473020669, 5756.625819321752, 5815.011448053841, 6006.609570377933, 6027.336992930774, 6002.189704389895, 6005.159277701126, 6005.909911351333, 6025.276966763994, 6029.849253768647, 5859.8535036624, 6531.0856480092825, 6533.411992920636, 6533.9872319790275, 6537.215534135934, 5820.854478638025, 5833.125031245305, 5861.824145275648, 5862.941370586283, 5872.412758724136, 13969.301534923214, 14419.279036048156, 14442.638934026629, 14450.132992020512, 14461.566153015427, 2433.683621129242, 2537.581299085649, 2555.3100662820934, 2573.125137453275, 2974.3307755755, 10340.793184136275, 10421.374717516974, 10558.841617375745, 10887.346759194394, 10894.782104357948, 1987.2419069573373, 2009.2364901337544, 2093.5812837432572, 2097.0982477534653, 2102.306238941161, 75244.0, 75610.62194689046, 78284.60857695733, 79926.6003669978, 80757.0, 11292.71768205785, 11384.886151138548, 11514.827277590843, 11520.251183673034, 11680.182387232811, 15896.602584935237, 15969.680606387807, 15975.12136832462, 15995.200239987955, 16143.112128833027, 8644.13558644143, 8658.740237792876, 8957.14907083826, 8961.731148065568, 9017.053209412918, 8600.483970961763, 8730.956345218234, 8752.212300892823, 8770.210681038738, 8924.8661270081, 4913.083897280064, 4962.305277261186, 4989.476105008995, 4992.275875815987, 5018.698878067327, 1768.942141851671, 1914.5979344337845, 2040.326692191603, 2067.077617815416, 2069.027557048185, 1022.7340891368298, 1057.381431862369, 1059.8516207730925, 1060.188955449082, 1096.8628921384827, 18011.459656210336, 18154.273829046953, 18159.909200453912, 18946.24215031411, 19823.30618428377, 1077.1382893685177, 1101.212632967416, 1109.778044391125, 1124.578283143821, 1155.5204590095184, 10606.310589811637, 10713.464326783629, 11153.553857845758, 11352.54589816398, 11386.373749443861, 362768.0, 374283.0, 376556.0, 377819.0, 383668.0, 93537.53231233801, 93583.53208233915, 94011.52994234985, 94555.52722236345, 95058.0, 1125.8536390269212, 1136.244397475686, 1196.0431654676224, 1213.8239955206445, 1214.4838443661408, 17123.0, 17374.305027798746, 17715.557111072325, 17786.199621017346, 18023.2790688371, 41082.79458602688, 41095.7945210272, 41469.585304146574, 41652.79173604234, 42602.78698606611, 58233.0, 58555.41444585499, 58576.4142358571, 58940.11589826242, 59009.0, 9038.0, 9152.267818574632, 9167.862482062908, 9382.10869967352, 9408.43549387039, 13300.401481933142, 13773.862261377259, 13913.304334783423, 13923.16461012302, 14136.222507762172, 12153.69615759613, 12210.38948052576, 12274.631761046825, 12331.445084971378, 12681.619551413472, 11738.178327516991, 11861.822072668745, 11979.161458697807, 12046.457909393923, 12278.815817762563, 12163.452644630835, 12351.444185011516, 12660.810087848506, 12877.935610321889, 13028.739425211632, 8993.370464067453, 9163.358564900393, 9185.016754427945, 9313.720588382359, 9328.300377471853, 9353.485558294358, 12407.441665125272, 12755.362231888183, 12771.233725976472, 12783.105182637126], "value": 9340.297277914104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.430395689746, 1544.2201688147488, 1551.8137823461186, 1732.445617402431, 1865.2818664814042, 8335.333173346133, 8539.188777066169, 8544.273736732384, 8592.828143437127, 8598.0, 8256.133106023872, 8435.62039708213, 8455.112213217615, 8482.363822713305, 8527.91472085279, 2098.0, 2337.275444612167, 2389.6893403857475, 2390.9043638254448, 2413.7706917842775, 2372.596658568039, 2380.523895220963, 2381.9285421437385, 2384.9046038158453, 2386.916457923973, 2334.4980829121737, 2420.6732091167733, 2423.5758742220128, 2423.8303318767694, 2424.115197952752, 2077.2521892118825, 2328.7787660172257, 2377.1442280778906, 2381.2975172324204, 2385.1413491143176, 2317.9304620861403, 2326.988365058181, 2327.94180145496, 2331.2889568681544, 2387.212219967407, 8636.82726345476, 8716.128387161296, 8738.126187381211, 8740.388172827905, 8742.169493898133, 5301.920471192935, 5372.489613486751, 5422.2951016368015, 5429.104197807357, 5438.293021907166, 3453.861845526176, 3479.0, 3509.78941263525, 3587.551556055493, 3621.130928577149, 1091.2252300866394, 1096.714854137922, 1112.0825319111766, 1127.0645364347572, 1159.4029075026367, 12329.136960412865, 12420.62738117858, 12420.62738117858, 12433.129680922339, 12447.875521244761, 4881.316615673809, 4888.315635810989, 4914.361133052752, 4926.482719314492, 4970.577500912391, 10468.842967355497, 10515.94742026285, 10522.842157367646, 10525.631602893862, 10537.473126343652, 4815.494373090775, 5418.431064738145, 5498.367687715884, 5499.092649712753, 5504.91742623861, 4912.312276281324, 4975.203967365204, 5010.398752149693, 5024.899502009939, 5045.344105266292, 4049.271131196413, 4067.247559201557, 4086.693497987663, 4140.296149654566, 4152.356384760367, 1297.9740405191844, 1309.0901823232762, 1315.3817705678316, 1317.4861803896488, 1324.5562736483387, 1008.5310330696115, 1020.132887046005, 1023.247912784103, 1024.364893765855, 1039.3400190878695, 5715.5427565794635, 5718.170865224519, 5755.3381361144, 5771.826845194651, 5834.620749651259, 933.3466573398513, 975.48299401316, 977.5210147027905, 1032.0041160280366, 1053.3627155570923, 3809.4095415210686, 3816.4084566892175, 3819.3698039823676, 3841.058940559525, 3872.5740168581433, 10246.924072972379, 10576.471176441148, 10627.574897004188, 10648.893511064945, 10661.200409969284, 10429.634962776267, 10468.581256749798, 10564.471776411148, 10781.0, 10861.945690271497, 8268.462549934235, 8359.623816928253, 8432.747017589481, 8482.618282177316, 8486.19381158789, 5568.0, 5580.581456390868, 5643.294588176478, 5731.971340143273, 5797.275340582427, 9515.952420237854, 9785.510724463891, 9820.312578119463, 9995.100440960057, 10024.899751002687, 9359.672411465566, 9796.608135674493, 9957.502124893872, 10000.249981251582, 10030.699079027641, 6987.650617469005, 7493.512921660293, 7721.961390193238, 7920.445568810128, 8065.314448271728, 8344.374171937008, 8348.749537513884, 8381.664733410595, 8414.032386275656, 8424.28393586552, 7488.775336739906, 7703.614819259126, 7720.53676779395, 7734.5359278443475, 7768.961155194188, 7442.776716698508, 7508.549487030577, 7534.39724822002, 7611.771646850603, 7638.694452221849, 7117.8576428470105, 7165.928340716526, 7252.4560657949805, 7308.415326773739, 7313.451491138079, 5116.513931176532, 5151.2273159026445, 5207.531322181022, 5239.895202096013, 5265.894682106413, 5133.998870220421, 5222.55608273301, 5243.344581927259, 5251.291075704731, 5360.2227676987395], "value": 4666.739333500723, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9181388280695, 363.3514177193709, 363.87082585682083, 369.9167687270363, 370.01206777903013, 6207.844803879905, 6393.136926514919, 6439.162908821852, 6449.322821103787, 6494.642794646293, 6243.5629505934485, 6340.4927605791545, 6347.206599175103, 6359.0, 6363.936360636392, 4617.3074038894265, 4713.316569097479, 4813.157697402958, 4842.370491836056, 4859.902801943959, 4820.132376172288, 4825.396825396825, 4834.4923783002805, 4853.466118726955, 4861.076395484864, 4749.810007599709, 4753.928691069636, 4756.952430475703, 4762.047590481918, 4775.3553270308585, 4561.247394179973, 4643.535646435364, 4699.835505757316, 4700.2479603263655, 4770.332153498513, 4120.608542188487, 4122.381642753581, 4127.855525056639, 4135.193637240739, 4135.503739551243, 5987.91018134724, 6033.577649564532, 6045.848853778647, 6049.395060493959, 6055.636661800262, 5523.889522209573, 5527.115661494142, 5532.059549876531, 5539.169124631298, 5541.639793413456, 6626.966865165691, 6627.536072474929, 6638.336166383371, 6639.070530125786, 6642.501812364092, 4474.105178964188, 4569.200389931749, 4577.542245775396, 4600.17196904557, 4632.467266264389, 6900.137482814648, 6954.3045695430055, 6959.721611135548, 6976.441884649216, 6979.092717946634, 4646.233371493733, 4648.907021859543, 4655.045715628288, 4656.464506581787, 4657.953420465819, 4718.646101542398, 4719.292106184032, 4719.882002949954, 4728.1252968201, 4729.574338309569, 5487.094629386186, 5490.862728431741, 5492.066348720727, 5501.779928802803, 5502.9174562381595, 6476.676166191766, 6632.9336706633285, 6639.502037347218, 6704.932950670528, 6718.697658605374, 1652.4133932454047, 1666.691662042526, 1679.4373884748502, 1680.126334306154, 1680.823513531086, 1076.682378698286, 1095.370162156763, 1107.983380249297, 1110.0841805510486, 1112.5994641928899, 6297.685115744286, 6410.0, 6502.252240992347, 6514.055461958084, 6560.311167327457, 6992.860142797115, 7002.7899163025195, 7005.299470052954, 7015.824604384829, 7020.368166865007, 6570.967145164243, 6571.967140164268, 6580.144581204413, 6586.407223349922, 6591.6374599397495, 3714.368557345204, 3759.548854137521, 3764.2847858907267, 3764.4729737836724, 3769.1142581493327, 6951.826204344932, 7179.317964793337, 7181.102362204724, 7226.891596626055, 7244.420446364278, 7063.752768653072, 7096.396806271525, 7111.502194846415, 7112.608806515691, 7127.857442851114, 7254.020707204565, 7255.854882902312, 7260.745873894389, 7263.055802745609, 7272.600006999666, 6983.755568555076, 6991.475639327173, 6996.580205187705, 7002.894956575554, 7007.649617519206, 6714.496412769159, 6894.276101009322, 6898.413634841095, 6905.274946130785, 6939.965300173467, 7237.421006319377, 7265.818354541178, 7275.708971641075, 7280.271972802678, 7284.0, 4716.504766999416, 4760.166970780169, 4761.381020467317, 4761.547652972962, 4768.189407800613, 6975.546589471668, 6977.79066628002, 6992.475564332593, 6994.405475534637, 7002.579845209304, 6667.066610674607, 6769.559978601375, 6793.150856142982, 6815.488838337045, 6823.931760682528, 6741.898871517029, 6756.493263005393, 6765.797026089225, 6773.898391524032, 6774.796756097325, 6662.700178492077, 6663.233728121134, 6667.233268174028, 6669.032990216295, 6675.666216689243, 6786.948023056434, 6826.795196144124, 6827.146606674166, 6830.829229269308, 6839.521233513805, 6342.873142537216, 6577.572457790228, 6623.6688165592495, 6652.401283884474, 6667.266600674019], "value": 5053.6466714734115, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.9912100878992, 916.5371487398867, 919.2645883293363, 932.6362718539771, 939.0327962198937, 15007.024543404685, 15131.31909064092, 15159.393624255044, 15173.317200725965, 15340.38638454462, 16050.678986420295, 16381.262843172057, 16431.83568164318, 16508.75236871448, 16530.752038719427, 760.8250102476434, 891.1623074310128, 891.31814162166, 908.5094049213417, 914.2092090341873, 897.824924139793, 903.0833703790657, 904.2720609909021, 906.6192199276287, 907.346710368537, 937.1940131486916, 945.6595625574788, 945.862849886766, 946.1815529566944, 946.4699768129869, 789.6407134753673, 927.2026057590467, 932.8320902237595, 945.7966537194536, 946.7917058247243, 690.5614934516568, 693.2062788107639, 693.7571849852561, 695.3637421759084, 696.3384784454788, 14858.554243372608, 14932.552023439313, 14954.40182392703, 14964.925175374163, 14986.70026599462, 12727.299998500079, 13116.409761560657, 14062.296885155702, 14337.56987290383, 14370.92814535931, 12106.152569320151, 12132.393380331036, 12987.350632468433, 13121.212727236301, 13142.751438613412, 4087.1008378156757, 4144.8756537303925, 4148.253314403406, 4182.2890108681595, 4246.044639956016, 61742.0, 61872.071918921254, 61896.69051654713, 62566.0, 62732.0, 11964.700882478008, 12077.818832717516, 12087.456064477119, 12330.19853709506, 12570.245785252913, 22178.44553886166, 22182.223622173147, 22211.66682499764, 22232.444188895406, 22285.554288914132, 9109.544522773967, 9113.40762850426, 9188.95405522968, 9214.262858971266, 9444.433333999981, 5112.897742045138, 5134.460881607452, 5158.226266060121, 5256.1327380982475, 5322.654027488201, 3696.815159242027, 3750.362438385481, 3765.548134223856, 3770.057485628593, 3771.962280377216, 635.9554831161867, 654.5876098058288, 654.9246836613755, 662.6289278004236, 678.6742363665468, 687.4775170870174, 742.7103429662436, 746.2425637977457, 759.2407592407592, 761.1741260732057, 9634.470104144199, 9658.323917325719, 9752.171065459515, 9988.500574971222, 10054.59781608742, 654.9312322206194, 730.5689643110591, 786.5595266650694, 795.347516034033, 804.4288555125809, 4482.462104547475, 4590.632749380042, 4592.7933243004145, 4648.163330600524, 4730.361401210861, 67162.32837671658, 68018.65990670114, 68157.0, 68190.0, 68198.65900670465, 57701.711491443115, 59699.0, 60379.396206037374, 60698.69650651718, 60707.39292607106, 52121.47878521242, 54607.18089228665, 54777.0, 55045.44954550403, 55704.72147639236, 9329.0, 9460.432374057578, 9477.383970041925, 9509.952450237704, 9564.856527151958, 40170.59829401669, 41144.38283425811, 41284.1743165141, 41634.79182604067, 41858.1628367437, 46813.0, 47166.29250561176, 47720.7613961928, 52791.47208528019, 53022.73488632532, 12119.757604847677, 12736.299503527025, 13013.804792927924, 13115.08194426381, 13287.335633218494, 11594.536218551164, 13745.862541374858, 14219.443626067667, 14362.856371436152, 14697.63478364277, 13230.0, 13482.93258533701, 13574.389152488358, 13596.184228946295, 13887.513937012156, 13642.522511712043, 13662.453501859814, 14065.367058482188, 14210.857891420954, 14460.349284282021, 13428.127171733806, 13603.727925441633, 13725.450981961008, 13891.444342226197, 14241.715165696834, 10594.735131621772, 10735.087517561095, 10756.515956781806, 10818.170073638401, 10949.069329106796, 10205.74485637865, 10229.232807539316, 10317.226208034277, 10342.431166285927, 10624.946875265574], "value": 7314.768487772897, "unit": "score"}], "name": "dromaeo_css", "value": 7466.915511817967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 112.0, 110.0, 111.0, 111.0, 106.0, 110.0, 110.0, 109.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 129.0, 160.0, 170.0, 129.0, 156.0, 158.0, 128.0, 182.0, 127.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 162.0, 160.0, 161.0, 161.0, 160.0, 159.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 76.0, 176.0, 77.0, 77.0, 76.0, 77.0, 157.0, 74.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 151.0, 92.0, 92.0, 92.0, 151.0, 92.0, 91.0, 158.0], "value": 110.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 118.0, 120.0, 117.0, 116.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 55.0, 54.0, 56.0, 55.0, 77.0, 54.0, 53.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 62.0, 57.0, 58.0, 58.0, 58.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 71.0, 72.0, 73.0, 74.0, 81.0, 73.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 117.0, 114.0, 111.0, 112.0, 119.0, 119.0, 114.0, 128.0, 116.0], "value": 117.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 156.0, 156.0, 160.0, 157.0, 162.0, 157.0, 158.0, 156.0, 157.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 65.0, 66.0, 65.0, 68.0, 67.0, 66.0, 67.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1512.3, "alertThreshold": 2.0}]} 04:33:28 INFO - Return code: 0 04:33:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:33:28 ERROR - # TBPL SUCCESS # 04:33:28 INFO - Running post-action listener: _resource_record_post_action 04:33:28 INFO - Running post-run listener: _resource_record_post_run 04:33:29 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 362516480; Read time: 0; Write time: 338420 04:33:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 112869376; Read time: 0; Write time: 149704 04:33:29 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 242466816; Read time: 0; Write time: 187960 04:33:29 INFO - Running post-run listener: _upload_blobber_files 04:33:29 INFO - Blob upload gear active. 04:33:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:29 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 04:33:29 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'] 04:33:29 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 04:33:29 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:30 INFO - (blobuploader) - INFO - Done attempting. 04:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:31 INFO - (blobuploader) - INFO - Done attempting. 04:33:31 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:31 INFO - Return code: 0 04:33:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/370a6dabe1153ce043f1770b7d011a88762a1b890a0a95da64c42a0344133587cf519c79bf9c7137c7c8b30063dcffea7f1a51d9b528c49eea087073e54c2575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e2ea55466667a2db4f9ed71e14685ae8127499d3bfe711b65ff6504a7791e14b63d5d395a95b2e395206014aa6b308636bce33d1b08b143b2f4443ea5e975c"} 04:33:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:31 INFO - Contents: 04:33:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/370a6dabe1153ce043f1770b7d011a88762a1b890a0a95da64c42a0344133587cf519c79bf9c7137c7c8b30063dcffea7f1a51d9b528c49eea087073e54c2575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e2ea55466667a2db4f9ed71e14685ae8127499d3bfe711b65ff6504a7791e14b63d5d395a95b2e395206014aa6b308636bce33d1b08b143b2f4443ea5e975c"} 04:33:31 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:31 INFO - Copying logs to upload dir... 04:33:31 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.720809 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-06 04:33:29.287022) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:33:29.290066) ========= 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=1861a34ebfea852630dec091000001fa-1462533029.343642-541288447 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/370a6dabe1153ce043f1770b7d011a88762a1b890a0a95da64c42a0344133587cf519c79bf9c7137c7c8b30063dcffea7f1a51d9b528c49eea087073e54c2575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e2ea55466667a2db4f9ed71e14685ae8127499d3bfe711b65ff6504a7791e14b63d5d395a95b2e395206014aa6b308636bce33d1b08b143b2f4443ea5e975c"} build_url:https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/CZULdrJzSvuOvylse2l50g/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/370a6dabe1153ce043f1770b7d011a88762a1b890a0a95da64c42a0344133587cf519c79bf9c7137c7c8b30063dcffea7f1a51d9b528c49eea087073e54c2575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e2ea55466667a2db4f9ed71e14685ae8127499d3bfe711b65ff6504a7791e14b63d5d395a95b2e395206014aa6b308636bce33d1b08b143b2f4443ea5e975c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:33:29.314458) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:33:29.314788) ========= 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=1861a34ebfea852630dec091000001fa-1462533029.343642-541288447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:33:29.364545) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:33:29.364890) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:33:29.365183) ========= ========= Total master_lag: 0.39 =========