builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1465288871.43 results: success (0) buildid: 20160606120332 builduid: 394ff36afaaf47b0a5ccdc9e3ac607e3 revision: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.432273) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.433177) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.433886) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.451258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.451959) ========= 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=523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 01:41:11-- 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% 127K=0.09s 2016-06-07 01:41:11 (127 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.205804 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.673017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.673361) ========= 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=523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014506 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.726968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:11.727477) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 --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-beta --rev 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 --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=523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 01:41:11,849 truncating revision to first 12 chars 2016-06-07 01:41:11,850 Setting DEBUG logging. 2016-06-07 01:41:11,850 attempt 1/10 2016-06-07 01:41:11,850 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/46d72a56c57d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 01:41:12,156 unpacking tar archive at: mozilla-beta-46d72a56c57d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654210 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:12.398886) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:12.399632) ========= script_repo_revision: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:12.400835) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:12.401491) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:41:12.413396) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 21 secs) (at 2016-06-07 01:41:12.413717) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:41:12 INFO - MultiFileLogger online at 20160607 01:41:12 in /builds/slave/test 01:41:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:41:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:41:12 INFO - {'append_to_log': False, 01:41:12 INFO - 'base_work_dir': '/builds/slave/test', 01:41:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:41:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:41:12 INFO - 'branch': 'Mozilla-Beta', 01:41:12 INFO - 'buildbot_json_path': 'buildprops.json', 01:41:12 INFO - 'config_files': ('talos/linux_config.py',), 01:41:12 INFO - 'default_actions': ('clobber', 01:41:12 INFO - 'read-buildbot-config', 01:41:12 INFO - 'download-and-extract', 01:41:12 INFO - 'populate-webroot', 01:41:12 INFO - 'create-virtualenv', 01:41:12 INFO - 'install', 01:41:12 INFO - 'run-tests'), 01:41:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:41:12 INFO - 'download_minidump_stackwalk': True, 01:41:12 INFO - 'download_symbols': 'ondemand', 01:41:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:41:12 INFO - 'tooltool.py': '/tools/tooltool.py', 01:41:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:41:12 INFO - '/tools/misc-python/virtualenv.py')}, 01:41:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:41:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:41:12 INFO - 'installer_path': 'installer.exe', 01:41:12 INFO - 'log_level': 'info', 01:41:12 INFO - 'log_name': 'talos', 01:41:12 INFO - 'log_to_console': True, 01:41:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:41:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:41:12 INFO - 'opt_config_files': (), 01:41:12 INFO - 'pip_index': False, 01:41:12 INFO - 'sps_profile': False, 01:41:12 INFO - 'sps_profile_interval': 0, 01:41:12 INFO - 'suite': 'dromaeojs-e10s', 01:41:12 INFO - 'system_bits': '32', 01:41:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:41:12 INFO - 'title': 'talos-linux64-ix-065', 01:41:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:41:12 INFO - 'use_talos_json': True, 01:41:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:41:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:41:12 INFO - 'work_dir': 'build'} 01:41:12 INFO - ##### 01:41:12 INFO - ##### Running clobber step. 01:41:12 INFO - ##### 01:41:12 INFO - Running pre-action listener: _resource_record_pre_action 01:41:12 INFO - Running main action method: clobber 01:41:12 INFO - rmtree: /builds/slave/test/build 01:41:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:41:13 INFO - Running post-action listener: _resource_record_post_action 01:41:13 INFO - ##### 01:41:13 INFO - ##### Running read-buildbot-config step. 01:41:13 INFO - ##### 01:41:13 INFO - Running pre-action listener: _resource_record_pre_action 01:41:13 INFO - Running main action method: read_buildbot_config 01:41:13 INFO - Using buildbot properties: 01:41:13 INFO - { 01:41:13 INFO - "project": "", 01:41:13 INFO - "product": "firefox", 01:41:13 INFO - "script_repo_revision": "production", 01:41:13 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 01:41:13 INFO - "repository": "", 01:41:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 01:41:13 INFO - "buildid": "20160606120332", 01:41:13 INFO - "pgo_build": "True", 01:41:13 INFO - "basedir": "/builds/slave/test", 01:41:13 INFO - "buildnumber": 133, 01:41:13 INFO - "slavename": "talos-linux64-ix-065", 01:41:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:41:13 INFO - "platform": "ubuntu64_hw", 01:41:13 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:41:13 INFO - "branch": "mozilla-beta", 01:41:13 INFO - "repo_path": "releases/mozilla-beta", 01:41:13 INFO - "revision": "46d72a56c57dafb4dc1061d4741a3e1181ac3d68", 01:41:13 INFO - "stage_platform": "linux64-pgo", 01:41:13 INFO - "builduid": "394ff36afaaf47b0a5ccdc9e3ac607e3", 01:41:13 INFO - "slavebuilddir": "test" 01:41:13 INFO - } 01:41:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 01:41:13 INFO - Running post-action listener: _resource_record_post_action 01:41:13 INFO - ##### 01:41:13 INFO - ##### Running download-and-extract step. 01:41:13 INFO - ##### 01:41:13 INFO - Running pre-action listener: _resource_record_pre_action 01:41:13 INFO - Running main action method: download_and_extract 01:41:13 INFO - mkdir: /builds/slave/test/build/tests 01:41:13 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')]} 01:41:13 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:41:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:41:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:41:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:41:13 INFO - Downloaded 1327 bytes. 01:41:13 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:41:13 INFO - Using the following test package requirements: 01:41:13 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 01:41:13 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:41:13 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 01:41:13 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'jsshell-linux-x86_64.zip'], 01:41:13 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:41:13 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 01:41:13 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 01:41:13 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 01:41:13 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:41:13 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:41:13 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:41:13 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:41:13 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')]} 01:41:13 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:41:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:41:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:41:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:41:14 INFO - Downloaded 24177984 bytes. 01:41:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:41:16 INFO - Return code: 0 01:41:16 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:41: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')]} 01:41:16 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:41:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:41:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:41:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:41:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:41:17 INFO - Downloaded 24177984 bytes. 01:41:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:41:18 INFO - Return code: 0 01:41:18 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')]} 01:41:18 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:41:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 01:41:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 01:41:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 01:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:41:19 INFO - Downloaded 10840145 bytes. 01:41:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 01:41:20 INFO - Return code: 0 01:41:20 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')]} 01:41:20 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:41:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:41:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:41:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:41:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:41:22 INFO - Downloaded 56008216 bytes. 01:41:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:41:22 INFO - mkdir: /builds/slave/test/properties 01:41:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:22 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:22 INFO - Contents: 01:41:22 INFO - build_url:https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:41:23 INFO - Running post-action listener: _resource_record_post_action 01:41:23 INFO - Running post-action listener: set_extra_try_arguments 01:41:23 INFO - ##### 01:41:23 INFO - ##### Running populate-webroot step. 01:41:23 INFO - ##### 01:41:23 INFO - Running pre-action listener: _resource_record_pre_action 01:41:23 INFO - Running main action method: populate_webroot 01:41:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:23 INFO - '--tppagecycles', 01:41:23 INFO - '7'], 01:41:23 INFO - 'tests': ['tsvgm']}}, 01:41:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['damp', 'tps']}, 01:41:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'tests': ['damp', 'tps']}, 01:41:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['dromaeo_dom']}, 01:41:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['a11yr', 01:41:23 INFO - 'ts_paint', 01:41:23 INFO - 'tpaint', 01:41:23 INFO - 'sessionrestore', 01:41:23 INFO - 'sessionrestore_no_auto_restore', 01:41:23 INFO - 'tabpaint']}, 01:41:23 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:23 INFO - 'ts_paint', 01:41:23 INFO - 'tpaint', 01:41:23 INFO - 'sessionrestore', 01:41:23 INFO - 'sessionrestore_no_auto_restore', 01:41:23 INFO - 'tabpaint']}, 01:41:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:23 INFO - 'ts_paint', 01:41:23 INFO - 'tpaint', 01:41:23 INFO - 'sessionrestore', 01:41:23 INFO - 'sessionrestore_no_auto_restore', 01:41:23 INFO - 'tabpaint']}, 01:41:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:23 INFO - 'ts_paint', 01:41:23 INFO - 'tpaint', 01:41:23 INFO - 'sessionrestore', 01:41:23 INFO - 'sessionrestore_no_auto_restore', 01:41:23 INFO - 'tabpaint']}, 01:41:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['a11yr', 01:41:23 INFO - 'ts_paint', 01:41:23 INFO - 'tpaint', 01:41:23 INFO - 'sessionrestore', 01:41:23 INFO - 'sessionrestore_no_auto_restore', 01:41:23 INFO - 'tabpaint']}, 01:41:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['a11yr', 01:41:23 INFO - 'ts_paint', 01:41:23 INFO - 'tpaint', 01:41:23 INFO - 'sessionrestore', 01:41:23 INFO - 'sessionrestore_no_auto_restore', 01:41:23 INFO - 'tabpaint']}, 01:41:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['tsvgx', 01:41:23 INFO - 'tsvgr_opacity', 01:41:23 INFO - 'tart', 01:41:23 INFO - 'tscrollx', 01:41:23 INFO - 'cart']}, 01:41:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:23 INFO - 'tsvgr_opacity', 01:41:23 INFO - 'tart', 01:41:23 INFO - 'tscrollx', 01:41:23 INFO - 'cart']}, 01:41:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'talos_options': ['--disable-e10s'], 01:41:23 INFO - 'tests': ['tp5o']}, 01:41:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'tests': ['tp5o']}, 01:41:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'talos_options': ['--disable-e10s', 01:41:23 INFO - '--xperf_path', 01:41:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:23 INFO - 'tests': ['tp5n']}, 01:41:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:23 INFO - 'talos_options': ['--xperf_path', 01:41:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:23 INFO - 'tests': ['tp5n']}}, 01:41:23 INFO - 'talos.zip': {'path': '', 01:41:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:23 INFO - Running post-action listener: _resource_record_post_action 01:41:23 INFO - ##### 01:41:23 INFO - ##### Running create-virtualenv step. 01:41:23 INFO - ##### 01:41:23 INFO - Running pre-action listener: _resource_record_pre_action 01:41:23 INFO - Running main action method: create_virtualenv 01:41:23 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41:23 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 01:41:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:23 INFO - Using real prefix '/usr' 01:41:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:23 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:25 INFO - Installing pip.................done. 01:41:25 INFO - Return code: 0 01:41:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41:25 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')]} 01:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:25 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')]} 01:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:25 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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:25 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 01:41:25 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 01:41:25 INFO - Using env: {'DISPLAY': ':0', 01:41:25 INFO - 'HOME': '/home/cltbld', 01:41:25 INFO - 'LANG': 'en_US.UTF-8', 01:41:25 INFO - 'LANGUAGE': 'en_US:en', 01:41:25 INFO - 'LOGNAME': 'cltbld', 01:41:25 INFO - 'MAIL': '/var/mail/cltbld', 01:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:25 INFO - 'MOZ_NO_REMOTE': '1', 01:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:25 INFO - 'NO_EM_RESTART': '1', 01:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:25 INFO - 'PWD': '/builds/slave/test', 01:41:25 INFO - 'SHELL': '/bin/bash', 01:41:25 INFO - 'SHLVL': '1', 01:41:25 INFO - 'TERM': 'linux', 01:41:25 INFO - 'TMOUT': '86400', 01:41:25 INFO - 'USER': 'cltbld', 01:41:25 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:25 INFO - '_': '/tools/buildbot/bin/python'} 01:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:26 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 01:41:26 INFO - Cleaning up... 01:41:26 INFO - Return code: 0 01:41:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:26 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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:26 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 01:41:26 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 01:41:26 INFO - Using env: {'DISPLAY': ':0', 01:41:26 INFO - 'HOME': '/home/cltbld', 01:41:26 INFO - 'LANG': 'en_US.UTF-8', 01:41:26 INFO - 'LANGUAGE': 'en_US:en', 01:41:26 INFO - 'LOGNAME': 'cltbld', 01:41:26 INFO - 'MAIL': '/var/mail/cltbld', 01:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:26 INFO - 'MOZ_NO_REMOTE': '1', 01:41:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:26 INFO - 'NO_EM_RESTART': '1', 01:41:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:26 INFO - 'PWD': '/builds/slave/test', 01:41:26 INFO - 'SHELL': '/bin/bash', 01:41:26 INFO - 'SHLVL': '1', 01:41:26 INFO - 'TERM': 'linux', 01:41:26 INFO - 'TMOUT': '86400', 01:41:26 INFO - 'USER': 'cltbld', 01:41:26 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:26 INFO - '_': '/tools/buildbot/bin/python'} 01:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:26 INFO - Downloading/unpacking psutil>=0.7.1 01:41:26 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 01:41:26 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 01:41:26 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 01:41:26 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 01:41:26 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 01:41:26 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 01:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:41:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:28 INFO - Installing collected packages: psutil 01:41:28 INFO - Running setup.py install for psutil 01:41:28 INFO - building 'psutil._psutil_linux' extension 01:41:28 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 01:41:28 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 01:41:28 INFO - building 'psutil._psutil_posix' extension 01:41:28 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 01:41:28 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 01:41:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:28 INFO - Successfully installed psutil 01:41:28 INFO - Cleaning up... 01:41:28 INFO - Return code: 0 01:41:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:28 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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:28 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 01:41:28 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 01:41:28 INFO - Using env: {'DISPLAY': ':0', 01:41:28 INFO - 'HOME': '/home/cltbld', 01:41:28 INFO - 'LANG': 'en_US.UTF-8', 01:41:28 INFO - 'LANGUAGE': 'en_US:en', 01:41:28 INFO - 'LOGNAME': 'cltbld', 01:41:28 INFO - 'MAIL': '/var/mail/cltbld', 01:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:28 INFO - 'MOZ_NO_REMOTE': '1', 01:41:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:28 INFO - 'NO_EM_RESTART': '1', 01:41:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:28 INFO - 'PWD': '/builds/slave/test', 01:41:28 INFO - 'SHELL': '/bin/bash', 01:41:28 INFO - 'SHLVL': '1', 01:41:28 INFO - 'TERM': 'linux', 01:41:28 INFO - 'TMOUT': '86400', 01:41:28 INFO - 'USER': 'cltbld', 01:41:28 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:28 INFO - '_': '/tools/buildbot/bin/python'} 01:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:29 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:41:30 INFO - Installing collected packages: mozsystemmonitor 01:41:30 INFO - Running setup.py install for mozsystemmonitor 01:41:31 INFO - Successfully installed mozsystemmonitor 01:41:31 INFO - Cleaning up... 01:41:31 INFO - Return code: 0 01:41:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:41:31 INFO - Using env: {'DISPLAY': ':0', 01:41:31 INFO - 'HOME': '/home/cltbld', 01:41:31 INFO - 'LANG': 'en_US.UTF-8', 01:41:31 INFO - 'LANGUAGE': 'en_US:en', 01:41:31 INFO - 'LOGNAME': 'cltbld', 01:41:31 INFO - 'MAIL': '/var/mail/cltbld', 01:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:31 INFO - 'MOZ_NO_REMOTE': '1', 01:41:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:31 INFO - 'NO_EM_RESTART': '1', 01:41:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:31 INFO - 'PWD': '/builds/slave/test', 01:41:31 INFO - 'SHELL': '/bin/bash', 01:41:31 INFO - 'SHLVL': '1', 01:41:31 INFO - 'TERM': 'linux', 01:41:31 INFO - 'TMOUT': '86400', 01:41:31 INFO - 'USER': 'cltbld', 01:41:31 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:31 INFO - '_': '/tools/buildbot/bin/python'} 01:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:31 INFO - Downloading/unpacking blobuploader==1.2.4 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:33 INFO - Downloading blobuploader-1.2.4.tar.gz 01:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:41:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:41:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 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 01:41:33 INFO - Downloading docopt-0.6.1.tar.gz 01:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:41:34 INFO - Installing collected packages: blobuploader, requests, docopt 01:41:34 INFO - Running setup.py install for blobuploader 01:41:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:41:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:41:34 INFO - Running setup.py install for requests 01:41:34 INFO - Running setup.py install for docopt 01:41:34 INFO - Successfully installed blobuploader requests docopt 01:41:34 INFO - Cleaning up... 01:41:34 INFO - Return code: 0 01:41:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:34 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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:34 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 01:41:34 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 01:41:34 INFO - Using env: {'DISPLAY': ':0', 01:41:34 INFO - 'HOME': '/home/cltbld', 01:41:34 INFO - 'LANG': 'en_US.UTF-8', 01:41:34 INFO - 'LANGUAGE': 'en_US:en', 01:41:34 INFO - 'LOGNAME': 'cltbld', 01:41:34 INFO - 'MAIL': '/var/mail/cltbld', 01:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:34 INFO - 'MOZ_NO_REMOTE': '1', 01:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:34 INFO - 'NO_EM_RESTART': '1', 01:41:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:34 INFO - 'PWD': '/builds/slave/test', 01:41:34 INFO - 'SHELL': '/bin/bash', 01:41:34 INFO - 'SHLVL': '1', 01:41:34 INFO - 'TERM': 'linux', 01:41:34 INFO - 'TMOUT': '86400', 01:41:34 INFO - 'USER': 'cltbld', 01:41:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:34 INFO - '_': '/tools/buildbot/bin/python'} 01:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:34 INFO - Running setup.py (path:/tmp/pip-wOA0DY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:34 INFO - Running setup.py (path:/tmp/pip-cnuoFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:34 INFO - Running setup.py (path:/tmp/pip-GXUed_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:35 INFO - Running setup.py (path:/tmp/pip-3uXwev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:35 INFO - Running setup.py (path:/tmp/pip-N0x9IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:35 INFO - Running setup.py (path:/tmp/pip-cya5Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:35 INFO - Running setup.py (path:/tmp/pip-4cIDv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:35 INFO - Running setup.py (path:/tmp/pip-w1WGj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:35 INFO - Running setup.py (path:/tmp/pip-5_FSki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:35 INFO - Running setup.py (path:/tmp/pip-9TrwsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:35 INFO - Running setup.py (path:/tmp/pip-eaiRkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:35 INFO - Running setup.py (path:/tmp/pip-1LrSq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:35 INFO - Running setup.py (path:/tmp/pip-LAyicb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:35 INFO - Running setup.py (path:/tmp/pip-f9xKUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:35 INFO - Running setup.py (path:/tmp/pip-HlX_m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:35 INFO - Running setup.py (path:/tmp/pip-DM3_H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:35 INFO - Running setup.py (path:/tmp/pip-2giAlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:41:36 INFO - Running setup.py install for manifestparser 01:41:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozcrash 01:41:36 INFO - Running setup.py install for mozdebug 01:41:36 INFO - Running setup.py install for mozdevice 01:41:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozfile 01:41:36 INFO - Running setup.py install for mozhttpd 01:41:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozinfo 01:41:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozInstall 01:41:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozleak 01:41:36 INFO - Running setup.py install for mozlog 01:41:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for moznetwork 01:41:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for mozprocess 01:41:37 INFO - Running setup.py install for mozprofile 01:41:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for mozrunner 01:41:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for mozscreenshot 01:41:37 INFO - Running setup.py install for moztest 01:41:37 INFO - Running setup.py install for mozversion 01:41:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:41:37 INFO - Cleaning up... 01:41:37 INFO - Return code: 0 01:41:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:37 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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:37 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 01:41:37 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 01:41:37 INFO - Using env: {'DISPLAY': ':0', 01:41:37 INFO - 'HOME': '/home/cltbld', 01:41:37 INFO - 'LANG': 'en_US.UTF-8', 01:41:37 INFO - 'LANGUAGE': 'en_US:en', 01:41:37 INFO - 'LOGNAME': 'cltbld', 01:41:37 INFO - 'MAIL': '/var/mail/cltbld', 01:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:37 INFO - 'MOZ_NO_REMOTE': '1', 01:41:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:37 INFO - 'NO_EM_RESTART': '1', 01:41:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:37 INFO - 'PWD': '/builds/slave/test', 01:41:37 INFO - 'SHELL': '/bin/bash', 01:41:37 INFO - 'SHLVL': '1', 01:41:37 INFO - 'TERM': 'linux', 01:41:37 INFO - 'TMOUT': '86400', 01:41:37 INFO - 'USER': 'cltbld', 01:41:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:37 INFO - '_': '/tools/buildbot/bin/python'} 01:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:38 INFO - Running setup.py (path:/tmp/pip-eUZPjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:38 INFO - Running setup.py (path:/tmp/pip-XtwIPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:38 INFO - Running setup.py (path:/tmp/pip-mnlhed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:38 INFO - Running setup.py (path:/tmp/pip-x9YgFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:38 INFO - Running setup.py (path:/tmp/pip-GPgAIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:38 INFO - Running setup.py (path:/tmp/pip-Cwk6Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:38 INFO - Running setup.py (path:/tmp/pip-2LjDa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:38 INFO - Running setup.py (path:/tmp/pip-Utv0eJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:38 INFO - Running setup.py (path:/tmp/pip-xr5gCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:38 INFO - Running setup.py (path:/tmp/pip-FYsyXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:38 INFO - Running setup.py (path:/tmp/pip-fNilF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:38 INFO - Running setup.py (path:/tmp/pip-hrEC6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:38 INFO - Running setup.py (path:/tmp/pip-5q8lWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:38 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)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:38 INFO - Running setup.py (path:/tmp/pip-DnMJGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:39 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)) 01:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:39 INFO - Running setup.py (path:/tmp/pip-Kc_J5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:39 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)) 01:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:39 INFO - Running setup.py (path:/tmp/pip-AYBVks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:39 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)) 01:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:39 INFO - Running setup.py (path:/tmp/pip-bWRFF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:39 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)) 01:41:39 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)) 01:41:39 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)) 01:41:39 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)) 01:41:39 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)) 01:41:39 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)) 01:41:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:39 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 01:41:39 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 01:41:39 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 01:41:39 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 01:41:39 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 01:41:39 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 01:41:41 INFO - Downloading blessings-1.6.tar.gz 01:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:41:41 INFO - Installing collected packages: blessings 01:41:41 INFO - Running setup.py install for blessings 01:41:41 INFO - Successfully installed blessings 01:41:41 INFO - Cleaning up... 01:41:41 INFO - Return code: 0 01:41:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:41:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:41 INFO - Reading from file tmpfile_stdout 01:41:41 INFO - Current package versions: 01:41:41 INFO - argparse == 1.2.1 01:41:41 INFO - blessings == 1.6 01:41:41 INFO - blobuploader == 1.2.4 01:41:41 INFO - docopt == 0.6.1 01:41:41 INFO - manifestparser == 1.1 01:41:41 INFO - mozInstall == 1.12 01:41:41 INFO - mozcrash == 0.17 01:41:41 INFO - mozdebug == 0.1 01:41:41 INFO - mozdevice == 0.48 01:41:41 INFO - mozfile == 1.2 01:41:41 INFO - mozhttpd == 0.7 01:41:41 INFO - mozinfo == 0.9 01:41:41 INFO - mozleak == 0.1 01:41:41 INFO - mozlog == 3.1 01:41:41 INFO - moznetwork == 0.27 01:41:41 INFO - mozprocess == 0.22 01:41:41 INFO - mozprofile == 0.28 01:41:41 INFO - mozrunner == 6.11 01:41:41 INFO - mozscreenshot == 0.1 01:41:41 INFO - mozsystemmonitor == 0.0 01:41:41 INFO - moztest == 0.7 01:41:41 INFO - mozversion == 1.4 01:41:41 INFO - psutil == 3.1.1 01:41:41 INFO - requests == 1.2.3 01:41:41 INFO - wsgiref == 0.1.2 01:41:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 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 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:41 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 01:41:41 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 01:41:41 INFO - Using env: {'DISPLAY': ':0', 01:41:41 INFO - 'HOME': '/home/cltbld', 01:41:41 INFO - 'LANG': 'en_US.UTF-8', 01:41:41 INFO - 'LANGUAGE': 'en_US:en', 01:41:41 INFO - 'LOGNAME': 'cltbld', 01:41:41 INFO - 'MAIL': '/var/mail/cltbld', 01:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:41 INFO - 'MOZ_NO_REMOTE': '1', 01:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:41 INFO - 'NO_EM_RESTART': '1', 01:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:41 INFO - 'PWD': '/builds/slave/test', 01:41:41 INFO - 'SHELL': '/bin/bash', 01:41:41 INFO - 'SHLVL': '1', 01:41:41 INFO - 'TERM': 'linux', 01:41:41 INFO - 'TMOUT': '86400', 01:41:41 INFO - 'USER': 'cltbld', 01:41:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:41 INFO - '_': '/tools/buildbot/bin/python'} 01:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 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)) 01:41:41 INFO - Cleaning up... 01:41:41 INFO - Return code: 0 01:41:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:41:41 INFO - Using env: {'DISPLAY': ':0', 01:41:41 INFO - 'HOME': '/home/cltbld', 01:41:41 INFO - 'LANG': 'en_US.UTF-8', 01:41:41 INFO - 'LANGUAGE': 'en_US:en', 01:41:41 INFO - 'LOGNAME': 'cltbld', 01:41:41 INFO - 'MAIL': '/var/mail/cltbld', 01:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:41 INFO - 'MOZ_NO_REMOTE': '1', 01:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:41 INFO - 'NO_EM_RESTART': '1', 01:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:41 INFO - 'PWD': '/builds/slave/test', 01:41:41 INFO - 'SHELL': '/bin/bash', 01:41:41 INFO - 'SHLVL': '1', 01:41:41 INFO - 'TERM': 'linux', 01:41:41 INFO - 'TMOUT': '86400', 01:41:41 INFO - 'USER': 'cltbld', 01:41:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:41 INFO - '_': '/tools/buildbot/bin/python'} 01:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:42 INFO - Downloading/unpacking jsonschema 01:41:42 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 01:41:42 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 01:41:42 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 01:41:42 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 01:41:42 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 01:41:42 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 01:41:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:41:43 INFO - Downloading/unpacking functools32 (from jsonschema) 01:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:44 INFO - Downloading functools32-3.2.3-2.tar.gz 01:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:41:44 INFO - warning: no files found matching '*.txt' 01:41:44 INFO - no previously-included directories found matching 'build' 01:41:44 INFO - no previously-included directories found matching 'dist' 01:41:44 INFO - no previously-included directories found matching '.git*' 01:41:44 INFO - Installing collected packages: jsonschema, functools32 01:41:44 INFO - Running setup.py install for functools32 01:41:44 INFO - warning: no files found matching '*.txt' 01:41:44 INFO - no previously-included directories found matching 'build' 01:41:44 INFO - no previously-included directories found matching 'dist' 01:41:44 INFO - no previously-included directories found matching '.git*' 01:41:44 INFO - Successfully installed jsonschema functools32 01:41:44 INFO - Cleaning up... 01:41:44 INFO - Return code: 0 01:41:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1befc56f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, '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': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', '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 01:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:41:44 INFO - Using env: {'DISPLAY': ':0', 01:41:44 INFO - 'HOME': '/home/cltbld', 01:41:44 INFO - 'LANG': 'en_US.UTF-8', 01:41:44 INFO - 'LANGUAGE': 'en_US:en', 01:41:44 INFO - 'LOGNAME': 'cltbld', 01:41:44 INFO - 'MAIL': '/var/mail/cltbld', 01:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:44 INFO - 'MOZ_NO_REMOTE': '1', 01:41:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:44 INFO - 'NO_EM_RESTART': '1', 01:41:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:44 INFO - 'PWD': '/builds/slave/test', 01:41:44 INFO - 'SHELL': '/bin/bash', 01:41:44 INFO - 'SHLVL': '1', 01:41:44 INFO - 'TERM': 'linux', 01:41:44 INFO - 'TMOUT': '86400', 01:41:44 INFO - 'USER': 'cltbld', 01:41:44 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:44 INFO - '_': '/tools/buildbot/bin/python'} 01:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:44 INFO - Downloading/unpacking flake8 01:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:41:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:41:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:41:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:41:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:41:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:41:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:41:47 INFO - no previously-included directories found matching 'docs/_build' 01:41:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:41:47 INFO - Running setup.py install for pep8 01:41:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:41:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:41:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:41:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:41:47 INFO - no previously-included directories found matching 'docs/_build' 01:41:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:41:47 INFO - Cleaning up... 01:41:47 INFO - Return code: 0 01:41:47 INFO - Running post-action listener: _resource_record_post_action 01:41:47 INFO - Running post-action listener: _start_resource_monitoring 01:41:47 INFO - Starting resource monitoring. 01:41:47 INFO - ##### 01:41:47 INFO - ##### Running install step. 01:41:47 INFO - ##### 01:41:47 INFO - Running pre-action listener: _resource_record_pre_action 01:41:47 INFO - Running main action method: install 01:41:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:47 INFO - Reading from file tmpfile_stdout 01:41:47 INFO - Detecting whether we're running mozinstall >=1.0... 01:41:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:41:47 INFO - Reading from file tmpfile_stdout 01:41:47 INFO - Output received: 01:41:47 INFO - Usage: mozinstall [options] installer 01:41:47 INFO - Options: 01:41:47 INFO - -h, --help show this help message and exit 01:41:47 INFO - -d DEST, --destination=DEST 01:41:47 INFO - Directory to install application into. [default: 01:41:47 INFO - "/builds/slave/test"] 01:41:47 INFO - --app=APP Application being installed. [default: firefox] 01:41:47 INFO - mkdir: /builds/slave/test/build/application 01:41:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:42:00 INFO - Reading from file tmpfile_stdout 01:42:00 INFO - Output received: 01:42:00 INFO - /builds/slave/test/build/application/firefox/firefox 01:42:00 INFO - Running post-action listener: _resource_record_post_action 01:42:00 INFO - ##### 01:42:00 INFO - ##### Running run-tests step. 01:42:00 INFO - ##### 01:42:00 INFO - Running pre-action listener: _resource_record_pre_action 01:42:00 INFO - Running main action method: run_tests 01:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:42:00 INFO - Python 2.7.3 01:42:00 INFO - Return code: 0 01:42:00 INFO - grabbing minidump binary from tooltool 01:42:00 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')]} 01:42:00 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 0x2a2c410>, '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 0x2a2d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2d7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:42:00 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 01:42:00 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 01:42:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:42:01 INFO - Return code: 0 01:42:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:42:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:42:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:42:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:42:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:42:01 INFO - Return code: 0 01:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:42:01 INFO - Using env: {'DISPLAY': ':0', 01:42:01 INFO - 'HOME': '/home/cltbld', 01:42:01 INFO - 'LANG': 'en_US.UTF-8', 01:42:01 INFO - 'LANGUAGE': 'en_US:en', 01:42:01 INFO - 'LOGNAME': 'cltbld', 01:42:01 INFO - 'MAIL': '/var/mail/cltbld', 01:42:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:42:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:01 INFO - 'MOZ_NO_REMOTE': '1', 01:42:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:42:01 INFO - 'NO_EM_RESTART': '1', 01:42:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:01 INFO - 'PWD': '/builds/slave/test', 01:42:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:42:01 INFO - 'SHELL': '/bin/bash', 01:42:01 INFO - 'SHLVL': '1', 01:42:01 INFO - 'TERM': 'linux', 01:42:01 INFO - 'TMOUT': '86400', 01:42:01 INFO - 'USER': 'cltbld', 01:42:01 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949', 01:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:01 INFO - '_': '/tools/buildbot/bin/python'} 01:42:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:42:02 INFO - mozversion application_buildid: 20160606120332 01:42:02 INFO - mozversion application_changeset: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 01:42:02 INFO - mozversion application_display_name: Firefox 01:42:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:42:02 INFO - mozversion application_name: Firefox 01:42:02 INFO - mozversion application_remotingname: firefox 01:42:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:42:02 INFO - mozversion application_vendor: Mozilla 01:42:02 INFO - mozversion application_version: 48.0 01:42:02 INFO - mozversion platform_buildid: 20160606120332 01:42:02 INFO - mozversion platform_changeset: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 01:42:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:42:02 INFO - mozversion platform_version: 48.0 01:42:02 INFO - using testdate: 1465288922 01:42:02 INFO - actual date: 1465288922 01:42:02 INFO - starting webserver on 'localhost:45110' 01:42:02 INFO - SUITE-START | Running 2 tests 01:42:02 INFO - TEST-START | dromaeo_css 01:42:02 INFO - operating with platform_type : linux_ 01:42:02 INFO - Initialising browser for dromaeo_css test... 01:42:02 INFO - TEST-INFO | started process 17679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyTIpfx/profile http://localhost:45110/getInfo.html) 01:42:05 INFO - PROCESS | 17679 | 01:42:05 INFO - PROCESS | 17679 | (process:17755): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:42:06 INFO - PROCESS | 17679 | __metrics Screen width/height:1600/1200 01:42:06 INFO - PROCESS | 17679 | colorDepth:24 01:42:06 INFO - PROCESS | 17679 | Browser inner width/height: 1024/697 01:42:06 INFO - PROCESS | 17679 | __metrics 01:42:06 ERROR - PROCESS | 17679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 01:42:07 INFO - TEST-INFO | 17679: exit 0 01:42:07 INFO - Browser initialized. 01:42:07 INFO - Running cycle 1/1 for dromaeo_css test... 01:42:07 INFO - TEST-INFO | started process 17805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyTIpfx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:42:09 INFO - PROCESS | 17805 | 01:42:09 INFO - PROCESS | 17805 | (process:17871): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:42:19 INFO - PROCESS | 17805 | RSS: Main: 151150592 01:42:19 INFO - PROCESS | 17805 | 01:43:09 INFO - PROCESS | 17805 | 1465288989121 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] 01:45:16 INFO - PROCESS | 17805 | Cycle 1(1): loaded http://localhost:45110/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45110/tests/dromaeo/cssquery-ext.html) 01:45:17 INFO - PROCESS | 17805 | RSS: Main: 155860992 01:45:17 INFO - PROCESS | 17805 | 01:48:19 INFO - PROCESS | 17805 | Cycle 1(1): loaded http://localhost:45110/tests/dromaeo/cssquery-ext.html (next: http://localhost:45110/tests/dromaeo/cssquery-jquery.html) 01:48:19 INFO - PROCESS | 17805 | RSS: Main: 153018368 01:48:19 INFO - PROCESS | 17805 | 01:51:21 INFO - PROCESS | 17805 | Cycle 1(1): loaded http://localhost:45110/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45110/tests/dromaeo/cssquery-mootools.html) 01:51:22 INFO - PROCESS | 17805 | RSS: Main: 152780800 01:51:22 INFO - PROCESS | 17805 | 01:54:23 INFO - PROCESS | 17805 | Cycle 1(1): loaded http://localhost:45110/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45110/tests/dromaeo/cssquery-prototype.html) 01:54:24 INFO - PROCESS | 17805 | RSS: Main: 154083328 01:54:24 INFO - PROCESS | 17805 | 01:57:26 INFO - PROCESS | 17805 | Cycle 1(1): loaded http://localhost:45110/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45110/tests/dromaeo/cssquery-yui.html) 01:57:27 INFO - PROCESS | 17805 | RSS: Main: 152924160 01:57:27 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | Cycle 1(1): loaded http://localhost:45110/tests/dromaeo/cssquery-yui.html (next: http://localhost:45110/tests/dromaeo/cssquery-dojo.html) 02:00:29 INFO - PROCESS | 17805 | RSS: Main: 156155904 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | __start_tp_report 02:00:29 INFO - PROCESS | 17805 | _x_x_mozilla_page_load 02:00:29 INFO - PROCESS | 17805 | _x_x_mozilla_page_load_details 02:00:29 INFO - PROCESS | 17805 | |i|pagename|runs| 02:00:29 INFO - PROCESS | 17805 | |0;dojo.html;3307.8676852925882;3361.3445378151255;3371.157210697326;3388.254583991521;3395.4397524408473;5626.043572592659;5630.577706672006;5631.211630371751;5636.2954630671165;5660.490555849972;5634.04221282382;5640.210370548127;5664.716764161796;5670.914936275959;5692.544596432286;5060.671056381333;5153;5154.123798954187;5154.871128221787;5162.915787684591;5031.345925029741;5102.515261050195;5249.186376111708;5256.973715131419;5283.365996080475;5065.670731402447;5099.796008159669;5107.336046313974;5112.182050871861;5135.691858488484;5120.92318615221;5131.7434128293435;5133.101707201225;5138.640295179339;5156.200788877712;5007.699538027694;5037.697738135724;5049.015441988814;5095.694258344474;5145.3568303962;5430.755615997289;5451.100568406208;5477.698726570037;5480.506754392084;5507.9449205507835;6005.669688167149;6006.609570377911;6008.729607167645;6009.369016253274;6011.609245399035;5882;5952.761889524457;5966.403359663999;5998.640081595118;6020.367861374581;5677.886442271173;5694.829155125353;5700.144978253254;5700.40145784695;5702.916445875312;5936.821895343147;6118.755249789959;6176.474999625082;6187.010078387437;6202.627842329474;5804.912926306109;5805.8548536286935;5873.530117590582;5886.352501224862;5895;5854.443827836349;5862.062070068854;5862.94137058624;5930.495907847795;5955.34491205967;5849.795257165979;5922.318933322723;5939.643621382731;5956.7319470623925;6023.247094113236;6022.2171117754415;6022.7289771959495;6026.7287972040485;6033.668148251801;6040.335563088057;4554.7039442436135;4573.565511276423;4574.382458368144;4574.862754117382;4592.586667199901;3980.6218409251073;4033.7176397651883;4083.89790255246;4095.692823038284;4134.979325103355;5588.608797384144;5666.97166514173;5669.319681638147;5808;5855.473007429352;5903.852403689943;5999.220101386792;6018.187544681499;6019.097135429721;6044.576879618385;5687.516561092353;5689.146628005749;5724.398938111522;5779.682117483495;5781.392953739881;5100.464451232641;5155.329807125125;5155.53600175986;5182.792688292426;5207.817726379559;6239.781607643801;6260.874782504325;6264.373562643699;6275.529335299871;6280.654563999024;5930.9110363343725;5963.671998040149;6071.150038994544;6073.423024812636;6090.056041313604;6200.56596038273;6251.99968005117;6261.436470717657;6264.812055638338;6283.214598175228;5968.403159683997;5974.37269086757;5977.3126090500145;5979.818113865703;6016.849578760566;5815.912761308668;5944.286685597756;5998.610090344288;6072.787452439144;6076.6961651916345;5823.213866128188;5858;5859.238299021271;5862.765489380377;5880.441358070976;5767.336756273083;5772.6536407815665;5821.679807610622;5822.214001109796;5854;5635.577331700221;5651.773929042793;5715.114157305624;5732.369439361751;5764.106563482667;5825.79609713658;5878.2358293421585;5880.441358071148;5894.941050589439;5923.703814809329;5798.217240672455;5798.362180160263;5819.592628515963;5820.185174075548;5827.863566604641 02:00:29 INFO - PROCESS | 17805 | |1;ext.html;606.9575129740917;649.5388274325229;656.917885264342;658.2200092889926;666.2205219892727;38533.99999999996;39879.401808972885;40038.1992360153;40087.39868901969;40181.799091004505;42498.15003699924;42977.57022429775;43396.566034339645;43400.3489947651;43463.565364346345;8691.478511289328;8847.646094156225;8852.247558957497;8853.911460885372;8869.068747781486;8724.912750872474;8759.868601970975;8816.691415800447;8829.823403531958;8860.556972151398;9113.772155696095;9149.222316103105;9151.954240228823;9176.72469825903;9179.632814687404;9015.413998090136;9015.774605634846;9035.54822258891;9046.050164732707;9060.864087038699;9114.088591140833;9195.310351723647;9204.585793639302;9226.354155209137;9232.907670923272;43772.3434148488;44327.77836110799;44395.334069988974;44462.11075778498;44637.77681111606;3853.2100919311474;4013;4120.835166593332;4277.315629499265;4551.68138230324;33500.8274710384;34034.149146271295;34154.82922585396;34232.31535369303;34288.48567271493;4612.584867361954;4632.698874573142;4636.165490211761;4640.373549570832;4682.2508398656055;334375;336321.99999999756;336616;337280;337316;58980;59156.40843591595;59379.703101484214;59404.405955940754;59565.702171489735;68387.65806170937;69027.65486172638;70766;71407.64296178486;71503.2849671507;19032.238710451704;19136.712949305773;19307.80692193088;19345.806541934682;19346.903265483583;14087.295635218403;14115.647108822157;14153.646158846112;14168.433262669378;14236.572902812932;21009;21287.0420831063;21384.893075534525;21409.46476338073;21572.676409853866;7659.595732453861;7722.884156737653;7729.07251129868;7824.726134585262;7829.45193836426;1051.2431049644244;1094.3324572011068;1109.4009235013002;1185.871107460238;1272.9180196832617;33078.3384332312;33535.329293414;33619.663803361655;34039.31921361559;34047;1244.3467179730715;1245.2901845947904;1252.2111070026008;1258.8552316483722;1405.8031875537433;17432.302707891795;17706.20322085509;17746.37887673925;17878.374256900945;17898.373556925446;471109;478097;910785;921138;945867;59653.40346596479;60129.79740405167;60271;60400;60642.393576064555;1505.819301683805;1549.5273941447967;1555.7744127101732;1565.4755656855082;1569.9372025118867;33475;34162.82918585391;34169;34404;34850.8509699523;53179.734101330796;54583;54600.45399545954;54646;54806.177907330624;417431;422297;423737;423963;424455;30908.69091309148;31332.53001204938;31341.21646958753;31384.68615313909;31398;50471.74764126156;51627;51673;51677;52226.73886630542;41921.79039104907;42270.57729422666;42459.575404246796;42552.148957020065;43647.56352436435;41539.58460415478;42334.36498452587;42615.5738442624;43192.56807431886;43369.13261734811;42599.148017040105;43168.29706528299;43692.34461483144;43913.56086439094;44080.33879491746;34155.65844341525;35160.82419587988;35447.113822153624;35567;36304;28916.710832892244;29070.854645726635;29244.70755292505;29524;29689.851550742107 02:00:29 INFO - PROCESS | 17805 | |2;jquery.html;404.4358120422011;408.95297040840313;411.7410694354187;427.10947674099504;428.06681434472836;18798.718019229706;19251.133698983544;19438.611227775455;19454.805451945474;20158.19367225309;19676;20201.19195232189;20237.595248095065;20394.286199982966;20437.897810510927;5345.786168553273;5635.943640563603;5676.574256930726;5919.052951527757;5921.319048309457;5523.28197334346;5555.777768889239;5563.082091454905;5593.972030139844;5597;5893.970530147343;6501.902471462932;6503.93496065038;6505.967470162643;6512.381323774234;5501.257330260403;6372.980323148297;6438.098666186715;6438.484921206339;6444.903326450107;5560.666360018412;5591.440855914417;5618.101103823409;5625.71871406428;5632;13327.533536326278;20655.69016464754;22585.887070564542;22727.772722272897;23133.53732925349;2451.767082127195;2461.9876900615563;2494.276659768665;2494.391117729073;2538.7207407185197;9913.90086099137;10085.193184545218;10115.7471063224;10341.534630941625;10417.32287401324;2003.889786061766;2056.311135769519;2079.0124690771763;2087;2122.5754849030254;73364.2663573368;75094.62452687701;76313.85529217121;76738;77296.22703772946;11141.94429027866;11351.829722554168;11883.58407455735;12071.094667899943;12286.255236467081;15961;16181.514554563382;16211.946223495435;16257.837421625869;16270.593235169215;7875.645595948196;8273.296769774517;8368.958155209186;8813.471191728517;8878.11218878107;8514.489130652057;8520.19058189471;8539.615717292736;8600.78498037554;8621.180987806149;4975.975120124449;5018.648694591417;5050.873728156752;5067.746612669352;5080.060188865072;2064.7728749837506;2066.276803118896;2130.712353832244;2158.78412158786;2198.2635817001483;1046.570905928581;1068.2789117345915;1086.5056399338282;1148.8793676663997;1159.4376727287427;18584.721229181574;19081.904590477236;19111.80888191128;19130.617387652448;19880.50298742514;1091.9672409827717;1114.2979922648678;1115.6764538283808;1130.3161587239765;1166.603354859335;10873.347599143919;10916.67249982486;11032.724181895517;11074.833877491845;11103.5558577656;484549;484641;485556;503413;505511;73420.63289683517;81828;83544;89358.92453187429;91121.54439227762;1084.7396624810146;1118.4966764955802;1140.3955903371061;1141.19545720267;1148.4602236948622;16740.916295418447;16798.66402671964;16894.6305694882;17043.892147010403;17153.742693859353;26423.207303780917;27040.72959270382;34524.82737586296;40687.1862562753;40854.79572602118;56055.71972140276;59312.703436482545;59532.702336489776;59758.40241597528;59969.40030599638;8764.956175219084;8822.86765698528;8871.068537803438;8992.730218093468;9037.051109633658;10176.949115254376;13739.725205495633;14105.788413174016;14227.14637121776;14301.499447519287;11706.180567360543;12290.692732681397;12361.814572781595;12496.625101246977;12606;11229.21395502307;11625.825612615987;11830.290182589073;11887.227330223508;12046.397680115784;12406;12525;12669.746605067663;12760.553380631633;12841.93579032099;8909.198172164537;9032.774180645274;9135.132162444557;9138.451692898447;9184.58669359867;12444.440000200193;12726.490940362282;12733.172343797623;12744.49022039108;12773.61679149627 02:00:29 INFO - PROCESS | 17805 | |3;mootools.html;1554.5103292462875;1578.7000469910715;1748.7726595542576;1844.5941892783583;1857.3592110721804;7520.586367749772;7886.250806173419;8398.79003024924;8598.441101328417;8626.56867156642;7976.840463190732;7994.840103197947;8001.599920004006;8026.478278911881;8031.759047228578;2316.779905908936;2344.871032093234;2345.3784747042046;2346.8474549154334;2354.446705024318;2289.2331069091874;2309.769023097685;2378.3102900158933;2384;2385.355953892448;2372.6441033844976;2379.1791831817995;2395.209580838328;2429.4290841652196;2431.1369463840347;2296.6555016747457;2297.8740417195622;2298.8505747126383;2302.804261637763;2361.8228632852433;2338.6024375856146;2355.8586484810794;2361.173589243768;2368.1593034472685;2373.7982271506944;8215.09633940266;8220.013598368174;8264.21489958453;8268.131846156128;8321.833563328699;5255.737213139366;5371.919421208685;5420.7018613976215;5435.945640543583;5446.319210098738;3428.9485657715154;3442.122258824009;3446.551948246737;3472.218750781054;3534.8939531814085;1151.4645689754304;1152.0553146419938;1170.7131752720638;1176.4000359816469;1177.8351030855688;12013.279203247834;12099.637010889688;12115.515379384815;12132.575359862363;12143.696407589792;4587.931181032287;4625.722456652612;4635.58279754817;4668.50980647034;4736.550027747358;10349.379037257788;10352.171826253882;10361.119304859021;10364.222683298784;10410.843837342445;5384.1385378340065;5412.91880621791;5427.294451721251;5432.320959880015;5456.645318054393;4807.278908163734;4911.582515486224;4940.456549779521;4966.602671786249;4982.227754698028;3815.771053736785;3857.9421308680394;3866.74866133706;3910.7262491625315;3957.109650328653;1176.8411264479357;1181.3915833345834;1188.2929656854158;1193.6359410379916;1219.2440686774257;1050.9264351495322;1057.0592172966067;1059.6820953713855;1062.9043386095288;1067.1569460126568;6017.6389416634265;6026;6098.040876101393;6127.2647282325515;6173.444390004921;486.76148687143046;494.2265354719852;494.59196163165535;497.8133200049981;502.57030239145007;4011.3581826907553;4167.1457351242925;4176.477940257468;4186.81159347824;4188.560201178832;9373.62505499786;9610.95194524023;10102.898971010196;10418.426986515815;10541.420221887716;9544.475053871965;9547;9581.377210481296;9590;9613.51932403391;7920.405969552191;7957.244061814118;8088.867558541809;8120.147384524658;8131.349492040623;5653.858653533694;5710.771569137354;5800.1299805029585;5874.882502350015;5889.676067816311;9247.213986811197;9421.057894210524;9520.666776662783;9706.514674266116;9723.756906077404;9591.952040239754;9728.075832795874;9755.512224388895;9759.658411955548;9761.658341957997;7665;7712;7737.419693523121;7908.090569584571;7915.604219789103;8368.665253389796;8368.958155209428;8400.159984001551;8428;8445.239928406472;7357.301056399633;7545.660445280086;7570.735024274124;7590.506617069873;7661.885071723817;7410.925890741024;7472.29013243741;7480.513766605153;7484.363829074589;7661.578613176329;7083.291670832876;7124.715011399693;7143.9285607143265;7144.607046612487;7147.499675022698;5118;5124.718140502308;5212.817551385683;5294.417614062373;5306.310179676618;5204.115300398866;5221.347331583552;5253.579713622825;5317.042932272228;5320.893582128413 02:00:29 INFO - PROCESS | 17805 | |4;prototype.html;348.11404974340303;355.91102224443887;359.46080878681977;363.5237838431653;366.1267876115464;6617.371349721775;6662.100616416788;6727.19273687157;6745.392914637681;6759.324067593245;6616.702248398821;6634.241925889643;6637.1703537057865;6637.269900310962;6641.634710090943;4826.589739872097;4951.455339912607;5009.198528235483;5010.4739002404585;5011.84964451067;4930.285108659242;4942.481039490855;4951.405831300231;4961;4967.4287456942375;4881.096997055538;4893.4372547157;4922.458529561745;4931.852044438672;4934.851954441372;4929.704217746928;4942.085714142877;4950.87622809429;4958.900821983576;4963.602911767086;4271.9572804271875;4292.034292284242;4294.355846623001;4294.699371044028;4295.720778149442;6396.39234272748;6396.744130234785;6400.167978162809;6409.775657851953;6413.102165696806;5695.088785794294;5781.450762177628;5793.551612096976;5793.797217097382;5794.739236734357;6968.338007889242;6984.546004509742;6995.965020174917;7010.789676309719;7017.929820701779;4751;4756.548127927841;4767.904641907143;4778.04439112179;4778.832740854054;7289.234630363789;7336.706531738724;7351.485396022281;7364.889526657051;7372.557646541171;4790.993411884589;4815.662903596714;4866.29438731389;4871.171900776876;4872.537108974641;4907.411110666742;4941.431735350408;4943.975280123576;4950.183219768698;4954.033963377107;5776.711164441761;5785.074388097968;5787.276590426197;5788.652680839163;5797.623154495029;7042.225355210922;7059.258777828254;7061.576305421691;7086.078809754699;7087.681054352566;1724.4395571439081;1763.2241813601975;1763.4357005758034;1764.5500397398837;1766.2669991953453;1105.4362275239525;1133.9546418143182;1141.8743938166795;1148.7530181011123;1161.0363398379325;6768.2216545097945;6862.279460656659;6879.724811007503;6893.517453778288;6915.204751453647;7268.055152830098;7318.377937875341;7326.963365183246;7331.74338898136;7340.632968351561;6620.702068406933;6753.594784312859;6850.486213533905;6872.278410766898;6921.48088893325;3982.1040265939932;3996.4005399189773;4007.73949693269;4022.7586344819406;4031.3952907064177;7623.275788800055;7653.387728981668;7694.615269236516;7746.535207887545;7747.457677962489;7494.138174110047;7534.886976695354;7543.924560754432;7550.395968322521;7574.621268936531;7448.106227252764;7665.846683066307;7685.03937007874;7689.231076892378;7748.7675369739;7386.335229829341;7431.702731890664;7433.776986690408;7434.553926764412;7443.590602516697;7283.8543229136185;7300.671469783766;7302.853942921218;7329.780106596811;7335.1564570075125;7622.733204337822;7767.339776118867;7777.455578109478;7778.222177782177;7784.338331241908;4906.116898958079;4946.455889852042;4953.678010929278;4953.851384458472;4956.55391014796;7367.926320736724;7383.704651813868;7389.334959853639;7406.8148296290865;7418.072740907387;7090.929090709234;7091.822704432224;7095.219525852255;7110.075690160246;7158.141023077264;6957.547759395623;7071.82320441993;7141.500094993508;7151;7167.964160179165;6978.476614254053;6985.057017302599;7025.789226323219;7026.191987921455;7034.894476582957;7184.46116541251;7201.927980720126;7220.422366210795;7228.096487939008;7236.638168091469;7055.682494287872;7056.6118863463;7058.223595404399;7070.575765454089;7077.964610176916 02:00:29 INFO - PROCESS | 17805 | |5;yui.html;829.0921441022083;830.8338332333533;845.9577021148941;875.0505701314074;899.2311573604572;14954.77567836482;15201.08793472391;15211.163386013752;15254.237288135604;15260.771088433683;14232.430702771902;14295.070820396693;14311.427542898271;14330.92834535826;14351.92360572956;865.2558799432483;873.9213470787628;911.3119594705988;912.2018234045211;920.5903372999018;907.5552979040289;908.1145882764318;911.680911680913;915.2586405011929;915.8214148241095;952.8475443928972;952.8904176019777;953.6566835939057;954.5513608603945;956.4118067388541;948.2035090523958;949.8717673114145;952.0574631115215;954.1841725324816;955.7706150523859;703.1105651351045;704.587816127566;704.7920863345325;707.225587981165;708.6988030087192;14991.475298364505;15049.849501504954;15070.924645376703;15125.319360628799;15156.1664108474;14527.78208326876;14636.487722929753;14671.926640366837;14722.190279534621;14741.189234592093;12209.755804883942;12461.501539938392;12485.625431237077;12499.250044997238;12785.296808675519;4072.5112986441522;4107.54816970133;4170.436991006206;4266.87199384019;4276.679249056333;56429.43570564324;56916.71541642307;57427;60671.393286067454;61974.38025619731;11063.557457701763;11561.248518846249;11815;11940.402979851146;11997.040236781037;20553.58892822135;20564.280250191172;20597.279095231595;20597.279095231595;20609.587808243752;8971.237444817132;9072.95463522678;9126.26989840811;9139.405938613969;9206;5158.3552055993005;5188.195829646335;5231.450697676689;5241.187615919463;5333.919991200135;3576.088097535112;3597.3165098631175;3601;3608.314420260142;3608.8736894208578;689.3830022130165;696.989545156823;701.698269744015;713.0338391479575;720.2509390234097;694.302226262613;761.432732614206;775.8370742144212;815.9428839981144;846.5682501924063;9919.273310391834;10188.6433974812;10208.642697505551;10226.488675566192;10246.333988290737;698.4203111417511;722.1911459165663;762.4853224073728;767.2940894377075;809.1261437647327;4628.444586649556;4792.281157826354;4851;4852.053849499384;4880.365552478155;62599;63547.04679429812;64162.358376416574;65119.674401627686;65833.34166658368;57843.13235301474;57873;58071.4192858066;58481.70759146177;59026.40973590295;52162.21756673653;52416.21375679368;52727.47272527226;53077;54405.72797135989;9223.123803238681;9297.07029297065;9382;9562.90437095648;9945.154661853823;39982.40026399664;40523.3921491172;40911;41571.58428415798;41738.79130604328;51800.481995179565;52142.739286303324;52721.7363913178;53006;53690.46309536855;11817.113716471471;11820.645380638594;11883.16817822744;11959;12173;14208;14405.855941440452;14406.351714172677;14468.710625787215;14512.709745805236;12633.2420054797;12890.67773305675;13231.404586793426;13910.51313204033;14284.0001199915;13088.934555327163;13163;13171.67070823237;13604.523841665494;13613.659658508617;12861.164024338388;12974.22154670685;13181.93409032987;13182.406791694588;14291.285435727965;11502.367369794742;11591.710207244549;11626;11659.358735269641;11855.110866684861;11372.374519401512;11584.362860042778;11819.23174993657;11820.113491488;11874.643760687193 02:00:29 INFO - PROCESS | 17805 | __end_tp_report 02:00:29 INFO - PROCESS | 17805 | __start_cc_report 02:00:29 INFO - PROCESS | 17805 | _x_x_mozilla_cycle_collect,314 02:00:29 INFO - PROCESS | 17805 | __end_cc_report 02:00:29 INFO - PROCESS | 17805 | __startTimestamp1465290029688__endTimestamp 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | ------- Summary: start ------- 02:00:29 INFO - PROCESS | 17805 | Number of tests: 6 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | [#0] dojo.html Cycles:165 Average:5578.15 Median:5804.91 stddev:620.08 (10.7%) stddev-sans-first:595.85 02:00:29 INFO - PROCESS | 17805 | Values: 3307.9 3361.3 3371.2 3388.3 3395.4 5626.0 5630.6 5631.2 5636.3 5660.5 5634.0 5640.2 5664.7 5670.9 5692.5 5060.7 5153.0 5154.1 5154.9 5162.9 5031.3 5102.5 5249.2 5257.0 5283.4 5065.7 5099.8 5107.3 5112.2 5135.7 5120.9 5131.7 5133.1 5138.6 5156.2 5007.7 5037.7 5049.0 5095.7 5145.4 5430.8 5451.1 5477.7 5480.5 5507.9 6005.7 6006.6 6008.7 6009.4 6011.6 5882.0 5952.8 5966.4 5998.6 6020.4 5677.9 5694.8 5700.1 5700.4 5702.9 5936.8 6118.8 6176.5 6187.0 6202.6 5804.9 5805.9 5873.5 5886.4 5895.0 5854.4 5862.1 5862.9 5930.5 5955.3 5849.8 5922.3 5939.6 5956.7 6023.2 6022.2 6022.7 6026.7 6033.7 6040.3 4554.7 4573.6 4574.4 4574.9 4592.6 3980.6 4033.7 4083.9 4095.7 4135.0 5588.6 5667.0 5669.3 5808.0 5855.5 5903.9 5999.2 6018.2 6019.1 6044.6 5687.5 5689.1 5724.4 5779.7 5781.4 5100.5 5155.3 5155.5 5182.8 5207.8 6239.8 6260.9 6264.4 6275.5 6280.7 5930.9 5963.7 6071.2 6073.4 6090.1 6200.6 6252.0 6261.4 6264.8 6283.2 5968.4 5974.4 5977.3 5979.8 6016.8 5815.9 5944.3 5998.6 6072.8 6076.7 5823.2 5858.0 5859.2 5862.8 5880.4 5767.3 5772.7 5821.7 5822.2 5854.0 5635.6 5651.8 5715.1 5732.4 5764.1 5825.8 5878.2 5880.4 5894.9 5923.7 5798.2 5798.4 5819.6 5820.2 5827.9 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | [#1] ext.html Cycles:180 Average:66447.62 Median:42607.36 stddev:147062.93 (345.2%) stddev-sans-first:147392.39 02:00:29 INFO - PROCESS | 17805 | Values: 607.0 649.5 656.9 658.2 666.2 38534.0 39879.4 40038.2 40087.4 40181.8 42498.2 42977.6 43396.6 43400.3 43463.6 8691.5 8847.6 8852.2 8853.9 8869.1 8724.9 8759.9 8816.7 8829.8 8860.6 9113.8 9149.2 9152.0 9176.7 9179.6 9015.4 9015.8 9035.5 9046.1 9060.9 9114.1 9195.3 9204.6 9226.4 9232.9 43772.3 44327.8 44395.3 44462.1 44637.8 3853.2 4013.0 4120.8 4277.3 4551.7 33500.8 34034.1 34154.8 34232.3 34288.5 4612.6 4632.7 4636.2 4640.4 4682.3 334375.0 336322.0 336616.0 337280.0 337316.0 58980.0 59156.4 59379.7 59404.4 59565.7 68387.7 69027.7 70766.0 71407.6 71503.3 19032.2 19136.7 19307.8 19345.8 19346.9 14087.3 14115.6 14153.6 14168.4 14236.6 21009.0 21287.0 21384.9 21409.5 21572.7 7659.6 7722.9 7729.1 7824.7 7829.5 1051.2 1094.3 1109.4 1185.9 1272.9 33078.3 33535.3 33619.7 34039.3 34047.0 1244.3 1245.3 1252.2 1258.9 1405.8 17432.3 17706.2 17746.4 17878.4 17898.4 471109.0 478097.0 910785.0 921138.0 945867.0 59653.4 60129.8 60271.0 60400.0 60642.4 1505.8 1549.5 1555.8 1565.5 1569.9 33475.0 34162.8 34169.0 34404.0 34850.9 53179.7 54583.0 54600.5 54646.0 54806.2 417431.0 422297.0 423737.0 423963.0 424455.0 30908.7 31332.5 31341.2 31384.7 31398.0 50471.7 51627.0 51673.0 51677.0 52226.7 41921.8 42270.6 42459.6 42552.1 43647.6 41539.6 42334.4 42615.6 43192.6 43369.1 42599.1 43168.3 43692.3 43913.6 44080.3 34155.7 35160.8 35447.1 35567.0 36304.0 28916.7 29070.9 29244.7 29524.0 29689.9 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | [#2] jquery.html Cycles:180 Average:28696.51 Median:12392.08 stddev:80937.33 (653.1%) stddev-sans-first:81136.50 02:00:29 INFO - PROCESS | 17805 | Values: 404.4 409.0 411.7 427.1 428.1 18798.7 19251.1 19438.6 19454.8 20158.2 19676.0 20201.2 20237.6 20394.3 20437.9 5345.8 5635.9 5676.6 5919.1 5921.3 5523.3 5555.8 5563.1 5594.0 5597.0 5894.0 6501.9 6503.9 6506.0 6512.4 5501.3 6373.0 6438.1 6438.5 6444.9 5560.7 5591.4 5618.1 5625.7 5632.0 13327.5 20655.7 22585.9 22727.8 23133.5 2451.8 2462.0 2494.3 2494.4 2538.7 9913.9 10085.2 10115.7 10341.5 10417.3 2003.9 2056.3 2079.0 2087.0 2122.6 73364.3 75094.6 76313.9 76738.0 77296.2 11141.9 11351.8 11883.6 12071.1 12286.3 15961.0 16181.5 16211.9 16257.8 16270.6 7875.6 8273.3 8369.0 8813.5 8878.1 8514.5 8520.2 8539.6 8600.8 8621.2 4976.0 5018.6 5050.9 5067.7 5080.1 2064.8 2066.3 2130.7 2158.8 2198.3 1046.6 1068.3 1086.5 1148.9 1159.4 18584.7 19081.9 19111.8 19130.6 19880.5 1092.0 1114.3 1115.7 1130.3 1166.6 10873.3 10916.7 11032.7 11074.8 11103.6 484549.0 484641.0 485556.0 503413.0 505511.0 73420.6 81828.0 83544.0 89358.9 91121.5 1084.7 1118.5 1140.4 1141.2 1148.5 16740.9 16798.7 16894.6 17043.9 17153.7 26423.2 27040.7 34524.8 40687.2 40854.8 56055.7 59312.7 59532.7 59758.4 59969.4 8765.0 8822.9 8871.1 8992.7 9037.1 10176.9 13739.7 14105.8 14227.1 14301.5 11706.2 12290.7 12361.8 12496.6 12606.0 11229.2 11625.8 11830.3 11887.2 12046.4 12406.0 12525.0 12669.7 12760.6 12841.9 8909.2 9032.8 9135.1 9138.5 9184.6 12444.4 12726.5 12733.2 12744.5 12773.6 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | [#3] mootools.html Cycles:180 Average:5660.88 Median:5208.47 stddev:3109.61 (59.7%) stddev-sans-first:3103.02 02:00:29 INFO - PROCESS | 17805 | Values: 1554.5 1578.7 1748.8 1844.6 1857.4 7520.6 7886.3 8398.8 8598.4 8626.6 7976.8 7994.8 8001.6 8026.5 8031.8 2316.8 2344.9 2345.4 2346.8 2354.4 2289.2 2309.8 2378.3 2384.0 2385.4 2372.6 2379.2 2395.2 2429.4 2431.1 2296.7 2297.9 2298.9 2302.8 2361.8 2338.6 2355.9 2361.2 2368.2 2373.8 8215.1 8220.0 8264.2 8268.1 8321.8 5255.7 5371.9 5420.7 5435.9 5446.3 3428.9 3442.1 3446.6 3472.2 3534.9 1151.5 1152.1 1170.7 1176.4 1177.8 12013.3 12099.6 12115.5 12132.6 12143.7 4587.9 4625.7 4635.6 4668.5 4736.6 10349.4 10352.2 10361.1 10364.2 10410.8 5384.1 5412.9 5427.3 5432.3 5456.6 4807.3 4911.6 4940.5 4966.6 4982.2 3815.8 3857.9 3866.7 3910.7 3957.1 1176.8 1181.4 1188.3 1193.6 1219.2 1050.9 1057.1 1059.7 1062.9 1067.2 6017.6 6026.0 6098.0 6127.3 6173.4 486.8 494.2 494.6 497.8 502.6 4011.4 4167.1 4176.5 4186.8 4188.6 9373.6 9611.0 10102.9 10418.4 10541.4 9544.5 9547.0 9581.4 9590.0 9613.5 7920.4 7957.2 8088.9 8120.1 8131.3 5653.9 5710.8 5800.1 5874.9 5889.7 9247.2 9421.1 9520.7 9706.5 9723.8 9592.0 9728.1 9755.5 9759.7 9761.7 7665.0 7712.0 7737.4 7908.1 7915.6 8368.7 8369.0 8400.2 8428.0 8445.2 7357.3 7545.7 7570.7 7590.5 7661.9 7410.9 7472.3 7480.5 7484.4 7661.6 7083.3 7124.7 7143.9 7144.6 7147.5 5118.0 5124.7 5212.8 5294.4 5306.3 5204.1 5221.3 5253.6 5317.0 5320.9 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | [#4] prototype.html Cycles:180 Average:5909.92 Median:6756.46 stddev:1835.88 (27.2%) stddev-sans-first:1792.94 02:00:29 INFO - PROCESS | 17805 | Values: 348.1 355.9 359.5 363.5 366.1 6617.4 6662.1 6727.2 6745.4 6759.3 6616.7 6634.2 6637.2 6637.3 6641.6 4826.6 4951.5 5009.2 5010.5 5011.8 4930.3 4942.5 4951.4 4961.0 4967.4 4881.1 4893.4 4922.5 4931.9 4934.9 4929.7 4942.1 4950.9 4958.9 4963.6 4272.0 4292.0 4294.4 4294.7 4295.7 6396.4 6396.7 6400.2 6409.8 6413.1 5695.1 5781.5 5793.6 5793.8 5794.7 6968.3 6984.5 6996.0 7010.8 7017.9 4751.0 4756.5 4767.9 4778.0 4778.8 7289.2 7336.7 7351.5 7364.9 7372.6 4791.0 4815.7 4866.3 4871.2 4872.5 4907.4 4941.4 4944.0 4950.2 4954.0 5776.7 5785.1 5787.3 5788.7 5797.6 7042.2 7059.3 7061.6 7086.1 7087.7 1724.4 1763.2 1763.4 1764.6 1766.3 1105.4 1134.0 1141.9 1148.8 1161.0 6768.2 6862.3 6879.7 6893.5 6915.2 7268.1 7318.4 7327.0 7331.7 7340.6 6620.7 6753.6 6850.5 6872.3 6921.5 3982.1 3996.4 4007.7 4022.8 4031.4 7623.3 7653.4 7694.6 7746.5 7747.5 7494.1 7534.9 7543.9 7550.4 7574.6 7448.1 7665.8 7685.0 7689.2 7748.8 7386.3 7431.7 7433.8 7434.6 7443.6 7283.9 7300.7 7302.9 7329.8 7335.2 7622.7 7767.3 7777.5 7778.2 7784.3 4906.1 4946.5 4953.7 4953.9 4956.6 7367.9 7383.7 7389.3 7406.8 7418.1 7090.9 7091.8 7095.2 7110.1 7158.1 6957.5 7071.8 7141.5 7151.0 7168.0 6978.5 6985.1 7025.8 7026.2 7034.9 7184.5 7201.9 7220.4 7228.1 7236.6 7055.7 7056.6 7058.2 7070.6 7078.0 02:00:29 INFO - PROCESS | 17805 | 02:00:29 INFO - PROCESS | 17805 | [#5] yui.html Cycles:180 Average:15985.82 Median:4821.64 stddev:18417.26 (382.0%) stddev-sans-first:18433.75 02:00:29 INFO - PROCESS | 17805 | Values: 829.1 830.8 846.0 875.1 899.2 14954.8 15201.1 15211.2 15254.2 15260.8 14232.4 14295.1 14311.4 14330.9 14351.9 865.3 873.9 911.3 912.2 920.6 907.6 908.1 911.7 915.3 915.8 952.8 952.9 953.7 954.6 956.4 948.2 949.9 952.1 954.2 955.8 703.1 704.6 704.8 707.2 708.7 14991.5 15049.8 15070.9 15125.3 15156.2 14527.8 14636.5 14671.9 14722.2 14741.2 12209.8 12461.5 12485.6 12499.3 12785.3 4072.5 4107.5 4170.4 4266.9 4276.7 56429.4 56916.7 57427.0 60671.4 61974.4 11063.6 11561.2 11815.0 11940.4 11997.0 20553.6 20564.3 20597.3 20597.3 20609.6 8971.2 9073.0 9126.3 9139.4 9206.0 5158.4 5188.2 5231.5 5241.2 5333.9 3576.1 3597.3 3601.0 3608.3 3608.9 689.4 697.0 701.7 713.0 720.3 694.3 761.4 775.8 815.9 846.6 9919.3 10188.6 10208.6 10226.5 10246.3 698.4 722.2 762.5 767.3 809.1 4628.4 4792.3 4851.0 4852.1 4880.4 62599.0 63547.0 64162.4 65119.7 65833.3 57843.1 57873.0 58071.4 58481.7 59026.4 52162.2 52416.2 52727.5 53077.0 54405.7 9223.1 9297.1 9382.0 9562.9 9945.2 39982.4 40523.4 40911.0 41571.6 41738.8 51800.5 52142.7 52721.7 53006.0 53690.5 11817.1 11820.6 11883.2 11959.0 12173.0 14208.0 14405.9 14406.4 14468.7 14512.7 12633.2 12890.7 13231.4 13910.5 14284.0 13088.9 13163.0 13171.7 13604.5 13613.7 12861.2 12974.2 13181.9 13182.4 14291.3 11502.4 11591.7 11626.0 11659.4 11855.1 11372.4 11584.4 11819.2 11820.1 11874.6 02:00:29 INFO - PROCESS | 17805 | -------- Summary: end -------- 02:00:29 INFO - PROCESS | 17805 | 02:00:30 INFO - TEST-INFO | 17805: exit 0 02:00:30 INFO - TEST-OK | dromaeo_css | took 1108168ms 02:00:30 INFO - TEST-START | kraken 02:00:30 INFO - operating with platform_type : linux_ 02:00:30 INFO - Initialising browser for kraken test... 02:00:30 INFO - TEST-INFO | started process 18048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTOp1jk/profile http://localhost:45110/getInfo.html) 02:00:33 INFO - PROCESS | 18048 | 02:00:33 INFO - PROCESS | 18048 | (process:18126): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:00:34 INFO - PROCESS | 18048 | __metrics Screen width/height:1600/1200 02:00:34 INFO - PROCESS | 18048 | colorDepth:24 02:00:34 INFO - PROCESS | 18048 | Browser inner width/height: 1024/697 02:00:34 INFO - PROCESS | 18048 | __metrics 02:00:35 ERROR - PROCESS | 18048 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 02:00:35 INFO - TEST-INFO | 18048: exit 0 02:00:35 INFO - Browser initialized. 02:00:35 INFO - Running cycle 1/1 for kraken test... 02:00:35 INFO - TEST-INFO | started process 18176 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTOp1jk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:00:37 INFO - PROCESS | 18176 | 02:00:37 INFO - PROCESS | 18176 | (process:18245): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:00:47 INFO - PROCESS | 18176 | RSS: Main: 150695936 02:00:47 INFO - PROCESS | 18176 | 02:00:50 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45110/tests/kraken/driver.html?testName=audio-beat-detection) 02:00:50 INFO - PROCESS | 18176 | RSS: Main: 156151808 02:00:50 INFO - PROCESS | 18176 | 02:00:53 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45110/tests/kraken/driver.html?testName=audio-dft) 02:00:54 INFO - PROCESS | 18176 | RSS: Main: 155897856 02:00:54 INFO - PROCESS | 18176 | 02:00:57 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45110/tests/kraken/driver.html?testName=audio-fft) 02:00:57 INFO - PROCESS | 18176 | RSS: Main: 156246016 02:00:57 INFO - PROCESS | 18176 | 02:00:59 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45110/tests/kraken/driver.html?testName=audio-oscillator) 02:01:00 INFO - PROCESS | 18176 | RSS: Main: 156233728 02:01:00 INFO - PROCESS | 18176 | 02:01:02 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45110/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:01:03 INFO - PROCESS | 18176 | RSS: Main: 156008448 02:01:03 INFO - PROCESS | 18176 | 02:01:06 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45110/tests/kraken/driver.html?testName=imaging-darkroom) 02:01:06 INFO - PROCESS | 18176 | RSS: Main: 156692480 02:01:06 INFO - PROCESS | 18176 | 02:01:10 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45110/tests/kraken/driver.html?testName=imaging-desaturate) 02:01:10 INFO - PROCESS | 18176 | RSS: Main: 156876800 02:01:10 INFO - PROCESS | 18176 | 02:01:13 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45110/tests/kraken/driver.html?testName=json-parse-financial) 02:01:14 INFO - PROCESS | 18176 | RSS: Main: 156639232 02:01:14 INFO - PROCESS | 18176 | 02:01:15 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45110/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:01:16 INFO - PROCESS | 18176 | RSS: Main: 156385280 02:01:16 INFO - PROCESS | 18176 | 02:01:18 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:01:18 INFO - PROCESS | 18176 | RSS: Main: 156577792 02:01:18 INFO - PROCESS | 18176 | 02:01:20 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:01:21 INFO - PROCESS | 18176 | RSS: Main: 156770304 02:01:21 INFO - PROCESS | 18176 | 02:01:23 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:01:24 INFO - PROCESS | 18176 | RSS: Main: 157069312 02:01:24 INFO - PROCESS | 18176 | 02:01:27 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:01:27 INFO - PROCESS | 18176 | RSS: Main: 156971008 02:01:27 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | Cycle 1(1): loaded http://localhost:45110/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45110/tests/kraken/driver.html?testName=ai-astar) 02:01:29 INFO - PROCESS | 18176 | RSS: Main: 158572544 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | __start_tp_report 02:01:29 INFO - PROCESS | 18176 | _x_x_mozilla_page_load 02:01:29 INFO - PROCESS | 18176 | _x_x_mozilla_page_load_details 02:01:29 INFO - PROCESS | 18176 | |i|pagename|runs| 02:01:29 INFO - PROCESS | 18176 | |0;ai-astar;108;111;108;111;118;111;99;109;110;110 02:01:29 INFO - PROCESS | 18176 | |1;audio-beat-detection;154;125;161;188;127;158;152;129;177;125 02:01:29 INFO - PROCESS | 18176 | |2;audio-dft;161;163;171;162;164;161;161;162;161;162 02:01:29 INFO - PROCESS | 18176 | |3;audio-fft;73;75;76;162;75;74;75;76;167;77 02:01:29 INFO - PROCESS | 18176 | |4;audio-oscillator;93;93;162;93;93;93;141;92;93;144 02:01:29 INFO - PROCESS | 18176 | |5;imaging-gaussian-blur;117;117;117;117;118;117;118;117;117;118 02:01:29 INFO - PROCESS | 18176 | |6;imaging-darkroom;146;147;146;146;146;146;146;147;147;146 02:01:29 INFO - PROCESS | 18176 | |7;imaging-desaturate;95;95;95;97;95;96;95;95;95;96 02:01:29 INFO - PROCESS | 18176 | |8;json-parse-financial;56;62;54;54;54;59;56;78;62;53 02:01:29 INFO - PROCESS | 18176 | |9;json-stringify-tinderbox;58;59;58;58;58;58;62;58;58;58 02:01:29 INFO - PROCESS | 18176 | |10;stanford-crypto-aes;74;80;73;74;74;75;75;73;74;75 02:01:29 INFO - PROCESS | 18176 | |11;stanford-crypto-ccm;120;119;113;113;115;112;111;116;128;125 02:01:29 INFO - PROCESS | 18176 | |12;stanford-crypto-pbkdf2;163;160;160;164;159;160;165;159;165;155 02:01:29 INFO - PROCESS | 18176 | |13;stanford-crypto-sha256-iterative;65;69;64;68;66;68;62;64;65;67 02:01:29 INFO - PROCESS | 18176 | __end_tp_report 02:01:29 INFO - PROCESS | 18176 | __start_cc_report 02:01:29 INFO - PROCESS | 18176 | _x_x_mozilla_cycle_collect,830 02:01:29 INFO - PROCESS | 18176 | __end_cc_report 02:01:29 INFO - PROCESS | 18176 | __startTimestamp1465290089546__endTimestamp 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | ------- Summary: start ------- 02:01:29 INFO - PROCESS | 18176 | Number of tests: 14 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:4.65 (4.2%) stddev-sans-first:4.90 02:01:29 INFO - PROCESS | 18176 | Values: 108.0 111.0 108.0 111.0 118.0 111.0 99.0 109.0 110.0 110.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#1] audio-beat-detection Cycles:10 Average:149.60 Median:156.00 stddev:22.60 (14.5%) stddev-sans-first:23.91 02:01:29 INFO - PROCESS | 18176 | Values: 154.0 125.0 161.0 188.0 127.0 158.0 152.0 129.0 177.0 125.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#2] audio-dft Cycles:10 Average:162.80 Median:162.00 stddev:3.05 (1.9%) stddev-sans-first:3.16 02:01:29 INFO - PROCESS | 18176 | Values: 161.0 163.0 171.0 162.0 164.0 161.0 161.0 162.0 161.0 162.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#3] audio-fft Cycles:10 Average:93.00 Median:75.00 stddev:37.72 (50.3%) stddev-sans-first:39.31 02:01:29 INFO - PROCESS | 18176 | Values: 73.0 75.0 76.0 162.0 75.0 74.0 75.0 76.0 167.0 77.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#4] audio-oscillator Cycles:10 Average:109.70 Median:93.00 stddev:27.64 (29.7%) stddev-sans-first:28.65 02:01:29 INFO - PROCESS | 18176 | Values: 93.0 93.0 162.0 93.0 93.0 93.0 141.0 92.0 93.0 144.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:01:29 INFO - PROCESS | 18176 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 118.0 117.0 117.0 118.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 02:01:29 INFO - PROCESS | 18176 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 02:01:29 INFO - PROCESS | 18176 | Values: 95.0 95.0 95.0 97.0 95.0 96.0 95.0 95.0 95.0 96.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:57.50 stddev:7.51 (13.1%) stddev-sans-first:7.90 02:01:29 INFO - PROCESS | 18176 | Values: 56.0 62.0 54.0 54.0 54.0 59.0 56.0 78.0 62.0 53.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.00 stddev:1.27 (2.2%) stddev-sans-first:1.33 02:01:29 INFO - PROCESS | 18176 | Values: 58.0 59.0 58.0 58.0 58.0 58.0 62.0 58.0 58.0 58.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.50 stddev:2.00 (2.7%) stddev-sans-first:2.11 02:01:29 INFO - PROCESS | 18176 | Values: 74.0 80.0 73.0 74.0 74.0 75.0 75.0 73.0 74.0 75.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:117.50 stddev:5.73 (4.9%) stddev-sans-first:5.99 02:01:29 INFO - PROCESS | 18176 | Values: 120.0 119.0 113.0 113.0 115.0 112.0 111.0 116.0 128.0 125.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.00 Median:161.50 stddev:3.20 (2.0%) stddev-sans-first:3.31 02:01:29 INFO - PROCESS | 18176 | Values: 163.0 160.0 160.0 164.0 159.0 160.0 165.0 159.0 165.0 155.0 02:01:29 INFO - PROCESS | 18176 | 02:01:29 INFO - PROCESS | 18176 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.50 stddev:2.20 (3.3%) stddev-sans-first:2.32 02:01:29 INFO - PROCESS | 18176 | Values: 65.0 69.0 64.0 68.0 66.0 68.0 62.0 64.0 65.0 67.0 02:01:29 INFO - PROCESS | 18176 | -------- Summary: end -------- 02:01:29 INFO - PROCESS | 18176 | 02:01:30 INFO - TEST-INFO | 18176: exit 0 02:01:30 INFO - TEST-OK | kraken | took 60093ms 02:01:30 INFO - SUITE-END | took 1168s 02:01:30 INFO - Completed test suite (00:19:28) 02:01:30 INFO - javascript benchmark 02:01:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3307.8676852925882, 3361.3445378151255, 3371.157210697326, 3388.254583991521, 3395.4397524408473, 5626.043572592659, 5630.577706672006, 5631.211630371751, 5636.2954630671165, 5660.490555849972, 5634.04221282382, 5640.210370548127, 5664.716764161796, 5670.914936275959, 5692.544596432286, 5060.671056381333, 5153.0, 5154.123798954187, 5154.871128221787, 5162.915787684591, 5031.345925029741, 5102.515261050195, 5249.186376111708, 5256.973715131419, 5283.365996080475, 5065.670731402447, 5099.796008159669, 5107.336046313974, 5112.182050871861, 5135.691858488484, 5120.92318615221, 5131.7434128293435, 5133.101707201225, 5138.640295179339, 5156.200788877712, 5007.699538027694, 5037.697738135724, 5049.015441988814, 5095.694258344474, 5145.3568303962, 5430.755615997289, 5451.100568406208, 5477.698726570037, 5480.506754392084, 5507.9449205507835, 6005.669688167149, 6006.609570377911, 6008.729607167645, 6009.369016253274, 6011.609245399035, 5882.0, 5952.761889524457, 5966.403359663999, 5998.640081595118, 6020.367861374581, 5677.886442271173, 5694.829155125353, 5700.144978253254, 5700.40145784695, 5702.916445875312, 5936.821895343147, 6118.755249789959, 6176.474999625082, 6187.010078387437, 6202.627842329474, 5804.912926306109, 5805.8548536286935, 5873.530117590582, 5886.352501224862, 5895.0, 5854.443827836349, 5862.062070068854, 5862.94137058624, 5930.495907847795, 5955.34491205967, 5849.795257165979, 5922.318933322723, 5939.643621382731, 5956.7319470623925, 6023.247094113236, 6022.2171117754415, 6022.7289771959495, 6026.7287972040485, 6033.668148251801, 6040.335563088057, 4554.7039442436135, 4573.565511276423, 4574.382458368144, 4574.862754117382, 4592.586667199901, 3980.6218409251073, 4033.7176397651883, 4083.89790255246, 4095.692823038284, 4134.979325103355, 5588.608797384144, 5666.97166514173, 5669.319681638147, 5808.0, 5855.473007429352, 5903.852403689943, 5999.220101386792, 6018.187544681499, 6019.097135429721, 6044.576879618385, 5687.516561092353, 5689.146628005749, 5724.398938111522, 5779.682117483495, 5781.392953739881, 5100.464451232641, 5155.329807125125, 5155.53600175986, 5182.792688292426, 5207.817726379559, 6239.781607643801, 6260.874782504325, 6264.373562643699, 6275.529335299871, 6280.654563999024, 5930.9110363343725, 5963.671998040149, 6071.150038994544, 6073.423024812636, 6090.056041313604, 6200.56596038273, 6251.99968005117, 6261.436470717657, 6264.812055638338, 6283.214598175228, 5968.403159683997, 5974.37269086757, 5977.3126090500145, 5979.818113865703, 6016.849578760566, 5815.912761308668, 5944.286685597756, 5998.610090344288, 6072.787452439144, 6076.6961651916345, 5823.213866128188, 5858.0, 5859.238299021271, 5862.765489380377, 5880.441358070976, 5767.336756273083, 5772.6536407815665, 5821.679807610622, 5822.214001109796, 5854.0, 5635.577331700221, 5651.773929042793, 5715.114157305624, 5732.369439361751, 5764.106563482667, 5825.79609713658, 5878.2358293421585, 5880.441358071148, 5894.941050589439, 5923.703814809329, 5798.217240672455, 5798.362180160263, 5819.592628515963, 5820.185174075548, 5827.863566604641], "value": 5537.652642376421, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.9575129740917, 649.5388274325229, 656.917885264342, 658.2200092889926, 666.2205219892727, 38533.99999999996, 39879.401808972885, 40038.1992360153, 40087.39868901969, 40181.799091004505, 42498.15003699924, 42977.57022429775, 43396.566034339645, 43400.3489947651, 43463.565364346345, 8691.478511289328, 8847.646094156225, 8852.247558957497, 8853.911460885372, 8869.068747781486, 8724.912750872474, 8759.868601970975, 8816.691415800447, 8829.823403531958, 8860.556972151398, 9113.772155696095, 9149.222316103105, 9151.954240228823, 9176.72469825903, 9179.632814687404, 9015.413998090136, 9015.774605634846, 9035.54822258891, 9046.050164732707, 9060.864087038699, 9114.088591140833, 9195.310351723647, 9204.585793639302, 9226.354155209137, 9232.907670923272, 43772.3434148488, 44327.77836110799, 44395.334069988974, 44462.11075778498, 44637.77681111606, 3853.2100919311474, 4013.0, 4120.835166593332, 4277.315629499265, 4551.68138230324, 33500.8274710384, 34034.149146271295, 34154.82922585396, 34232.31535369303, 34288.48567271493, 4612.584867361954, 4632.698874573142, 4636.165490211761, 4640.373549570832, 4682.2508398656055, 334375.0, 336321.99999999756, 336616.0, 337280.0, 337316.0, 58980.0, 59156.40843591595, 59379.703101484214, 59404.405955940754, 59565.702171489735, 68387.65806170937, 69027.65486172638, 70766.0, 71407.64296178486, 71503.2849671507, 19032.238710451704, 19136.712949305773, 19307.80692193088, 19345.806541934682, 19346.903265483583, 14087.295635218403, 14115.647108822157, 14153.646158846112, 14168.433262669378, 14236.572902812932, 21009.0, 21287.0420831063, 21384.893075534525, 21409.46476338073, 21572.676409853866, 7659.595732453861, 7722.884156737653, 7729.07251129868, 7824.726134585262, 7829.45193836426, 1051.2431049644244, 1094.3324572011068, 1109.4009235013002, 1185.871107460238, 1272.9180196832617, 33078.3384332312, 33535.329293414, 33619.663803361655, 34039.31921361559, 34047.0, 1244.3467179730715, 1245.2901845947904, 1252.2111070026008, 1258.8552316483722, 1405.8031875537433, 17432.302707891795, 17706.20322085509, 17746.37887673925, 17878.374256900945, 17898.373556925446, 471109.0, 478097.0, 910785.0, 921138.0, 945867.0, 59653.40346596479, 60129.79740405167, 60271.0, 60400.0, 60642.393576064555, 1505.819301683805, 1549.5273941447967, 1555.7744127101732, 1565.4755656855082, 1569.9372025118867, 33475.0, 34162.82918585391, 34169.0, 34404.0, 34850.8509699523, 53179.734101330796, 54583.0, 54600.45399545954, 54646.0, 54806.177907330624, 417431.0, 422297.0, 423737.0, 423963.0, 424455.0, 30908.69091309148, 31332.53001204938, 31341.21646958753, 31384.68615313909, 31398.0, 50471.74764126156, 51627.0, 51673.0, 51677.0, 52226.73886630542, 41921.79039104907, 42270.57729422666, 42459.575404246796, 42552.148957020065, 43647.56352436435, 41539.58460415478, 42334.36498452587, 42615.5738442624, 43192.56807431886, 43369.13261734811, 42599.148017040105, 43168.29706528299, 43692.34461483144, 43913.56086439094, 44080.33879491746, 34155.65844341525, 35160.82419587988, 35447.113822153624, 35567.0, 36304.0, 28916.710832892244, 29070.854645726635, 29244.70755292505, 29524.0, 29689.851550742107], "value": 21380.664849022312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.4358120422011, 408.95297040840313, 411.7410694354187, 427.10947674099504, 428.06681434472836, 18798.718019229706, 19251.133698983544, 19438.611227775455, 19454.805451945474, 20158.19367225309, 19676.0, 20201.19195232189, 20237.595248095065, 20394.286199982966, 20437.897810510927, 5345.786168553273, 5635.943640563603, 5676.574256930726, 5919.052951527757, 5921.319048309457, 5523.28197334346, 5555.777768889239, 5563.082091454905, 5593.972030139844, 5597.0, 5893.970530147343, 6501.902471462932, 6503.93496065038, 6505.967470162643, 6512.381323774234, 5501.257330260403, 6372.980323148297, 6438.098666186715, 6438.484921206339, 6444.903326450107, 5560.666360018412, 5591.440855914417, 5618.101103823409, 5625.71871406428, 5632.0, 13327.533536326278, 20655.69016464754, 22585.887070564542, 22727.772722272897, 23133.53732925349, 2451.767082127195, 2461.9876900615563, 2494.276659768665, 2494.391117729073, 2538.7207407185197, 9913.90086099137, 10085.193184545218, 10115.7471063224, 10341.534630941625, 10417.32287401324, 2003.889786061766, 2056.311135769519, 2079.0124690771763, 2087.0, 2122.5754849030254, 73364.2663573368, 75094.62452687701, 76313.85529217121, 76738.0, 77296.22703772946, 11141.94429027866, 11351.829722554168, 11883.58407455735, 12071.094667899943, 12286.255236467081, 15961.0, 16181.514554563382, 16211.946223495435, 16257.837421625869, 16270.593235169215, 7875.645595948196, 8273.296769774517, 8368.958155209186, 8813.471191728517, 8878.11218878107, 8514.489130652057, 8520.19058189471, 8539.615717292736, 8600.78498037554, 8621.180987806149, 4975.975120124449, 5018.648694591417, 5050.873728156752, 5067.746612669352, 5080.060188865072, 2064.7728749837506, 2066.276803118896, 2130.712353832244, 2158.78412158786, 2198.2635817001483, 1046.570905928581, 1068.2789117345915, 1086.5056399338282, 1148.8793676663997, 1159.4376727287427, 18584.721229181574, 19081.904590477236, 19111.80888191128, 19130.617387652448, 19880.50298742514, 1091.9672409827717, 1114.2979922648678, 1115.6764538283808, 1130.3161587239765, 1166.603354859335, 10873.347599143919, 10916.67249982486, 11032.724181895517, 11074.833877491845, 11103.5558577656, 484549.0, 484641.0, 485556.0, 503413.0, 505511.0, 73420.63289683517, 81828.0, 83544.0, 89358.92453187429, 91121.54439227762, 1084.7396624810146, 1118.4966764955802, 1140.3955903371061, 1141.19545720267, 1148.4602236948622, 16740.916295418447, 16798.66402671964, 16894.6305694882, 17043.892147010403, 17153.742693859353, 26423.207303780917, 27040.72959270382, 34524.82737586296, 40687.1862562753, 40854.79572602118, 56055.71972140276, 59312.703436482545, 59532.702336489776, 59758.40241597528, 59969.40030599638, 8764.956175219084, 8822.86765698528, 8871.068537803438, 8992.730218093468, 9037.051109633658, 10176.949115254376, 13739.725205495633, 14105.788413174016, 14227.14637121776, 14301.499447519287, 11706.180567360543, 12290.692732681397, 12361.814572781595, 12496.625101246977, 12606.0, 11229.21395502307, 11625.825612615987, 11830.290182589073, 11887.227330223508, 12046.397680115784, 12406.0, 12525.0, 12669.746605067663, 12760.553380631633, 12841.93579032099, 8909.198172164537, 9032.774180645274, 9135.132162444557, 9138.451692898447, 9184.58669359867, 12444.440000200193, 12726.490940362282, 12733.172343797623, 12744.49022039108, 12773.61679149627], "value": 9471.675959874743, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.5103292462875, 1578.7000469910715, 1748.7726595542576, 1844.5941892783583, 1857.3592110721804, 7520.586367749772, 7886.250806173419, 8398.79003024924, 8598.441101328417, 8626.56867156642, 7976.840463190732, 7994.840103197947, 8001.599920004006, 8026.478278911881, 8031.759047228578, 2316.779905908936, 2344.871032093234, 2345.3784747042046, 2346.8474549154334, 2354.446705024318, 2289.2331069091874, 2309.769023097685, 2378.3102900158933, 2384.0, 2385.355953892448, 2372.6441033844976, 2379.1791831817995, 2395.209580838328, 2429.4290841652196, 2431.1369463840347, 2296.6555016747457, 2297.8740417195622, 2298.8505747126383, 2302.804261637763, 2361.8228632852433, 2338.6024375856146, 2355.8586484810794, 2361.173589243768, 2368.1593034472685, 2373.7982271506944, 8215.09633940266, 8220.013598368174, 8264.21489958453, 8268.131846156128, 8321.833563328699, 5255.737213139366, 5371.919421208685, 5420.7018613976215, 5435.945640543583, 5446.319210098738, 3428.9485657715154, 3442.122258824009, 3446.551948246737, 3472.218750781054, 3534.8939531814085, 1151.4645689754304, 1152.0553146419938, 1170.7131752720638, 1176.4000359816469, 1177.8351030855688, 12013.279203247834, 12099.637010889688, 12115.515379384815, 12132.575359862363, 12143.696407589792, 4587.931181032287, 4625.722456652612, 4635.58279754817, 4668.50980647034, 4736.550027747358, 10349.379037257788, 10352.171826253882, 10361.119304859021, 10364.222683298784, 10410.843837342445, 5384.1385378340065, 5412.91880621791, 5427.294451721251, 5432.320959880015, 5456.645318054393, 4807.278908163734, 4911.582515486224, 4940.456549779521, 4966.602671786249, 4982.227754698028, 3815.771053736785, 3857.9421308680394, 3866.74866133706, 3910.7262491625315, 3957.109650328653, 1176.8411264479357, 1181.3915833345834, 1188.2929656854158, 1193.6359410379916, 1219.2440686774257, 1050.9264351495322, 1057.0592172966067, 1059.6820953713855, 1062.9043386095288, 1067.1569460126568, 6017.6389416634265, 6026.0, 6098.040876101393, 6127.2647282325515, 6173.444390004921, 486.76148687143046, 494.2265354719852, 494.59196163165535, 497.8133200049981, 502.57030239145007, 4011.3581826907553, 4167.1457351242925, 4176.477940257468, 4186.81159347824, 4188.560201178832, 9373.62505499786, 9610.95194524023, 10102.898971010196, 10418.426986515815, 10541.420221887716, 9544.475053871965, 9547.0, 9581.377210481296, 9590.0, 9613.51932403391, 7920.405969552191, 7957.244061814118, 8088.867558541809, 8120.147384524658, 8131.349492040623, 5653.858653533694, 5710.771569137354, 5800.1299805029585, 5874.882502350015, 5889.676067816311, 9247.213986811197, 9421.057894210524, 9520.666776662783, 9706.514674266116, 9723.756906077404, 9591.952040239754, 9728.075832795874, 9755.512224388895, 9759.658411955548, 9761.658341957997, 7665.0, 7712.0, 7737.419693523121, 7908.090569584571, 7915.604219789103, 8368.665253389796, 8368.958155209428, 8400.159984001551, 8428.0, 8445.239928406472, 7357.301056399633, 7545.660445280086, 7570.735024274124, 7590.506617069873, 7661.885071723817, 7410.925890741024, 7472.29013243741, 7480.513766605153, 7484.363829074589, 7661.578613176329, 7083.291670832876, 7124.715011399693, 7143.9285607143265, 7144.607046612487, 7147.499675022698, 5118.0, 5124.718140502308, 5212.817551385683, 5294.417614062373, 5306.310179676618, 5204.115300398866, 5221.347331583552, 5253.579713622825, 5317.042932272228, 5320.893582128413], "value": 4530.494350042069, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.11404974340303, 355.91102224443887, 359.46080878681977, 363.5237838431653, 366.1267876115464, 6617.371349721775, 6662.100616416788, 6727.19273687157, 6745.392914637681, 6759.324067593245, 6616.702248398821, 6634.241925889643, 6637.1703537057865, 6637.269900310962, 6641.634710090943, 4826.589739872097, 4951.455339912607, 5009.198528235483, 5010.4739002404585, 5011.84964451067, 4930.285108659242, 4942.481039490855, 4951.405831300231, 4961.0, 4967.4287456942375, 4881.096997055538, 4893.4372547157, 4922.458529561745, 4931.852044438672, 4934.851954441372, 4929.704217746928, 4942.085714142877, 4950.87622809429, 4958.900821983576, 4963.602911767086, 4271.9572804271875, 4292.034292284242, 4294.355846623001, 4294.699371044028, 4295.720778149442, 6396.39234272748, 6396.744130234785, 6400.167978162809, 6409.775657851953, 6413.102165696806, 5695.088785794294, 5781.450762177628, 5793.551612096976, 5793.797217097382, 5794.739236734357, 6968.338007889242, 6984.546004509742, 6995.965020174917, 7010.789676309719, 7017.929820701779, 4751.0, 4756.548127927841, 4767.904641907143, 4778.04439112179, 4778.832740854054, 7289.234630363789, 7336.706531738724, 7351.485396022281, 7364.889526657051, 7372.557646541171, 4790.993411884589, 4815.662903596714, 4866.29438731389, 4871.171900776876, 4872.537108974641, 4907.411110666742, 4941.431735350408, 4943.975280123576, 4950.183219768698, 4954.033963377107, 5776.711164441761, 5785.074388097968, 5787.276590426197, 5788.652680839163, 5797.623154495029, 7042.225355210922, 7059.258777828254, 7061.576305421691, 7086.078809754699, 7087.681054352566, 1724.4395571439081, 1763.2241813601975, 1763.4357005758034, 1764.5500397398837, 1766.2669991953453, 1105.4362275239525, 1133.9546418143182, 1141.8743938166795, 1148.7530181011123, 1161.0363398379325, 6768.2216545097945, 6862.279460656659, 6879.724811007503, 6893.517453778288, 6915.204751453647, 7268.055152830098, 7318.377937875341, 7326.963365183246, 7331.74338898136, 7340.632968351561, 6620.702068406933, 6753.594784312859, 6850.486213533905, 6872.278410766898, 6921.48088893325, 3982.1040265939932, 3996.4005399189773, 4007.73949693269, 4022.7586344819406, 4031.3952907064177, 7623.275788800055, 7653.387728981668, 7694.615269236516, 7746.535207887545, 7747.457677962489, 7494.138174110047, 7534.886976695354, 7543.924560754432, 7550.395968322521, 7574.621268936531, 7448.106227252764, 7665.846683066307, 7685.03937007874, 7689.231076892378, 7748.7675369739, 7386.335229829341, 7431.702731890664, 7433.776986690408, 7434.553926764412, 7443.590602516697, 7283.8543229136185, 7300.671469783766, 7302.853942921218, 7329.780106596811, 7335.1564570075125, 7622.733204337822, 7767.339776118867, 7777.455578109478, 7778.222177782177, 7784.338331241908, 4906.116898958079, 4946.455889852042, 4953.678010929278, 4953.851384458472, 4956.55391014796, 7367.926320736724, 7383.704651813868, 7389.334959853639, 7406.8148296290865, 7418.072740907387, 7090.929090709234, 7091.822704432224, 7095.219525852255, 7110.075690160246, 7158.141023077264, 6957.547759395623, 7071.82320441993, 7141.500094993508, 7151.0, 7167.964160179165, 6978.476614254053, 6985.057017302599, 7025.789226323219, 7026.191987921455, 7034.894476582957, 7184.46116541251, 7201.927980720126, 7220.422366210795, 7228.096487939008, 7236.638168091469, 7055.682494287872, 7056.6118863463, 7058.223595404399, 7070.575765454089, 7077.964610176916], "value": 5304.253900826328, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.0921441022083, 830.8338332333533, 845.9577021148941, 875.0505701314074, 899.2311573604572, 14954.77567836482, 15201.08793472391, 15211.163386013752, 15254.237288135604, 15260.771088433683, 14232.430702771902, 14295.070820396693, 14311.427542898271, 14330.92834535826, 14351.92360572956, 865.2558799432483, 873.9213470787628, 911.3119594705988, 912.2018234045211, 920.5903372999018, 907.5552979040289, 908.1145882764318, 911.680911680913, 915.2586405011929, 915.8214148241095, 952.8475443928972, 952.8904176019777, 953.6566835939057, 954.5513608603945, 956.4118067388541, 948.2035090523958, 949.8717673114145, 952.0574631115215, 954.1841725324816, 955.7706150523859, 703.1105651351045, 704.587816127566, 704.7920863345325, 707.225587981165, 708.6988030087192, 14991.475298364505, 15049.849501504954, 15070.924645376703, 15125.319360628799, 15156.1664108474, 14527.78208326876, 14636.487722929753, 14671.926640366837, 14722.190279534621, 14741.189234592093, 12209.755804883942, 12461.501539938392, 12485.625431237077, 12499.250044997238, 12785.296808675519, 4072.5112986441522, 4107.54816970133, 4170.436991006206, 4266.87199384019, 4276.679249056333, 56429.43570564324, 56916.71541642307, 57427.0, 60671.393286067454, 61974.38025619731, 11063.557457701763, 11561.248518846249, 11815.0, 11940.402979851146, 11997.040236781037, 20553.58892822135, 20564.280250191172, 20597.279095231595, 20597.279095231595, 20609.587808243752, 8971.237444817132, 9072.95463522678, 9126.26989840811, 9139.405938613969, 9206.0, 5158.3552055993005, 5188.195829646335, 5231.450697676689, 5241.187615919463, 5333.919991200135, 3576.088097535112, 3597.3165098631175, 3601.0, 3608.314420260142, 3608.8736894208578, 689.3830022130165, 696.989545156823, 701.698269744015, 713.0338391479575, 720.2509390234097, 694.302226262613, 761.432732614206, 775.8370742144212, 815.9428839981144, 846.5682501924063, 9919.273310391834, 10188.6433974812, 10208.642697505551, 10226.488675566192, 10246.333988290737, 698.4203111417511, 722.1911459165663, 762.4853224073728, 767.2940894377075, 809.1261437647327, 4628.444586649556, 4792.281157826354, 4851.0, 4852.053849499384, 4880.365552478155, 62599.0, 63547.04679429812, 64162.358376416574, 65119.674401627686, 65833.34166658368, 57843.13235301474, 57873.0, 58071.4192858066, 58481.70759146177, 59026.40973590295, 52162.21756673653, 52416.21375679368, 52727.47272527226, 53077.0, 54405.72797135989, 9223.123803238681, 9297.07029297065, 9382.0, 9562.90437095648, 9945.154661853823, 39982.40026399664, 40523.3921491172, 40911.0, 41571.58428415798, 41738.79130604328, 51800.481995179565, 52142.739286303324, 52721.7363913178, 53006.0, 53690.46309536855, 11817.113716471471, 11820.645380638594, 11883.16817822744, 11959.0, 12173.0, 14208.0, 14405.855941440452, 14406.351714172677, 14468.710625787215, 14512.709745805236, 12633.2420054797, 12890.67773305675, 13231.404586793426, 13910.51313204033, 14284.0001199915, 13088.934555327163, 13163.0, 13171.67070823237, 13604.523841665494, 13613.659658508617, 12861.164024338388, 12974.22154670685, 13181.93409032987, 13182.406791694588, 14291.285435727965, 11502.367369794742, 11591.710207244549, 11626.0, 11659.358735269641, 11855.110866684861, 11372.374519401512, 11584.362860042778, 11819.23174993657, 11820.113491488, 11874.643760687193], "value": 7306.337052568582, "unit": "score"}], "name": "dromaeo_css", "value": 7627.471253843674, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 108.0, 111.0, 118.0, 111.0, 99.0, 109.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 161.0, 188.0, 127.0, 158.0, 152.0, 129.0, 177.0, 125.0], "value": 149.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 171.0, 162.0, 164.0, 161.0, 161.0, 162.0, 161.0, 162.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 162.0, 75.0, 74.0, 75.0, 76.0, 167.0, 77.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 162.0, 93.0, 93.0, 93.0, 141.0, 92.0, 93.0, 144.0], "value": 109.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 97.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 62.0, 54.0, 54.0, 54.0, 59.0, 56.0, 78.0, 62.0, 53.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 58.0, 58.0, 58.0, 62.0, 58.0, 58.0, 58.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 73.0, 74.0, 74.0, 75.0, 75.0, 73.0, 74.0, 75.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 119.0, 113.0, 113.0, 115.0, 112.0, 111.0, 116.0, 128.0, 125.0], "value": 117.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 164.0, 159.0, 160.0, 165.0, 159.0, 165.0, 155.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 64.0, 68.0, 66.0, 68.0, 62.0, 64.0, 65.0, 67.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1519.6000000000001, "alertThreshold": 2.0}]} 02:01:30 INFO - Return code: 0 02:01:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:01:30 ERROR - # TBPL SUCCESS # 02:01:30 INFO - Running post-action listener: _resource_record_post_action 02:01:30 INFO - Running post-run listener: _resource_record_post_run 02:01:31 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 8192; Write bytes: 464867328; Read time: 24; Write time: 359972 02:01:31 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 241840128; Read time: 0; Write time: 250180 02:01:31 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 223027200; Read time: 24; Write time: 109792 02:01:31 INFO - Running post-run listener: _upload_blobber_files 02:01:31 INFO - Blob upload gear active. 02:01:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:01:31 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 02:01:31 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:01:31 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:01:32 INFO - (blobuploader) - INFO - Open directory for files ... 02:01:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:01:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:01:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:33 INFO - (blobuploader) - INFO - Done attempting. 02:01:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:01:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:01:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:33 INFO - (blobuploader) - INFO - Done attempting. 02:01:33 INFO - (blobuploader) - INFO - Iteration through files over. 02:01:33 INFO - Return code: 0 02:01:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:01:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:01:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5645c4dcfbc82612b3f0cc3b53f10cbd6d2bcd2de66f011ef61027eb913be9056bac501dd1cbc07cb8e98796a165ca34898a1a723e240cbcfaadb4fd322bc274", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa6ec30973d1af54d695de367d5c17701665acbf46c23cbc927b761f6d3c2ff578cef681f49e39ff6ccc0560942fae664228b2cdc133245d6cbcceed17bd174a"} 02:01:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:01:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:01:33 INFO - Contents: 02:01:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5645c4dcfbc82612b3f0cc3b53f10cbd6d2bcd2de66f011ef61027eb913be9056bac501dd1cbc07cb8e98796a165ca34898a1a723e240cbcfaadb4fd322bc274", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa6ec30973d1af54d695de367d5c17701665acbf46c23cbc927b761f6d3c2ff578cef681f49e39ff6ccc0560942fae664228b2cdc133245d6cbcceed17bd174a"} 02:01:33 INFO - Running post-run listener: copy_logs_to_upload_dir 02:01:33 INFO - Copying logs to upload dir... 02:01:33 INFO - mkdir: /builds/slave/test/build/upload/logs 02:01:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1221.408569 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 21 secs) (at 2016-06-07 02:01:33.827134) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 02:01:33.831054) ========= 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=523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5645c4dcfbc82612b3f0cc3b53f10cbd6d2bcd2de66f011ef61027eb913be9056bac501dd1cbc07cb8e98796a165ca34898a1a723e240cbcfaadb4fd322bc274", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa6ec30973d1af54d695de367d5c17701665acbf46c23cbc927b761f6d3c2ff578cef681f49e39ff6ccc0560942fae664228b2cdc133245d6cbcceed17bd174a"} build_url:https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5645c4dcfbc82612b3f0cc3b53f10cbd6d2bcd2de66f011ef61027eb913be9056bac501dd1cbc07cb8e98796a165ca34898a1a723e240cbcfaadb4fd322bc274", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa6ec30973d1af54d695de367d5c17701665acbf46c23cbc927b761f6d3c2ff578cef681f49e39ff6ccc0560942fae664228b2cdc133245d6cbcceed17bd174a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 02:01:33.856188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 02:01:33.856481) ========= 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=523ca41ca1e0623de333ad52000001bf-1465288870.745803-1094751949 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 02:01:33.941559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 02:01:33.941884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 02:01:33.942225) ========= ========= Total master_lag: 0.17 =========