builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-030 starttime: 1462971561.48 results: success (0) buildid: 20160511023007 builduid: 1c14df4b706649ad96711d1d155471c3 revision: 52f40a1102ad7c5bf8f463334b37595ecbc5bd73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.481431) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.481877) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.482148) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.499756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.500164) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 05:59:21-- 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% 23.0M=0.001s 2016-05-11 05:59:21 (23.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081568 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.595011) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.595338) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014119 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:21.645292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 05:59:21.648704) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 52f40a1102ad7c5bf8f463334b37595ecbc5bd73 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 52f40a1102ad7c5bf8f463334b37595ecbc5bd73 --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=4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 05:59:21,882 truncating revision to first 12 chars 2016-05-11 05:59:21,882 Setting DEBUG logging. 2016-05-11 05:59:21,882 attempt 1/10 2016-05-11 05:59:21,882 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/52f40a1102ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 05:59:22,888 unpacking tar archive at: fx-team-52f40a1102ad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.272449 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 05:59:22.935831) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:22.936161) ========= script_repo_revision: 52f40a1102ad7c5bf8f463334b37595ecbc5bd73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:22.936570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:22.936832) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 05:59:22.946973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-05-11 05:59:22.947287) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:59:23 INFO - MultiFileLogger online at 20160511 05:59:23 in /builds/slave/test 05:59:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:59:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:59:23 INFO - {'append_to_log': False, 05:59:23 INFO - 'base_work_dir': '/builds/slave/test', 05:59:23 INFO - 'blob_upload_branch': 'Fx-Team', 05:59:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:59:23 INFO - 'branch': 'Fx-Team', 05:59:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:59:23 INFO - 'config_files': ('talos/linux_config.py',), 05:59:23 INFO - 'default_actions': ('clobber', 05:59:23 INFO - 'read-buildbot-config', 05:59:23 INFO - 'download-and-extract', 05:59:23 INFO - 'populate-webroot', 05:59:23 INFO - 'create-virtualenv', 05:59:23 INFO - 'install', 05:59:23 INFO - 'run-tests'), 05:59:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:59:23 INFO - 'download_minidump_stackwalk': True, 05:59:23 INFO - 'download_symbols': 'ondemand', 05:59:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:59:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:59:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:59:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:59:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:59:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:59:23 INFO - 'installer_path': 'installer.exe', 05:59:23 INFO - 'log_level': 'info', 05:59:23 INFO - 'log_name': 'talos', 05:59:23 INFO - 'log_to_console': True, 05:59:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:59:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:59:23 INFO - 'opt_config_files': (), 05:59:23 INFO - 'pip_index': False, 05:59:23 INFO - 'sps_profile': False, 05:59:23 INFO - 'sps_profile_interval': 0, 05:59:23 INFO - 'suite': 'dromaeojs-e10s', 05:59:23 INFO - 'system_bits': '32', 05:59:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:59:23 INFO - 'title': 'talos-linux64-ix-030', 05:59:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:59:23 INFO - 'use_talos_json': True, 05:59:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:59:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:59:23 INFO - 'work_dir': 'build'} 05:59:23 INFO - ##### 05:59:23 INFO - ##### Running clobber step. 05:59:23 INFO - ##### 05:59:23 INFO - Running pre-action listener: _resource_record_pre_action 05:59:23 INFO - Running main action method: clobber 05:59:23 INFO - rmtree: /builds/slave/test/build 05:59:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:59:23 INFO - Running post-action listener: _resource_record_post_action 05:59:23 INFO - ##### 05:59:23 INFO - ##### Finished clobber step (success) 05:59:23 INFO - ##### 05:59:23 INFO - ##### 05:59:23 INFO - ##### Running read-buildbot-config step. 05:59:23 INFO - ##### 05:59:23 INFO - Running pre-action listener: _resource_record_pre_action 05:59:23 INFO - Running main action method: read_buildbot_config 05:59:23 INFO - Using buildbot properties: 05:59:23 INFO - { 05:59:23 INFO - "project": "", 05:59:23 INFO - "product": "firefox", 05:59:23 INFO - "script_repo_revision": "production", 05:59:23 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:59:23 INFO - "repository": "", 05:59:23 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 05:59:23 INFO - "buildid": "20160511023007", 05:59:23 INFO - "pgo_build": "True", 05:59:23 INFO - "basedir": "/builds/slave/test", 05:59:23 INFO - "buildnumber": 453, 05:59:23 INFO - "slavename": "talos-linux64-ix-030", 05:59:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:59:23 INFO - "platform": "ubuntu64_hw", 05:59:23 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:59:23 INFO - "branch": "fx-team", 05:59:23 INFO - "repo_path": "integration/fx-team", 05:59:23 INFO - "revision": "52f40a1102ad7c5bf8f463334b37595ecbc5bd73", 05:59:23 INFO - "stage_platform": "linux64-pgo", 05:59:23 INFO - "builduid": "1c14df4b706649ad96711d1d155471c3", 05:59:23 INFO - "slavebuilddir": "test" 05:59:23 INFO - } 05:59:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:59:23 INFO - Running post-action listener: _resource_record_post_action 05:59:23 INFO - ##### 05:59:23 INFO - ##### Finished read-buildbot-config step (success) 05:59:23 INFO - ##### 05:59:23 INFO - ##### 05:59:23 INFO - ##### Running download-and-extract step. 05:59:23 INFO - ##### 05:59:23 INFO - Running pre-action listener: _resource_record_pre_action 05:59:23 INFO - Running main action method: download_and_extract 05:59:23 INFO - mkdir: /builds/slave/test/build/tests 05:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:23 INFO - https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:59:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:59:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:59:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:59:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:59:24 INFO - Downloaded 1361 bytes. 05:59:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:59:24 INFO - Using the following test package requirements: 05:59:24 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:59:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:59:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:59:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'jsshell-linux-x86_64.zip'], 05:59:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:59:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:59:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:59:24 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:59:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:59:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:59:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:59:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:24 INFO - https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:59:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:59:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:59:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:59:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:59:25 INFO - Downloaded 23903081 bytes. 05:59:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:59:26 INFO - Return code: 0 05:59:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:59: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')]} 05:59:26 INFO - https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:59:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:59:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:59:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:59:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:59:28 INFO - Downloaded 23903081 bytes. 05:59:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:59:29 INFO - Return code: 0 05:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:29 INFO - https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:59:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:59:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:59:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:59:30 INFO - Downloaded 10840152 bytes. 05:59:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:59:31 INFO - Return code: 0 05:59: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')]} 05:59:31 INFO - https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:59:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:59:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:59:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:59:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:59:32 INFO - Downloaded 58336325 bytes. 05:59:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:59:32 INFO - mkdir: /builds/slave/test/properties 05:59:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:32 INFO - Contents: 05:59:32 INFO - build_url:https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:59:33 INFO - Running post-action listener: _resource_record_post_action 05:59:33 INFO - Running post-action listener: set_extra_try_arguments 05:59:33 INFO - ##### 05:59:33 INFO - ##### Finished download-and-extract step (success) 05:59:33 INFO - ##### 05:59:33 INFO - ##### 05:59:33 INFO - ##### Running populate-webroot step. 05:59:33 INFO - ##### 05:59:33 INFO - Running pre-action listener: _resource_record_pre_action 05:59:33 INFO - Running main action method: populate_webroot 05:59:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:33 INFO - '--tppagecycles', 05:59:33 INFO - '7'], 05:59:33 INFO - 'tests': ['tsvgm']}}, 05:59:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:59:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:59:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:59:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['damp', 'tps']}, 05:59:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'tests': ['damp', 'tps']}, 05:59:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['dromaeo_dom']}, 05:59:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['a11yr', 05:59:33 INFO - 'ts_paint', 05:59:33 INFO - 'tpaint', 05:59:33 INFO - 'sessionrestore', 05:59:33 INFO - 'sessionrestore_no_auto_restore', 05:59:33 INFO - 'tabpaint']}, 05:59:33 INFO - 'other-e10s': {'tests': ['a11yr', 05:59:33 INFO - 'ts_paint', 05:59:33 INFO - 'tpaint', 05:59:33 INFO - 'sessionrestore', 05:59:33 INFO - 'sessionrestore_no_auto_restore', 05:59:33 INFO - 'tabpaint']}, 05:59:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:59:33 INFO - 'ts_paint', 05:59:33 INFO - 'tpaint', 05:59:33 INFO - 'sessionrestore', 05:59:33 INFO - 'sessionrestore_no_auto_restore', 05:59:33 INFO - 'tabpaint']}, 05:59:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:59:33 INFO - 'ts_paint', 05:59:33 INFO - 'tpaint', 05:59:33 INFO - 'sessionrestore', 05:59:33 INFO - 'sessionrestore_no_auto_restore', 05:59:33 INFO - 'tabpaint']}, 05:59:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['a11yr', 05:59:33 INFO - 'ts_paint', 05:59:33 INFO - 'tpaint', 05:59:33 INFO - 'sessionrestore', 05:59:33 INFO - 'sessionrestore_no_auto_restore', 05:59:33 INFO - 'tabpaint']}, 05:59:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['a11yr', 05:59:33 INFO - 'ts_paint', 05:59:33 INFO - 'tpaint', 05:59:33 INFO - 'sessionrestore', 05:59:33 INFO - 'sessionrestore_no_auto_restore', 05:59:33 INFO - 'tabpaint']}, 05:59:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['tsvgx', 05:59:33 INFO - 'tsvgr_opacity', 05:59:33 INFO - 'tart', 05:59:33 INFO - 'tscrollx', 05:59:33 INFO - 'cart']}, 05:59:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:59:33 INFO - 'tsvgr_opacity', 05:59:33 INFO - 'tart', 05:59:33 INFO - 'tscrollx', 05:59:33 INFO - 'cart']}, 05:59:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'talos_options': ['--disable-e10s'], 05:59:33 INFO - 'tests': ['tp5o']}, 05:59:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'tests': ['tp5o']}, 05:59:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'talos_options': ['--disable-e10s', 05:59:33 INFO - '--xperf_path', 05:59:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:33 INFO - 'tests': ['tp5n']}, 05:59:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:33 INFO - 'talos_options': ['--xperf_path', 05:59:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:33 INFO - 'tests': ['tp5n']}}, 05:59:33 INFO - 'talos.zip': {'path': '', 05:59:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:33 INFO - Running post-action listener: _resource_record_post_action 05:59:33 INFO - ##### 05:59:33 INFO - ##### Finished populate-webroot step (success) 05:59:33 INFO - ##### 05:59:33 INFO - ##### 05:59:33 INFO - ##### Running create-virtualenv step. 05:59:33 INFO - ##### 05:59:33 INFO - Running pre-action listener: _resource_record_pre_action 05:59:33 INFO - Running main action method: create_virtualenv 05:59:33 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:33 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 05:59:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:33 INFO - Using real prefix '/usr' 05:59:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:33 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:35 INFO - Installing pip.................done. 05:59:35 INFO - Return code: 0 05:59:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:35 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 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:35 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 05:59:35 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 05:59:35 INFO - Using env: {'DISPLAY': ':0', 05:59:35 INFO - 'HOME': '/home/cltbld', 05:59:35 INFO - 'LANG': 'en_US.UTF-8', 05:59:35 INFO - 'LANGUAGE': 'en_US:en', 05:59:35 INFO - 'LOGNAME': 'cltbld', 05:59:35 INFO - 'MAIL': '/var/mail/cltbld', 05:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:35 INFO - 'MOZ_NO_REMOTE': '1', 05:59:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:35 INFO - 'NO_EM_RESTART': '1', 05:59:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:35 INFO - 'PWD': '/builds/slave/test', 05:59:35 INFO - 'SHELL': '/bin/bash', 05:59:35 INFO - 'SHLVL': '1', 05:59:35 INFO - 'TERM': 'linux', 05:59:35 INFO - 'TMOUT': '86400', 05:59:35 INFO - 'USER': 'cltbld', 05:59:35 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:35 INFO - '_': '/tools/buildbot/bin/python'} 05:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:36 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 05:59:36 INFO - Cleaning up... 05:59:36 INFO - Return code: 0 05:59:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:59:36 INFO - Using env: {'DISPLAY': ':0', 05:59:36 INFO - 'HOME': '/home/cltbld', 05:59:36 INFO - 'LANG': 'en_US.UTF-8', 05:59:36 INFO - 'LANGUAGE': 'en_US:en', 05:59:36 INFO - 'LOGNAME': 'cltbld', 05:59:36 INFO - 'MAIL': '/var/mail/cltbld', 05:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:36 INFO - 'MOZ_NO_REMOTE': '1', 05:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:36 INFO - 'NO_EM_RESTART': '1', 05:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:36 INFO - 'PWD': '/builds/slave/test', 05:59:36 INFO - 'SHELL': '/bin/bash', 05:59:36 INFO - 'SHLVL': '1', 05:59:36 INFO - 'TERM': 'linux', 05:59:36 INFO - 'TMOUT': '86400', 05:59:36 INFO - 'USER': 'cltbld', 05:59:36 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:36 INFO - '_': '/tools/buildbot/bin/python'} 05:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:36 INFO - Downloading/unpacking psutil>=0.7.1 05:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:59:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:38 INFO - Installing collected packages: psutil 05:59:38 INFO - Running setup.py install for psutil 05:59:38 INFO - building 'psutil._psutil_linux' extension 05:59:38 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 05:59:38 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 05:59:38 INFO - building 'psutil._psutil_posix' extension 05:59:38 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 05:59:38 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 05:59:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:38 INFO - Successfully installed psutil 05:59:38 INFO - Cleaning up... 05:59:38 INFO - Return code: 0 05:59:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:59:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:59:38 INFO - Using env: {'DISPLAY': ':0', 05:59:38 INFO - 'HOME': '/home/cltbld', 05:59:38 INFO - 'LANG': 'en_US.UTF-8', 05:59:38 INFO - 'LANGUAGE': 'en_US:en', 05:59:38 INFO - 'LOGNAME': 'cltbld', 05:59:38 INFO - 'MAIL': '/var/mail/cltbld', 05:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:38 INFO - 'MOZ_NO_REMOTE': '1', 05:59:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:38 INFO - 'NO_EM_RESTART': '1', 05:59:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:38 INFO - 'PWD': '/builds/slave/test', 05:59:38 INFO - 'SHELL': '/bin/bash', 05:59:38 INFO - 'SHLVL': '1', 05:59:38 INFO - 'TERM': 'linux', 05:59:38 INFO - 'TMOUT': '86400', 05:59:38 INFO - 'USER': 'cltbld', 05:59:38 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:38 INFO - '_': '/tools/buildbot/bin/python'} 05:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:59:40 INFO - Installing collected packages: mozsystemmonitor 05:59:40 INFO - Running setup.py install for mozsystemmonitor 05:59:41 INFO - Successfully installed mozsystemmonitor 05:59:41 INFO - Cleaning up... 05:59:41 INFO - Return code: 0 05:59:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:59:41 INFO - Using env: {'DISPLAY': ':0', 05:59:41 INFO - 'HOME': '/home/cltbld', 05:59:41 INFO - 'LANG': 'en_US.UTF-8', 05:59:41 INFO - 'LANGUAGE': 'en_US:en', 05:59:41 INFO - 'LOGNAME': 'cltbld', 05:59:41 INFO - 'MAIL': '/var/mail/cltbld', 05:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:41 INFO - 'MOZ_NO_REMOTE': '1', 05:59:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:41 INFO - 'NO_EM_RESTART': '1', 05:59:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:41 INFO - 'PWD': '/builds/slave/test', 05:59:41 INFO - 'SHELL': '/bin/bash', 05:59:41 INFO - 'SHLVL': '1', 05:59:41 INFO - 'TERM': 'linux', 05:59:41 INFO - 'TMOUT': '86400', 05:59:41 INFO - 'USER': 'cltbld', 05:59:41 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:41 INFO - '_': '/tools/buildbot/bin/python'} 05:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:43 INFO - Downloading docopt-0.6.1.tar.gz 05:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:43 INFO - Running setup.py install for blobuploader 05:59:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:44 INFO - Running setup.py install for requests 05:59:44 INFO - Running setup.py install for docopt 05:59:44 INFO - Successfully installed blobuploader requests docopt 05:59:44 INFO - Cleaning up... 05:59:44 INFO - Return code: 0 05:59:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:44 INFO - Using env: {'DISPLAY': ':0', 05:59:44 INFO - 'HOME': '/home/cltbld', 05:59:44 INFO - 'LANG': 'en_US.UTF-8', 05:59:44 INFO - 'LANGUAGE': 'en_US:en', 05:59:44 INFO - 'LOGNAME': 'cltbld', 05:59:44 INFO - 'MAIL': '/var/mail/cltbld', 05:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:44 INFO - 'MOZ_NO_REMOTE': '1', 05:59:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:44 INFO - 'NO_EM_RESTART': '1', 05:59:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:44 INFO - 'PWD': '/builds/slave/test', 05:59:44 INFO - 'SHELL': '/bin/bash', 05:59:44 INFO - 'SHLVL': '1', 05:59:44 INFO - 'TERM': 'linux', 05:59:44 INFO - 'TMOUT': '86400', 05:59:44 INFO - 'USER': 'cltbld', 05:59:44 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:44 INFO - '_': '/tools/buildbot/bin/python'} 05:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:44 INFO - Running setup.py (path:/tmp/pip-UE2Ftx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:44 INFO - Running setup.py (path:/tmp/pip-9v7sfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:44 INFO - Running setup.py (path:/tmp/pip-pcTzb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:45 INFO - Running setup.py (path:/tmp/pip-knIA1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:45 INFO - Running setup.py (path:/tmp/pip-SeQghR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:45 INFO - Running setup.py (path:/tmp/pip-BFdGwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:45 INFO - Running setup.py (path:/tmp/pip-TkRyb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:45 INFO - Running setup.py (path:/tmp/pip-oCFk5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:45 INFO - Running setup.py (path:/tmp/pip-UDAwli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:45 INFO - Running setup.py (path:/tmp/pip-aEMSYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:45 INFO - Running setup.py (path:/tmp/pip-sHnyNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:45 INFO - Running setup.py (path:/tmp/pip-Egg3M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:45 INFO - Running setup.py (path:/tmp/pip-3w8Ncz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:45 INFO - Running setup.py (path:/tmp/pip-IImpj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:45 INFO - Running setup.py (path:/tmp/pip-gvAm8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:45 INFO - Running setup.py (path:/tmp/pip-D9Ts3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:45 INFO - Running setup.py (path:/tmp/pip-ZQ5wwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:45 INFO - Running setup.py install for manifestparser 05:59:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Running setup.py install for mozcrash 05:59:46 INFO - Running setup.py install for mozdebug 05:59:46 INFO - Running setup.py install for mozdevice 05:59:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Running setup.py install for mozfile 05:59:46 INFO - Running setup.py install for mozhttpd 05:59:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Running setup.py install for mozinfo 05:59:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Running setup.py install for mozInstall 05:59:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Running setup.py install for mozleak 05:59:46 INFO - Running setup.py install for mozlog 05:59:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Running setup.py install for moznetwork 05:59:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Running setup.py install for mozprocess 05:59:47 INFO - Running setup.py install for mozprofile 05:59:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Running setup.py install for mozrunner 05:59:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Running setup.py install for mozscreenshot 05:59:47 INFO - Running setup.py install for moztest 05:59:47 INFO - Running setup.py install for mozversion 05:59:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:47 INFO - Cleaning up... 05:59:47 INFO - Return code: 0 05:59:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:47 INFO - Using env: {'DISPLAY': ':0', 05:59:47 INFO - 'HOME': '/home/cltbld', 05:59:47 INFO - 'LANG': 'en_US.UTF-8', 05:59:47 INFO - 'LANGUAGE': 'en_US:en', 05:59:47 INFO - 'LOGNAME': 'cltbld', 05:59:47 INFO - 'MAIL': '/var/mail/cltbld', 05:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:47 INFO - 'MOZ_NO_REMOTE': '1', 05:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:47 INFO - 'NO_EM_RESTART': '1', 05:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:47 INFO - 'PWD': '/builds/slave/test', 05:59:47 INFO - 'SHELL': '/bin/bash', 05:59:47 INFO - 'SHLVL': '1', 05:59:47 INFO - 'TERM': 'linux', 05:59:47 INFO - 'TMOUT': '86400', 05:59:47 INFO - 'USER': 'cltbld', 05:59:47 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:47 INFO - '_': '/tools/buildbot/bin/python'} 05:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:47 INFO - Running setup.py (path:/tmp/pip-UeyjVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:48 INFO - Running setup.py (path:/tmp/pip-y6LclM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:48 INFO - Running setup.py (path:/tmp/pip-maZMyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:48 INFO - Running setup.py (path:/tmp/pip-EIPZKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:48 INFO - Running setup.py (path:/tmp/pip-lY_f6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:48 INFO - Running setup.py (path:/tmp/pip-AW8HIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:48 INFO - Running setup.py (path:/tmp/pip-_yeKQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:48 INFO - Running setup.py (path:/tmp/pip-qD1hDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:48 INFO - Running setup.py (path:/tmp/pip-ccyWv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:48 INFO - Running setup.py (path:/tmp/pip-7EgE9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:48 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)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:48 INFO - Running setup.py (path:/tmp/pip-wf3zSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:48 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)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:48 INFO - Running setup.py (path:/tmp/pip-SLDKj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:48 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)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:48 INFO - Running setup.py (path:/tmp/pip-ZwYu5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:48 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)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:48 INFO - Running setup.py (path:/tmp/pip-_R56Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:49 INFO - Running setup.py (path:/tmp/pip-Jhelzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:49 INFO - Running setup.py (path:/tmp/pip-9vORMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:49 INFO - Running setup.py (path:/tmp/pip-Rgcn9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:51 INFO - Downloading blessings-1.6.tar.gz 05:59:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:59:51 INFO - Installing collected packages: blessings 05:59:51 INFO - Running setup.py install for blessings 05:59:51 INFO - Successfully installed blessings 05:59:51 INFO - Cleaning up... 05:59:51 INFO - Return code: 0 05:59:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:59:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:51 INFO - Reading from file tmpfile_stdout 05:59:51 INFO - Current package versions: 05:59:51 INFO - argparse == 1.2.1 05:59:51 INFO - blessings == 1.6 05:59:51 INFO - blobuploader == 1.2.4 05:59:51 INFO - docopt == 0.6.1 05:59:51 INFO - manifestparser == 1.1 05:59:51 INFO - mozInstall == 1.12 05:59:51 INFO - mozcrash == 0.17 05:59:51 INFO - mozdebug == 0.1 05:59:51 INFO - mozdevice == 0.48 05:59:51 INFO - mozfile == 1.2 05:59:51 INFO - mozhttpd == 0.7 05:59:51 INFO - mozinfo == 0.9 05:59:51 INFO - mozleak == 0.1 05:59:51 INFO - mozlog == 3.1 05:59:51 INFO - moznetwork == 0.27 05:59:51 INFO - mozprocess == 0.22 05:59:51 INFO - mozprofile == 0.28 05:59:51 INFO - mozrunner == 6.11 05:59:51 INFO - mozscreenshot == 0.1 05:59:51 INFO - mozsystemmonitor == 0.0 05:59:51 INFO - moztest == 0.7 05:59:51 INFO - mozversion == 1.4 05:59:51 INFO - psutil == 3.1.1 05:59:51 INFO - requests == 1.2.3 05:59:51 INFO - wsgiref == 0.1.2 05:59:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:51 INFO - Using env: {'DISPLAY': ':0', 05:59:51 INFO - 'HOME': '/home/cltbld', 05:59:51 INFO - 'LANG': 'en_US.UTF-8', 05:59:51 INFO - 'LANGUAGE': 'en_US:en', 05:59:51 INFO - 'LOGNAME': 'cltbld', 05:59:51 INFO - 'MAIL': '/var/mail/cltbld', 05:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:51 INFO - 'MOZ_NO_REMOTE': '1', 05:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:51 INFO - 'NO_EM_RESTART': '1', 05:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:51 INFO - 'PWD': '/builds/slave/test', 05:59:51 INFO - 'SHELL': '/bin/bash', 05:59:51 INFO - 'SHLVL': '1', 05:59:51 INFO - 'TERM': 'linux', 05:59:51 INFO - 'TMOUT': '86400', 05:59:51 INFO - 'USER': 'cltbld', 05:59:51 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:51 INFO - '_': '/tools/buildbot/bin/python'} 05:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 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)) 05:59:51 INFO - Cleaning up... 05:59:51 INFO - Return code: 0 05:59:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 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 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:51 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 05:59:51 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 05:59:51 INFO - Using env: {'DISPLAY': ':0', 05:59:51 INFO - 'HOME': '/home/cltbld', 05:59:51 INFO - 'LANG': 'en_US.UTF-8', 05:59:51 INFO - 'LANGUAGE': 'en_US:en', 05:59:51 INFO - 'LOGNAME': 'cltbld', 05:59:51 INFO - 'MAIL': '/var/mail/cltbld', 05:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:51 INFO - 'MOZ_NO_REMOTE': '1', 05:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:51 INFO - 'NO_EM_RESTART': '1', 05:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:51 INFO - 'PWD': '/builds/slave/test', 05:59:51 INFO - 'SHELL': '/bin/bash', 05:59:51 INFO - 'SHLVL': '1', 05:59:51 INFO - 'TERM': 'linux', 05:59:51 INFO - 'TMOUT': '86400', 05:59:51 INFO - 'USER': 'cltbld', 05:59:51 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:51 INFO - '_': '/tools/buildbot/bin/python'} 05:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:51 INFO - Downloading/unpacking jsonschema 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:59:53 INFO - Downloading/unpacking functools32 (from jsonschema) 05:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:54 INFO - Downloading functools32-3.2.3-2.tar.gz 05:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:59:54 INFO - warning: no files found matching '*.txt' 05:59:54 INFO - no previously-included directories found matching 'build' 05:59:54 INFO - no previously-included directories found matching 'dist' 05:59:54 INFO - no previously-included directories found matching '.git*' 05:59:54 INFO - Installing collected packages: jsonschema, functools32 05:59:54 INFO - Running setup.py install for functools32 05:59:54 INFO - warning: no files found matching '*.txt' 05:59:54 INFO - no previously-included directories found matching 'build' 05:59:54 INFO - no previously-included directories found matching 'dist' 05:59:54 INFO - no previously-included directories found matching '.git*' 05:59:54 INFO - Successfully installed jsonschema functools32 05:59:54 INFO - Cleaning up... 05:59:54 INFO - Return code: 0 05:59:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:59:54 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')]} 05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:54 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')]} 05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:54 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 0x7fd729cb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', '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 05:59:54 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 05:59:54 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 05:59:54 INFO - Using env: {'DISPLAY': ':0', 05:59:54 INFO - 'HOME': '/home/cltbld', 05:59:54 INFO - 'LANG': 'en_US.UTF-8', 05:59:54 INFO - 'LANGUAGE': 'en_US:en', 05:59:54 INFO - 'LOGNAME': 'cltbld', 05:59:54 INFO - 'MAIL': '/var/mail/cltbld', 05:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:54 INFO - 'MOZ_NO_REMOTE': '1', 05:59:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:54 INFO - 'NO_EM_RESTART': '1', 05:59:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:54 INFO - 'PWD': '/builds/slave/test', 05:59:54 INFO - 'SHELL': '/bin/bash', 05:59:54 INFO - 'SHLVL': '1', 05:59:54 INFO - 'TERM': 'linux', 05:59:54 INFO - 'TMOUT': '86400', 05:59:54 INFO - 'USER': 'cltbld', 05:59:54 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 05:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:54 INFO - '_': '/tools/buildbot/bin/python'} 05:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:54 INFO - Downloading/unpacking flake8 05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:59:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:59:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:59:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:59:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:59:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:59:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:59:57 INFO - no previously-included directories found matching 'docs/_build' 05:59:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:59:57 INFO - Running setup.py install for pep8 05:59:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:59:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:59:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:59:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:59:57 INFO - no previously-included directories found matching 'docs/_build' 05:59:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:59:57 INFO - Cleaning up... 05:59:57 INFO - Return code: 0 05:59:57 INFO - Running post-action listener: _resource_record_post_action 05:59:57 INFO - Running post-action listener: _start_resource_monitoring 05:59:57 INFO - Starting resource monitoring. 05:59:57 INFO - ##### 05:59:57 INFO - ##### Finished create-virtualenv step (success) 05:59:57 INFO - ##### 05:59:57 INFO - ##### 05:59:57 INFO - ##### Running install step. 05:59:57 INFO - ##### 05:59:57 INFO - Running pre-action listener: _resource_record_pre_action 05:59:57 INFO - Running main action method: install 05:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:57 INFO - Reading from file tmpfile_stdout 05:59:57 INFO - Detecting whether we're running mozinstall >=1.0... 05:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:59:57 INFO - Reading from file tmpfile_stdout 05:59:57 INFO - Output received: 05:59:57 INFO - Usage: mozinstall [options] installer 05:59:57 INFO - Options: 05:59:57 INFO - -h, --help show this help message and exit 05:59:57 INFO - -d DEST, --destination=DEST 05:59:57 INFO - Directory to install application into. [default: 05:59:57 INFO - "/builds/slave/test"] 05:59:57 INFO - --app=APP Application being installed. [default: firefox] 05:59:57 INFO - mkdir: /builds/slave/test/build/application 05:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:00:14 INFO - Reading from file tmpfile_stdout 06:00:14 INFO - Output received: 06:00:14 INFO - /builds/slave/test/build/application/firefox/firefox 06:00:14 INFO - Running post-action listener: _resource_record_post_action 06:00:14 INFO - ##### 06:00:14 INFO - ##### Finished install step (success) 06:00:14 INFO - ##### 06:00:14 INFO - ##### 06:00:14 INFO - ##### Running run-tests step. 06:00:14 INFO - ##### 06:00:14 INFO - Running pre-action listener: _resource_record_pre_action 06:00:14 INFO - Running main action method: run_tests 06:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:00:14 INFO - Python 2.7.3 06:00:14 INFO - Return code: 0 06:00:14 INFO - grabbing minidump binary from tooltool 06:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:14 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea09c0>, '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 0x1ea18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ea1d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:00:14 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 06:00:14 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 06:00:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:00:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:00:14 INFO - Return code: 0 06:00:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:00:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:00:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:00:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:00:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:00:15 INFO - Return code: 0 06:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 06:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 06:00:15 INFO - Using env: {'DISPLAY': ':0', 06:00:15 INFO - 'HOME': '/home/cltbld', 06:00:15 INFO - 'LANG': 'en_US.UTF-8', 06:00:15 INFO - 'LANGUAGE': 'en_US:en', 06:00:15 INFO - 'LOGNAME': 'cltbld', 06:00:15 INFO - 'MAIL': '/var/mail/cltbld', 06:00:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:00:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:15 INFO - 'MOZ_NO_REMOTE': '1', 06:00:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:00:15 INFO - 'NO_EM_RESTART': '1', 06:00:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:15 INFO - 'PWD': '/builds/slave/test', 06:00:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:00:15 INFO - 'SHELL': '/bin/bash', 06:00:15 INFO - 'SHLVL': '1', 06:00:15 INFO - 'TERM': 'linux', 06:00:15 INFO - 'TMOUT': '86400', 06:00:15 INFO - 'USER': 'cltbld', 06:00:15 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479', 06:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:15 INFO - '_': '/tools/buildbot/bin/python'} 06:00:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 06:00:15 INFO - mozversion application_buildid: 20160511023007 06:00:15 INFO - mozversion application_changeset: 52f40a1102ad7c5bf8f463334b37595ecbc5bd73 06:00:15 INFO - mozversion application_display_name: Nightly 06:00:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:00:15 INFO - mozversion application_name: Firefox 06:00:15 INFO - mozversion application_remotingname: firefox 06:00:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:00:15 INFO - mozversion application_vendor: Mozilla 06:00:15 INFO - mozversion application_version: 49.0a1 06:00:15 INFO - mozversion platform_buildid: 20160511023007 06:00:15 INFO - mozversion platform_changeset: 52f40a1102ad7c5bf8f463334b37595ecbc5bd73 06:00:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:00:15 INFO - mozversion platform_version: 49.0a1 06:00:15 INFO - using testdate: 1462971615 06:00:15 INFO - actual date: 1462971615 06:00:15 INFO - starting webserver on 'localhost:49289' 06:00:15 INFO - SUITE-START | Running 2 tests 06:00:15 INFO - TEST-START | dromaeo_css 06:00:15 INFO - operating with platform_type : linux_ 06:00:15 INFO - Initialising browser for dromaeo_css test... 06:00:15 INFO - TEST-INFO | started process 2598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_tGY50/profile http://localhost:49289/getInfo.html) 06:00:18 INFO - PROCESS | 2598 | 06:00:18 INFO - PROCESS | 2598 | (process:2692): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:00:19 INFO - PROCESS | 2598 | __metrics Screen width/height:1600/1200 06:00:19 INFO - PROCESS | 2598 | colorDepth:24 06:00:19 INFO - PROCESS | 2598 | Browser inner width/height: 1024/697 06:00:19 INFO - PROCESS | 2598 | __metrics 06:00:20 ERROR - PROCESS | 2598 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 06:00:21 INFO - TEST-INFO | 2598: exit 0 06:00:21 INFO - Browser initialized. 06:00:21 INFO - Running cycle 1/1 for dromaeo_css test... 06:00:21 INFO - TEST-INFO | started process 2794 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_tGY50/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:23 INFO - PROCESS | 2794 | 06:00:23 INFO - PROCESS | 2794 | (process:2882): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:00:33 INFO - PROCESS | 2794 | RSS: Main: 163057664 06:00:33 INFO - PROCESS | 2794 | 06:01:22 INFO - PROCESS | 2794 | 1462971682658 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] 06:03:30 INFO - PROCESS | 2794 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49289/tests/dromaeo/cssquery-ext.html) 06:03:31 INFO - PROCESS | 2794 | RSS: Main: 166518784 06:03:31 INFO - PROCESS | 2794 | 06:06:33 INFO - PROCESS | 2794 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-ext.html (next: http://localhost:49289/tests/dromaeo/cssquery-jquery.html) 06:06:34 INFO - PROCESS | 2794 | RSS: Main: 166420480 06:06:34 INFO - PROCESS | 2794 | 06:09:35 INFO - PROCESS | 2794 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49289/tests/dromaeo/cssquery-mootools.html) 06:09:36 INFO - PROCESS | 2794 | RSS: Main: 167350272 06:09:36 INFO - PROCESS | 2794 | 06:12:38 INFO - PROCESS | 2794 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49289/tests/dromaeo/cssquery-prototype.html) 06:12:38 INFO - PROCESS | 2794 | RSS: Main: 165498880 06:12:38 INFO - PROCESS | 2794 | 06:15:41 INFO - PROCESS | 2794 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49289/tests/dromaeo/cssquery-yui.html) 06:15:41 INFO - PROCESS | 2794 | RSS: Main: 166154240 06:15:41 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-yui.html (next: http://localhost:49289/tests/dromaeo/cssquery-dojo.html) 06:18:44 INFO - PROCESS | 2794 | RSS: Main: 168353792 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | __start_tp_report 06:18:44 INFO - PROCESS | 2794 | _x_x_mozilla_page_load 06:18:44 INFO - PROCESS | 2794 | _x_x_mozilla_page_load_details 06:18:44 INFO - PROCESS | 2794 | |i|pagename|runs| 06:18:44 INFO - PROCESS | 2794 | |0;dojo.html;3150.0549835049487;3154.88957886474;3184.1561986073693;3198.8400579970994;3203.19920019995;5321.946780532194;5327.200919862023;5348.331458567679;5396.082665946788;5399.19012148178;5267.183586544092;5344.118220493614;5404.108322126855;5478.096114141172;5488.53347465466;4795.328653988445;4831.37192165018;4873.098476781806;4957.454679985199;5009.148444764399;4812.23004319309;4994.325766021577;5025.145725226702;5046.293518907356;5048.671836330645;4832.081904438181;4857.319975203474;4887.462379138292;4952.925706114411;4958.900821983558;4873.074115917964;4893.143699852511;4893.168161412568;4915.5330243626795;4916.557509824097;4879.121758083544;4881.926771098437;4889.706617602919;4895.412550493964;4913.557779799799;5159.458256883011;5306.548943339821;5317.202419637047;5365.03429382711;5396.839679468933;5849.561282903799;5864.266966629171;5873.030949893263;5886.234789477383;5895.61678490901;5686.943130568683;5916.083007133901;5920.171176035358;5934.732937017845;5942.881142377171;5349.43830897754;5380.354357477088;5398.163284690879;5399.487048730325;5412.161115027177;5843.474087332161;5845.678487683218;5854.531637468992;5856.414358564195;5874.500667443315;5532.529734972572;5614.466625670637;5622.353429355595;5629.915551266734;5630.268065151504;5766.855828604318;5811.389804070512;5813.04084825999;5813.970930145322;5871.706414679249;5592.692401917933;5636.971815140898;5777.19119323295;5783.392743761844;5813.88372232553;5579.46995035471;5680.7727690892725;5710.143478478179;5718.25662663851;5771.971140144273;4351.521332653406;4351.521332653406;4386.780660966939;4388.626966707801;4391.978040109779;3921.568627450978;3934.11482416454;3941.0541470047556;3947.1908258806875;3972.4041393791163;5431.728413579384;5490.203920431515;5530.225768392428;5548.55611551075;5552.7223638817895;5755.769769209269;5784.074548072288;5791.768329266866;5820.35976042635;5821.446962538552;5552.666839989613;5553.750081246354;5560.360558535739;5572.665640061609;5581.413951535112;4917.434495033045;4939.580135688435;4948.331975183376;4964.230544265644;4989.700617962933;5898.292204935436;5925.703714814242;5951.047832346891;5952.821415357546;5961.2846458425265;5830.504407125357;5839.795607153899;5842.2405087338375;5844.620099693507;5847.473727364558;5845.152452894222;5851.444112809447;5852.736626851887;5901.114832775118;5958.702064896825;5741.081426971831;5743.282089738783;5744.971275143597;5753.798617048383;5765.625234359753;5706.2011318414625;5840.065589505659;5842.006858833924;5844.8310337933435;5867.559933004956;5683.175939488834;5737.08206686928;5743.62666426681;5746.9712651436475;5752.050911599539;5512.4211957745465;5679.574031947538;5734.254546908875;5740.712964351849;5749.511291540099;5603.719814009365;5669.177969194526;5677.914831277617;5678.602497825113;5695.288088988877;5619.213310136666;5669.177969194526;5747.655140691572;5749.338826035059;5804.593678442468;5656.43435656431;5700.25896633435;5707.600467967369;5734.5125664319;5767.452092051249 06:18:44 INFO - PROCESS | 2794 | |1;ext.html;575.7725698349152;639.5746828359139;642.1299139665753;675.0785178231715;678.067656971664;37181;41609;41732;41785.79107104464;41828.79085604575;40862.161202745876;41523.58476415235;41561.58438415615;41718.582814171925;41895.162096758046;8744.30045596354;8898.065703101178;8904.109589041076;8907.955460222689;8913.910860891372;8669.95665021674;8725.869111963326;8802.163794439519;8809;8813.779655508599;8817.515036672981;8873.11268873114;8877.378583499158;8883.73348799537;8916.73249802507;8795;8804.383693141483;8856.601452934665;8860.734177974671;8865.734027979172;8751.124887511261;8815.559222038872;8826.602802873822;8834.646614135427;8887.289016878634;36799.816000919825;39024.41463378023;39407.211855762725;39469.40795888064;39535.40696889549;3519.0322661268356;3521.7182625389905;3592.4072528032843;3977.244323578511;4213.51544572375;30343.241418964702;30437.695623043706;30701.385972280652;30770.38459230825;31724.682753172405;4155.743372648909;4158.542560318362;4234.830606775725;4295.226859165349;4308.3968244445805;294818;295693;297250;297753;297851;35340.823295883354;35697;35865.282694345966;35865.82067089648;36823;37158.8142059288;37174.62825371766;37187.628123718416;37205.81397092997;37324.25351492955;16147.596310092107;16281.185940702917;16287.348506059861;16307;16322;12526.74946501065;12558.120931534888;12599.811002834966;12633.810492842615;12640.620781376574;8530.957345213359;8572.35707321941;8605.096464871223;8630.78423039429;8671.913280867111;3258.9348213035605;3280.803151810899;3323.135984643962;3332.3168750406594;3354.9496757548654;1039.9220058495491;1048.2871647279771;1184.5321098166348;1203.0676225924803;1223.7797196504537;31199.192072713566;31540.36919261648;31589.526157107663;32031.839840800647;32539.837300813346;1152.2913408253864;1177.5407591039502;1184.7393573413835;1191.624638238952;1219.7377563823643;16457.177141142896;16741.832581674273;16785.136916457286;17069.658606827794;17210.139493025297;450775;451737.70655733213;452279;454746;456542;58452.41547584555;58695.70652146712;59065.70467147637;59068.704656476446;59074;1377.5178687459509;1386.6533366658336;1407.2189934586197;1407.4651632379835;1418.5389748331627;30358.696413036472;31221.687783121877;31363.215919602193;31636.209094772814;32179.51730723994;49375.753121234164;56268;57909.71045144747;58017.129743054735;62242.68878655578;426002;428067;431755;432267;434035;43966.56033439752;44105.77947110244;44147.558524414344;44182.77908610565;44224.77887610541;48507.39179777916;48530;48534.514654853;49498.50501494939;49857.75071124621;40186.598134018284;40964.1807163861;41196.38205426981;41489.1702165961;41716;39736;39765.409383624334;41033.589664102976;41391.7930410346;41655.58344416638;42114.36828447637;42529.787351063045;42820.35769463398;43246.351304731084;45690.31464528101;35563;36481.452778207815;37116.6288337124;37210.25579488449;38119.42820857745;28864.855675721486;29162.854185728935;29435.41129177447;29459.85270073636;29493.410131796816 06:18:44 INFO - PROCESS | 2794 | |2;jquery.html;390.0638467677574;393.9310620641387;394.292245419472;397.3106659944995;412.8596277265731;13788.379522921468;18185.636287274247;18725.812741872578;19993.300234491795;20025.198992040318;18179.90910045451;18490.352837650684;18755.43733687988;18829.623407531843;18967.905160474213;5569.554435645159;5588.329400471949;5714.9714251428895;5765.1352297155345;5776.162456443813;5306.734663266821;5603.775848966036;5655.575831812631;5710.2862142232225;5731.914021289684;5629.324481062258;5641.68970706611;5649.12438572022;5664.943350566483;5697.487226149646;5403.891922161554;5694.544436445074;5738.942610573882;5824.825255242349;5847.06446968483;5571.609987300891;5619.157126431027;5784.826455206308;5799.5650326225705;5818.941810581882;21763;21798.56402871934;21883.562328753334;22446.214382496535;22533.774662253494;2294.219965211836;2296.552172326397;2303.57383883982;2322.1059891941554;2479.6280557916275;8934.687285945025;9436.952815235949;9445.433274003582;9458.243340532741;9725.708228753148;1938.8255057044935;2006.6989951507244;2011.1050582490798;2038.1847261095531;2054.979450205509;82598.3480330396;82733;82939.17060829375;83058.16941830565;83520.16479835185;10662.147028237723;10670.519826607666;10676;10895.891041089646;11668.649940501798;15282.541523754306;15353.692926141637;15358.462453814063;15360.769588456182;15377.538673839803;8108.8378232435025;8260.793480163044;8363.20549547792;8474.491530508189;8488.193621605938;8140.389470789714;8155.918440815634;8175;8337.791555211168;8408.32733381328;4593.471750748707;4609.216433206363;4622.884427889329;4683.672142949961;4788.688735232199;1930.9517262068562;1992.4221975627195;2006.5886493268845;2015.1032790408128;2053.856230063881;912.2291663544295;988.6391467114595;1005.4603465353932;1059.6344261229863;1079.3416016230094;16915.492535223962;17771.911140444216;18009;18749.531261718294;19018.33435829767;1036.2487196782272;1060.7613287010515;1074.521837782195;1109.312226419625;1142.4801715219555;10093.142082922886;10236;10539.57841686324;10602.469876506144;10894.346339219672;477191;484210;489145;501430;508984;77878;88109.55945220233;88974.55512722395;98045.50977245068;100616.49691751495;1085.9728506787394;1115.6764538283808;1128.311729844794;1130.3331034689572;1136.7442325476702;16589;16726.996380217224;16876.24056917466;16894.324227030782;17154.142292885055;39550.60449395584;39649.2070158601;40234.39648405218;40876.38685419781;41006.1798764029;57677;57710.422895772186;58395.70802145962;58451.70774146102;58923.70538147282;6379.043143528323;8220.054693710299;8469.703560375603;8511.063782983743;8594.519219716025;12799.936000319938;13041.543545975845;13313;13390.26353550564;13500.392482338515;11648.475818588353;11756.294622322688;11798.41007949582;11963.76072478563;12067.57763478274;11326.773464530828;11487.770244595229;11573.247738896946;11680.76638467209;11753.941230293794;11885.76228475443;12106.455209515418;12161.45273462714;12172;12308.938455307667;7481.738139165103;7986.321162701235;8368.916310836814;8459.196376344238;8509.702160424355;12052.578159764365;12162.635120946386;12174.391280435766;12348.197367171106;12463.252204867737 06:18:44 INFO - PROCESS | 2794 | |3;mootools.html;1519.2707500399808;1551.2941611566737;1681.9915900420497;1810.1944634637584;1827.5613852675363;8137.471064380811;8557.614907329169;8603.397762156652;9097.408668436556;9147.6798312059;8685.869711954325;8979.057198994109;9007.774805629846;9032.548372581361;9074.455532668027;2349.2130136404326;2372.323887692008;2374.287713685896;2391.8445301055376;2392.35406440261;2334.5681049005902;2355.5759963206615;2370.099362242354;2374.4063984004;2375.0974629640714;2297.2304278066786;2337.1469413663976;2348.5655153796515;2370.4547953970596;2392.9880350598223;2346.1905642553293;2360.834741568091;2382.916597919082;2401.7117945846435;2410.4696966667307;2343.8593684378993;2368.6683864259016;2371.5968285391355;2378.7859092681742;2391.6173412253956;9019.684311049083;9101.863472047859;9111.17999380059;9126.58930348136;9130.406523575946;5207.3751149862255;5341.572674186055;5363.329583802025;5372.946270537283;5436.103042997901;3309.420851351004;3340.7828491148;3391.6099648540485;3396.235846934445;3426.7601267911255;1095.5015467962057;1104.9116070714404;1109.955601775928;1114.1254115519278;1175.142146233246;12804.551840685532;12820.551280705224;12869.163504372185;12872.935635321857;12889.548865789746;4650;4749.715017098985;4781.880452988703;4839.322494850724;4888.9755551222015;10641.148708103334;10688;10698.679039628823;10705.411202383788;10726.785464290671;5286.656367336188;5313.415524292324;5332.360116786011;5350.51845333914;5353.2773075634295;4456.933146002748;4763.880902977384;4886.486918873538;4892;5036.84889453317;4046.231216068996;4088.9591104088577;4164.292070348048;4173.457450531411;4191.1827193697545;1270.7776139175608;1309.1948451702135;1321.0752473268751;1325.2578556008666;1325.4300650720284;928.4475736936512;1037.9532921018572;1049.7113293844102;1060.596973150198;1061.124572227915;6372.585781924253;6464.967675161594;6509.772157974543;6533.216014078341;6569.080328753978;470.7575598566741;472.376463068746;474.0471651979546;479.90641824844516;488.38463535944254;4521.095780843844;4543.863684089482;4578.496365399803;4596.057808149321;4629.1436084324505;10820.729481763019;11254.831177532344;11289.60486382989;11316.15128865322;11341.659750207342;10424.426656533811;10966.177536684781;11075.446227688582;11190.160737944523;11261.887381126247;8230.42387032902;8499.447535910145;8538;8538.871916921127;8556.61495232716;6245.094461302995;6392.360763923571;6550.672466376567;6552.83617909537;6595.670216489252;10322.690319290434;10521.053105220566;10521.21090918169;10633;10640.201984851012;10501.527431265764;10608.151347892304;10676.306040107369;10793.838092428463;10817.026467617952;7950.204979502004;8076.959615202121;8099.109097999333;8176.264136227768;8179.304759095306;8143.348532117535;8161.387895907712;8200.917990820015;8210.3021243195;8231.547264900488;7835;7916.129225785044;7987.201279871966;8022.799430014063;8041.718539851077;7359.852802944019;7449.553026818408;7508.0990281166605;7528.20953799865;7536.7362142324755;7121.14546254432;7124.679389427592;7245.021922040668;7307.26927307265;7373.5575865448245;5387.542058925035;5396.460353964572;5421.9186712200135;5499;5610.803621873215;5540.667559946416;5558.583106266965;5619.522340601095;5664.207011018543;5696.629719068247 06:18:44 INFO - PROCESS | 2794 | |4;prototype.html;326.3033423640527;333.13219064337386;333.3017328696381;346.91500582357327;348.242572405019;5421.322334708161;5471.425500322469;5545.778168873246;5637;5671.432856714332;5499.752511136999;5545.805896793613;5549.361823390312;5583.413741557129;5589.441055894418;4282.678799090065;4298.7205831621;4302.763348015857;4304;4316.97841510792;4223.852165174219;4302.97848510757;4340.52254252032;4357.912841743163;4363.759993200372;4272.957270427302;4276.850310239142;4280.72175308604;4301.333293339538;4306.763128027973;4310.568943105695;4318.330658747899;4322.805473753689;4330.610245077943;4333.65330773539;3558.448440491728;3833.271678381099;3837.827297771607;3860.749051311656;3864.536255649312;5315.255864179018;5316;5348.545373643245;5358.142697168473;5361.651492652965;4930.679505832145;4941.50584941503;4946;4950.183219768734;4952.950470495285;5515.365732940683;5798.9130163047585;5810.099434587646;5811.331696854873;5852.215074402306;4144.668426525871;4156.625903668654;4176.997520595066;4185.727927684691;4192.664586833046;6127.387261273837;6144.446999769997;6156.507479401637;6158.5381096417495;6170.8765824683705;4108.541467778955;4160.812763425653;4175.561566035583;4214.136102099063;4240.49114106303;4192.119654872511;4201.2017716634355;4201.516825565099;4207.453031105998;4224.4508213931995;4844.346013288232;4877.902471943784;4878.268259760993;4895.951040489621;4918.409790825124;5699.686517241508;5891.410858914074;5907.4683278505145;5957.79147729836;5963.5527335450015;1476.3799204334205;1494.8654621083938;1495.4691084665005;1497.09425797391;1503.2859391788813;909.8680691299725;914.4970266353479;926.4487629860222;939.52553960251;940.4545363689044;4965.205567109318;5015.724135172528;5082.898342033212;5106.66806657566;5113.284140220372;5351.438098999627;5386.542143917811;5387.515123638891;5388.218708287276;5424.647397919122;5027.698338099725;5063.696178229318;5106.4382917879;5204.76578553966;5735.225744524436;3150.692462627984;3171.7938334008218;3192.2498212919945;3209.534617480499;3223.9194020149685;6093.878122437526;6186.350433204539;6224.844378890473;6235.944387250214;6253.6873156342;6093.542984276197;6103.8474038148515;6145.938540614626;6181.567290289727;6217.751289948352;6132.62016046386;6145.201123853871;6152.78465253714;6169.8457538561;6172.135900973867;5776.595638305278;5873.823785286449;5985.533544281679;5989.8918700038885;5995.100734889801;5811.692369216892;5911.852203694942;5932.940670593412;5966.164736936746;5974.40255974399;5863.009864968812;6132.9080063799975;6139.986902161093;6166.568340216142;6168.673735146965;4184.974681203124;4243.639290660205;4257.148570286017;4277.786110694515;4279.550647182125;5985.162077309267;6042.728077236623;6059.424354686298;6074.787382441594;6086.865264388754;5534.612577119563;5836;5836.270466191726;5860.0330945395235;5871.442212989759;5783.843231353662;5807.2670773319605;5844.6785426801935;5856.443637854397;5903.881922361615;5634.549236061187;5766.163906233656;5777.682227477361;5780.797672081457;5836.299644042742;5887.4406931341455;5924.644521328734;5934.376890426443;5935.495482884004;5948.73230704628;5734.426557344399;5777.422257774189;5778.682172480554;5796.739146738322;5799.565032622486 06:18:44 INFO - PROCESS | 2794 | |5;yui.html;708.4934271995522;720.0639169080198;748.8127968007998;763.7899577616155;827.648249493965;13362.599122026342;14285.785713214296;14313.427462901485;15051.322690478928;15419.229038548056;15505;15570.065796052244;15585.142817145052;15629.765553516707;15701.607459813482;681.6012632609929;785.2618538573751;798.5647821937043;810.4367464612097;811.3995643224001;798.3732769775727;798.4450806689354;798.9640466179036;804.1315379390245;806.5644551941973;838.4214891724721;839.0895877972407;844.9197326254027;845.1379592815325;846.1876598465481;843.1189407069596;843.2200214801314;844.3034496540317;844.4933040175907;844.624142256696;607.4563265877015;608.9817305480842;609.5580703989616;612.0757655939519;612.1613389656155;14644.853551464563;14718.33767480466;14719.116852988855;14755.55733328002;14763.92618036903;14001.299935003311;14067;14085.366158522891;14184.57446276603;14237.145771253758;11282.774344513064;11345.886541134565;11437.656870293904;11439.42802859862;11681.2407193533;3733.178700685845;3763.2849758546063;3782.9054273643105;3795.4876091727538;3824.7513911595656;57855.42144578585;58124.41875581232;58447.41552584462;59065;59437.702811485666;10945.562177512811;10955.17836162291;10998.560057597606;11098;11130.888691113147;19916.50208744793;19942.401727948185;20041.29855455052;20126.29557965464;20247.19011239534;8181.181881811771;8262.686447794627;8313.875291870627;8378.581070946428;8450.492970421794;4708.788104535236;4762.14281429346;4786.976065119652;4891.26631005352;4954.3063971044085;3513.3149035937745;3599.262151258986;3599.8380072896784;3621.311950729353;3624;629.7827249598881;631.3024108360196;639.5459223951046;640.5676168586278;656.0094257670951;587.0548417048549;591.8165368735706;646.9644169570624;792.7598573429625;810.295043312322;9318.394304370195;9559.235261179088;9632.662856799978;9695.93344732079;9897.554610042565;736.6758626204453;752.8306131120454;754.8226166850785;759.4304271796153;765.4947734495206;4485.6411487081605;4772.308015337757;4881.365422495053;4883.121038213085;4943.3573635427165;63999.68000159969;64605;64738.05785826433;65406.67296663486;65630;58494.12258816121;59264.70367648134;59428.40571594229;59569.10646340222;59662;52758.472415275355;53289.73355133199;53301.46698533043;53356.46643533515;53550.7322463393;8697.652093916173;8732.60703268367;8814;8846.557672116498;9151.542422878963;34488;34625.30749385049;34767.65232347745;34919.65080349164;35268.823655881555;44375.77812111048;47759.761201193774;48071.519284808106;48106.27840582464;48310.309139181365;11495.425228738697;11613;11770.470328835052;11972.64082077539;12021.519139234682;12842.357882106044;13041.804372934224;13112.344382780631;13132.868671313547;13356.799648005483;11140.387278699425;11166.707951449096;11353.148513861328;11366.669987050569;11616.303021818718;11231.943840280746;11614.702560052834;11753.706157345792;11769.882301176878;11776.11679124052;12096.455659495168;12297.32364719949;12468.875311246771;12516.186447881222;12523.185992910432;9902.712647355798;10521.579586755995;10560.471976401303;10647.893521065;11261.21171517986;9650.704701153894;10252.179825613784;10283.537240824331;10368.948155259175;10678.305910115818 06:18:44 INFO - PROCESS | 2794 | __end_tp_report 06:18:44 INFO - PROCESS | 2794 | __start_cc_report 06:18:44 INFO - PROCESS | 2794 | _x_x_mozilla_cycle_collect,339 06:18:44 INFO - PROCESS | 2794 | __end_cc_report 06:18:44 INFO - PROCESS | 2794 | __startTimestamp1462972724358__endTimestamp 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | ------- Summary: start ------- 06:18:44 INFO - PROCESS | 2794 | Number of tests: 6 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | [#0] dojo.html Cycles:165 Average:5379.43 Median:5622.35 stddev:612.77 (10.9%) stddev-sans-first:589.16 06:18:44 INFO - PROCESS | 2794 | Values: 3150.1 3154.9 3184.2 3198.8 3203.2 5321.9 5327.2 5348.3 5396.1 5399.2 5267.2 5344.1 5404.1 5478.1 5488.5 4795.3 4831.4 4873.1 4957.5 5009.1 4812.2 4994.3 5025.1 5046.3 5048.7 4832.1 4857.3 4887.5 4952.9 4958.9 4873.1 4893.1 4893.2 4915.5 4916.6 4879.1 4881.9 4889.7 4895.4 4913.6 5159.5 5306.5 5317.2 5365.0 5396.8 5849.6 5864.3 5873.0 5886.2 5895.6 5686.9 5916.1 5920.2 5934.7 5942.9 5349.4 5380.4 5398.2 5399.5 5412.2 5843.5 5845.7 5854.5 5856.4 5874.5 5532.5 5614.5 5622.4 5629.9 5630.3 5766.9 5811.4 5813.0 5814.0 5871.7 5592.7 5637.0 5777.2 5783.4 5813.9 5579.5 5680.8 5710.1 5718.3 5772.0 4351.5 4351.5 4386.8 4388.6 4392.0 3921.6 3934.1 3941.1 3947.2 3972.4 5431.7 5490.2 5530.2 5548.6 5552.7 5755.8 5784.1 5791.8 5820.4 5821.4 5552.7 5553.8 5560.4 5572.7 5581.4 4917.4 4939.6 4948.3 4964.2 4989.7 5898.3 5925.7 5951.0 5952.8 5961.3 5830.5 5839.8 5842.2 5844.6 5847.5 5845.2 5851.4 5852.7 5901.1 5958.7 5741.1 5743.3 5745.0 5753.8 5765.6 5706.2 5840.1 5842.0 5844.8 5867.6 5683.2 5737.1 5743.6 5747.0 5752.1 5512.4 5679.6 5734.3 5740.7 5749.5 5603.7 5669.2 5677.9 5678.6 5695.3 5619.2 5669.2 5747.7 5749.3 5804.6 5656.4 5700.3 5707.6 5734.5 5767.5 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | [#1] ext.html Cycles:180 Average:55076.38 Median:41114.99 stddev:105668.10 (257.0%) stddev-sans-first:105885.29 06:18:44 INFO - PROCESS | 2794 | Values: 575.8 639.6 642.1 675.1 678.1 37181.0 41609.0 41732.0 41785.8 41828.8 40862.2 41523.6 41561.6 41718.6 41895.2 8744.3 8898.1 8904.1 8908.0 8913.9 8670.0 8725.9 8802.2 8809.0 8813.8 8817.5 8873.1 8877.4 8883.7 8916.7 8795.0 8804.4 8856.6 8860.7 8865.7 8751.1 8815.6 8826.6 8834.6 8887.3 36799.8 39024.4 39407.2 39469.4 39535.4 3519.0 3521.7 3592.4 3977.2 4213.5 30343.2 30437.7 30701.4 30770.4 31724.7 4155.7 4158.5 4234.8 4295.2 4308.4 294818.0 295693.0 297250.0 297753.0 297851.0 35340.8 35697.0 35865.3 35865.8 36823.0 37158.8 37174.6 37187.6 37205.8 37324.3 16147.6 16281.2 16287.3 16307.0 16322.0 12526.7 12558.1 12599.8 12633.8 12640.6 8531.0 8572.4 8605.1 8630.8 8671.9 3258.9 3280.8 3323.1 3332.3 3354.9 1039.9 1048.3 1184.5 1203.1 1223.8 31199.2 31540.4 31589.5 32031.8 32539.8 1152.3 1177.5 1184.7 1191.6 1219.7 16457.2 16741.8 16785.1 17069.7 17210.1 450775.0 451737.7 452279.0 454746.0 456542.0 58452.4 58695.7 59065.7 59068.7 59074.0 1377.5 1386.7 1407.2 1407.5 1418.5 30358.7 31221.7 31363.2 31636.2 32179.5 49375.8 56268.0 57909.7 58017.1 62242.7 426002.0 428067.0 431755.0 432267.0 434035.0 43966.6 44105.8 44147.6 44182.8 44224.8 48507.4 48530.0 48534.5 49498.5 49857.8 40186.6 40964.2 41196.4 41489.2 41716.0 39736.0 39765.4 41033.6 41391.8 41655.6 42114.4 42529.8 42820.4 43246.4 45690.3 35563.0 36481.5 37116.6 37210.3 38119.4 28864.9 29162.9 29435.4 29459.9 29493.4 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | [#2] jquery.html Cycles:180 Average:28918.83 Median:2300.06 stddev:81196.03 (3530.2%) stddev-sans-first:81395.54 06:18:44 INFO - PROCESS | 2794 | Values: 390.1 393.9 394.3 397.3 412.9 13788.4 18185.6 18725.8 19993.3 20025.2 18179.9 18490.4 18755.4 18829.6 18967.9 5569.6 5588.3 5715.0 5765.1 5776.2 5306.7 5603.8 5655.6 5710.3 5731.9 5629.3 5641.7 5649.1 5664.9 5697.5 5403.9 5694.5 5738.9 5824.8 5847.1 5571.6 5619.2 5784.8 5799.6 5818.9 21763.0 21798.6 21883.6 22446.2 22533.8 2294.2 2296.6 2303.6 2322.1 2479.6 8934.7 9437.0 9445.4 9458.2 9725.7 1938.8 2006.7 2011.1 2038.2 2055.0 82598.3 82733.0 82939.2 83058.2 83520.2 10662.1 10670.5 10676.0 10895.9 11668.6 15282.5 15353.7 15358.5 15360.8 15377.5 8108.8 8260.8 8363.2 8474.5 8488.2 8140.4 8155.9 8175.0 8337.8 8408.3 4593.5 4609.2 4622.9 4683.7 4788.7 1931.0 1992.4 2006.6 2015.1 2053.9 912.2 988.6 1005.5 1059.6 1079.3 16915.5 17771.9 18009.0 18749.5 19018.3 1036.2 1060.8 1074.5 1109.3 1142.5 10093.1 10236.0 10539.6 10602.5 10894.3 477191.0 484210.0 489145.0 501430.0 508984.0 77878.0 88109.6 88974.6 98045.5 100616.5 1086.0 1115.7 1128.3 1130.3 1136.7 16589.0 16727.0 16876.2 16894.3 17154.1 39550.6 39649.2 40234.4 40876.4 41006.2 57677.0 57710.4 58395.7 58451.7 58923.7 6379.0 8220.1 8469.7 8511.1 8594.5 12799.9 13041.5 13313.0 13390.3 13500.4 11648.5 11756.3 11798.4 11963.8 12067.6 11326.8 11487.8 11573.2 11680.8 11753.9 11885.8 12106.5 12161.5 12172.0 12308.9 7481.7 7986.3 8368.9 8459.2 8509.7 12052.6 12162.6 12174.4 12348.2 12463.3 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | [#3] mootools.html Cycles:180 Average:5971.09 Median:2560.38 stddev:3404.84 (133.0%) stddev-sans-first:3397.96 06:18:44 INFO - PROCESS | 2794 | Values: 1519.3 1551.3 1682.0 1810.2 1827.6 8137.5 8557.6 8603.4 9097.4 9147.7 8685.9 8979.1 9007.8 9032.5 9074.5 2349.2 2372.3 2374.3 2391.8 2392.4 2334.6 2355.6 2370.1 2374.4 2375.1 2297.2 2337.1 2348.6 2370.5 2393.0 2346.2 2360.8 2382.9 2401.7 2410.5 2343.9 2368.7 2371.6 2378.8 2391.6 9019.7 9101.9 9111.2 9126.6 9130.4 5207.4 5341.6 5363.3 5372.9 5436.1 3309.4 3340.8 3391.6 3396.2 3426.8 1095.5 1104.9 1110.0 1114.1 1175.1 12804.6 12820.6 12869.2 12872.9 12889.5 4650.0 4749.7 4781.9 4839.3 4889.0 10641.1 10688.0 10698.7 10705.4 10726.8 5286.7 5313.4 5332.4 5350.5 5353.3 4456.9 4763.9 4886.5 4892.0 5036.8 4046.2 4089.0 4164.3 4173.5 4191.2 1270.8 1309.2 1321.1 1325.3 1325.4 928.4 1038.0 1049.7 1060.6 1061.1 6372.6 6465.0 6509.8 6533.2 6569.1 470.8 472.4 474.0 479.9 488.4 4521.1 4543.9 4578.5 4596.1 4629.1 10820.7 11254.8 11289.6 11316.2 11341.7 10424.4 10966.2 11075.4 11190.2 11261.9 8230.4 8499.4 8538.0 8538.9 8556.6 6245.1 6392.4 6550.7 6552.8 6595.7 10322.7 10521.1 10521.2 10633.0 10640.2 10501.5 10608.2 10676.3 10793.8 10817.0 7950.2 8077.0 8099.1 8176.3 8179.3 8143.3 8161.4 8200.9 8210.3 8231.5 7835.0 7916.1 7987.2 8022.8 8041.7 7359.9 7449.6 7508.1 7528.2 7536.7 7121.1 7124.7 7245.0 7307.3 7373.6 5387.5 5396.5 5421.9 5499.0 5610.8 5540.7 5558.6 5619.5 5664.2 5696.6 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | [#4] prototype.html Cycles:180 Average:4863.53 Median:5485.59 stddev:1441.95 (26.3%) stddev-sans-first:1405.20 06:18:44 INFO - PROCESS | 2794 | Values: 326.3 333.1 333.3 346.9 348.2 5421.3 5471.4 5545.8 5637.0 5671.4 5499.8 5545.8 5549.4 5583.4 5589.4 4282.7 4298.7 4302.8 4304.0 4317.0 4223.9 4303.0 4340.5 4357.9 4363.8 4273.0 4276.9 4280.7 4301.3 4306.8 4310.6 4318.3 4322.8 4330.6 4333.7 3558.4 3833.3 3837.8 3860.7 3864.5 5315.3 5316.0 5348.5 5358.1 5361.7 4930.7 4941.5 4946.0 4950.2 4953.0 5515.4 5798.9 5810.1 5811.3 5852.2 4144.7 4156.6 4177.0 4185.7 4192.7 6127.4 6144.4 6156.5 6158.5 6170.9 4108.5 4160.8 4175.6 4214.1 4240.5 4192.1 4201.2 4201.5 4207.5 4224.5 4844.3 4877.9 4878.3 4896.0 4918.4 5699.7 5891.4 5907.5 5957.8 5963.6 1476.4 1494.9 1495.5 1497.1 1503.3 909.9 914.5 926.4 939.5 940.5 4965.2 5015.7 5082.9 5106.7 5113.3 5351.4 5386.5 5387.5 5388.2 5424.6 5027.7 5063.7 5106.4 5204.8 5735.2 3150.7 3171.8 3192.2 3209.5 3223.9 6093.9 6186.4 6224.8 6235.9 6253.7 6093.5 6103.8 6145.9 6181.6 6217.8 6132.6 6145.2 6152.8 6169.8 6172.1 5776.6 5873.8 5985.5 5989.9 5995.1 5811.7 5911.9 5932.9 5966.2 5974.4 5863.0 6132.9 6140.0 6166.6 6168.7 4185.0 4243.6 4257.1 4277.8 4279.6 5985.2 6042.7 6059.4 6074.8 6086.9 5534.6 5836.0 5836.3 5860.0 5871.4 5783.8 5807.3 5844.7 5856.4 5903.9 5634.5 5766.2 5777.7 5780.8 5836.3 5887.4 5924.6 5934.4 5935.5 5948.7 5734.4 5777.4 5778.7 5796.7 5799.6 06:18:44 INFO - PROCESS | 2794 | 06:18:44 INFO - PROCESS | 2794 | [#5] yui.html Cycles:180 Average:15308.04 Median:48088.90 stddev:18153.16 (37.7%) stddev-sans-first:18170.98 06:18:44 INFO - PROCESS | 2794 | Values: 708.5 720.1 748.8 763.8 827.6 13362.6 14285.8 14313.4 15051.3 15419.2 15505.0 15570.1 15585.1 15629.8 15701.6 681.6 785.3 798.6 810.4 811.4 798.4 798.4 799.0 804.1 806.6 838.4 839.1 844.9 845.1 846.2 843.1 843.2 844.3 844.5 844.6 607.5 609.0 609.6 612.1 612.2 14644.9 14718.3 14719.1 14755.6 14763.9 14001.3 14067.0 14085.4 14184.6 14237.1 11282.8 11345.9 11437.7 11439.4 11681.2 3733.2 3763.3 3782.9 3795.5 3824.8 57855.4 58124.4 58447.4 59065.0 59437.7 10945.6 10955.2 10998.6 11098.0 11130.9 19916.5 19942.4 20041.3 20126.3 20247.2 8181.2 8262.7 8313.9 8378.6 8450.5 4708.8 4762.1 4787.0 4891.3 4954.3 3513.3 3599.3 3599.8 3621.3 3624.0 629.8 631.3 639.5 640.6 656.0 587.1 591.8 647.0 792.8 810.3 9318.4 9559.2 9632.7 9695.9 9897.6 736.7 752.8 754.8 759.4 765.5 4485.6 4772.3 4881.4 4883.1 4943.4 63999.7 64605.0 64738.1 65406.7 65630.0 58494.1 59264.7 59428.4 59569.1 59662.0 52758.5 53289.7 53301.5 53356.5 53550.7 8697.7 8732.6 8814.0 8846.6 9151.5 34488.0 34625.3 34767.7 34919.7 35268.8 44375.8 47759.8 48071.5 48106.3 48310.3 11495.4 11613.0 11770.5 11972.6 12021.5 12842.4 13041.8 13112.3 13132.9 13356.8 11140.4 11166.7 11353.1 11366.7 11616.3 11231.9 11614.7 11753.7 11769.9 11776.1 12096.5 12297.3 12468.9 12516.2 12523.2 9902.7 10521.6 10560.5 10647.9 11261.2 9650.7 10252.2 10283.5 10368.9 10678.3 06:18:44 INFO - PROCESS | 2794 | -------- Summary: end -------- 06:18:44 INFO - PROCESS | 2794 | 06:18:45 INFO - TEST-INFO | 2794: exit 0 06:18:45 INFO - TEST-OK | dromaeo_css | took 1109652ms 06:18:45 INFO - TEST-START | kraken 06:18:45 INFO - operating with platform_type : linux_ 06:18:45 INFO - Initialising browser for kraken test... 06:18:45 INFO - TEST-INFO | started process 3063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Wef4i/profile http://localhost:49289/getInfo.html) 06:18:48 INFO - PROCESS | 3063 | 06:18:48 INFO - PROCESS | 3063 | (process:3130): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:18:49 INFO - PROCESS | 3063 | __metrics Screen width/height:1600/1200 06:18:49 INFO - PROCESS | 3063 | colorDepth:24 06:18:49 INFO - PROCESS | 3063 | Browser inner width/height: 1024/697 06:18:49 INFO - PROCESS | 3063 | __metrics 06:18:50 ERROR - PROCESS | 3063 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 06:18:50 INFO - TEST-INFO | 3063: exit 0 06:18:50 INFO - Browser initialized. 06:18:50 INFO - Running cycle 1/1 for kraken test... 06:18:50 INFO - TEST-INFO | started process 3181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Wef4i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:52 INFO - PROCESS | 3181 | 06:18:52 INFO - PROCESS | 3181 | (process:3241): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:19:02 INFO - PROCESS | 3181 | RSS: Main: 163160064 06:19:02 INFO - PROCESS | 3181 | 06:19:05 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-beat-detection) 06:19:06 INFO - PROCESS | 3181 | RSS: Main: 167473152 06:19:06 INFO - PROCESS | 3181 | 06:19:08 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-dft) 06:19:09 INFO - PROCESS | 3181 | RSS: Main: 167755776 06:19:09 INFO - PROCESS | 3181 | 06:19:12 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-fft) 06:19:12 INFO - PROCESS | 3181 | RSS: Main: 162443264 06:19:12 INFO - PROCESS | 3181 | 06:19:15 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-oscillator) 06:19:15 INFO - PROCESS | 3181 | RSS: Main: 162713600 06:19:15 INFO - PROCESS | 3181 | 06:19:18 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49289/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:19:18 INFO - PROCESS | 3181 | RSS: Main: 163016704 06:19:18 INFO - PROCESS | 3181 | 06:19:21 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49289/tests/kraken/driver.html?testName=imaging-darkroom) 06:19:22 INFO - PROCESS | 3181 | RSS: Main: 163766272 06:19:22 INFO - PROCESS | 3181 | 06:19:25 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49289/tests/kraken/driver.html?testName=imaging-desaturate) 06:19:26 INFO - PROCESS | 3181 | RSS: Main: 161746944 06:19:26 INFO - PROCESS | 3181 | 06:19:29 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49289/tests/kraken/driver.html?testName=json-parse-financial) 06:19:30 INFO - PROCESS | 3181 | RSS: Main: 162852864 06:19:30 INFO - PROCESS | 3181 | 06:19:31 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49289/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:19:32 INFO - PROCESS | 3181 | RSS: Main: 163028992 06:19:32 INFO - PROCESS | 3181 | 06:19:34 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:19:34 INFO - PROCESS | 3181 | RSS: Main: 163168256 06:19:34 INFO - PROCESS | 3181 | 06:19:36 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:19:37 INFO - PROCESS | 3181 | RSS: Main: 163606528 06:19:37 INFO - PROCESS | 3181 | 06:19:40 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:19:40 INFO - PROCESS | 3181 | RSS: Main: 163520512 06:19:40 INFO - PROCESS | 3181 | 06:19:43 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:19:44 INFO - PROCESS | 3181 | RSS: Main: 163835904 06:19:44 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49289/tests/kraken/driver.html?testName=ai-astar) 06:19:46 INFO - PROCESS | 3181 | RSS: Main: 164380672 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | __start_tp_report 06:19:46 INFO - PROCESS | 3181 | _x_x_mozilla_page_load 06:19:46 INFO - PROCESS | 3181 | _x_x_mozilla_page_load_details 06:19:46 INFO - PROCESS | 3181 | |i|pagename|runs| 06:19:46 INFO - PROCESS | 3181 | |0;ai-astar;108;111;105;112;110;113;106;111;114;112 06:19:46 INFO - PROCESS | 3181 | |1;audio-beat-detection;169;127;152;215;126;155;152;128;186;125 06:19:46 INFO - PROCESS | 3181 | |2;audio-dft;162;163;161;162;162;166;168;163;164;164 06:19:46 INFO - PROCESS | 3181 | |3;audio-fft;75;76;76;164;75;76;75;76;180;75 06:19:46 INFO - PROCESS | 3181 | |4;audio-oscillator;92;92;190;91;91;91;151;91;93;145 06:19:46 INFO - PROCESS | 3181 | |5;imaging-gaussian-blur;118;118;118;117;117;117;117;117;118;118 06:19:46 INFO - PROCESS | 3181 | |6;imaging-darkroom;147;146;147;146;146;149;147;148;147;147 06:19:46 INFO - PROCESS | 3181 | |7;imaging-desaturate;96;95;95;96;96;95;95;95;96;95 06:19:46 INFO - PROCESS | 3181 | |8;json-parse-financial;57;60;57;60;60;59;58;108;56;56 06:19:46 INFO - PROCESS | 3181 | |9;json-stringify-tinderbox;58;59;58;59;59;58;58;60;59;59 06:19:46 INFO - PROCESS | 3181 | |10;stanford-crypto-aes;78;78;80;79;81;81;86;79;80;80 06:19:46 INFO - PROCESS | 3181 | |11;stanford-crypto-ccm;124;126;139;128;141;124;127;132;192;127 06:19:46 INFO - PROCESS | 3181 | |12;stanford-crypto-pbkdf2;175;166;164;165;164;165;164;168;165;163 06:19:46 INFO - PROCESS | 3181 | |13;stanford-crypto-sha256-iterative;64;71;69;65;63;63;64;64;64;63 06:19:46 INFO - PROCESS | 3181 | __end_tp_report 06:19:46 INFO - PROCESS | 3181 | __start_cc_report 06:19:46 INFO - PROCESS | 3181 | _x_x_mozilla_cycle_collect,848 06:19:46 INFO - PROCESS | 3181 | __end_cc_report 06:19:46 INFO - PROCESS | 3181 | __startTimestamp1462972786277__endTimestamp 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | ------- Summary: start ------- 06:19:46 INFO - PROCESS | 3181 | Number of tests: 14 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.50 stddev:2.97 (2.7%) stddev-sans-first:3.05 06:19:46 INFO - PROCESS | 3181 | Values: 108.0 111.0 105.0 112.0 110.0 113.0 106.0 111.0 114.0 112.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#1] audio-beat-detection Cycles:10 Average:153.50 Median:153.50 stddev:29.83 (19.4%) stddev-sans-first:31.10 06:19:46 INFO - PROCESS | 3181 | Values: 169.0 127.0 152.0 215.0 126.0 155.0 152.0 128.0 186.0 125.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.12 (1.3%) stddev-sans-first:2.18 06:19:46 INFO - PROCESS | 3181 | Values: 162.0 163.0 161.0 162.0 162.0 166.0 168.0 163.0 164.0 164.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#3] audio-fft Cycles:10 Average:94.80 Median:75.50 stddev:40.87 (54.1%) stddev-sans-first:42.71 06:19:46 INFO - PROCESS | 3181 | Values: 75.0 76.0 76.0 164.0 75.0 76.0 75.0 76.0 180.0 75.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#4] audio-oscillator Cycles:10 Average:112.70 Median:91.00 stddev:35.92 (39.5%) stddev-sans-first:37.31 06:19:46 INFO - PROCESS | 3181 | Values: 92.0 92.0 190.0 91.0 91.0 91.0 151.0 91.0 93.0 145.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 06:19:46 INFO - PROCESS | 3181 | Values: 118.0 118.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 06:19:46 INFO - PROCESS | 3181 | Values: 147.0 146.0 147.0 146.0 146.0 149.0 147.0 148.0 147.0 147.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 06:19:46 INFO - PROCESS | 3181 | Values: 96.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:58.50 stddev:15.86 (27.1%) stddev-sans-first:16.66 06:19:46 INFO - PROCESS | 3181 | Values: 57.0 60.0 57.0 60.0 60.0 59.0 58.0 108.0 56.0 56.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 06:19:46 INFO - PROCESS | 3181 | Values: 58.0 59.0 58.0 59.0 59.0 58.0 58.0 60.0 59.0 59.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:2.30 (2.9%) stddev-sans-first:2.30 06:19:46 INFO - PROCESS | 3181 | Values: 78.0 78.0 80.0 79.0 81.0 81.0 86.0 79.0 80.0 80.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:130.00 stddev:20.55 (15.8%) stddev-sans-first:21.33 06:19:46 INFO - PROCESS | 3181 | Values: 124.0 126.0 139.0 128.0 141.0 124.0 127.0 132.0 192.0 127.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:165.00 stddev:3.48 (2.1%) stddev-sans-first:1.45 06:19:46 INFO - PROCESS | 3181 | Values: 175.0 166.0 164.0 165.0 164.0 165.0 164.0 168.0 165.0 163.0 06:19:46 INFO - PROCESS | 3181 | 06:19:46 INFO - PROCESS | 3181 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:64.00 stddev:2.75 (4.3%) stddev-sans-first:2.89 06:19:46 INFO - PROCESS | 3181 | Values: 64.0 71.0 69.0 65.0 63.0 63.0 64.0 64.0 64.0 63.0 06:19:46 INFO - PROCESS | 3181 | -------- Summary: end -------- 06:19:46 INFO - PROCESS | 3181 | 06:19:47 INFO - TEST-INFO | 3181: exit 0 06:19:47 INFO - TEST-OK | kraken | took 62147ms 06:19:47 INFO - SUITE-END | took 1171s 06:19:47 INFO - Completed test suite (00:19:32) 06:19:47 INFO - javascript benchmark 06:19:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.0549835049487, 3154.88957886474, 3184.1561986073693, 3198.8400579970994, 3203.19920019995, 5321.946780532194, 5327.200919862023, 5348.331458567679, 5396.082665946788, 5399.19012148178, 5267.183586544092, 5344.118220493614, 5404.108322126855, 5478.096114141172, 5488.53347465466, 4795.328653988445, 4831.37192165018, 4873.098476781806, 4957.454679985199, 5009.148444764399, 4812.23004319309, 4994.325766021577, 5025.145725226702, 5046.293518907356, 5048.671836330645, 4832.081904438181, 4857.319975203474, 4887.462379138292, 4952.925706114411, 4958.900821983558, 4873.074115917964, 4893.143699852511, 4893.168161412568, 4915.5330243626795, 4916.557509824097, 4879.121758083544, 4881.926771098437, 4889.706617602919, 4895.412550493964, 4913.557779799799, 5159.458256883011, 5306.548943339821, 5317.202419637047, 5365.03429382711, 5396.839679468933, 5849.561282903799, 5864.266966629171, 5873.030949893263, 5886.234789477383, 5895.61678490901, 5686.943130568683, 5916.083007133901, 5920.171176035358, 5934.732937017845, 5942.881142377171, 5349.43830897754, 5380.354357477088, 5398.163284690879, 5399.487048730325, 5412.161115027177, 5843.474087332161, 5845.678487683218, 5854.531637468992, 5856.414358564195, 5874.500667443315, 5532.529734972572, 5614.466625670637, 5622.353429355595, 5629.915551266734, 5630.268065151504, 5766.855828604318, 5811.389804070512, 5813.04084825999, 5813.970930145322, 5871.706414679249, 5592.692401917933, 5636.971815140898, 5777.19119323295, 5783.392743761844, 5813.88372232553, 5579.46995035471, 5680.7727690892725, 5710.143478478179, 5718.25662663851, 5771.971140144273, 4351.521332653406, 4351.521332653406, 4386.780660966939, 4388.626966707801, 4391.978040109779, 3921.568627450978, 3934.11482416454, 3941.0541470047556, 3947.1908258806875, 3972.4041393791163, 5431.728413579384, 5490.203920431515, 5530.225768392428, 5548.55611551075, 5552.7223638817895, 5755.769769209269, 5784.074548072288, 5791.768329266866, 5820.35976042635, 5821.446962538552, 5552.666839989613, 5553.750081246354, 5560.360558535739, 5572.665640061609, 5581.413951535112, 4917.434495033045, 4939.580135688435, 4948.331975183376, 4964.230544265644, 4989.700617962933, 5898.292204935436, 5925.703714814242, 5951.047832346891, 5952.821415357546, 5961.2846458425265, 5830.504407125357, 5839.795607153899, 5842.2405087338375, 5844.620099693507, 5847.473727364558, 5845.152452894222, 5851.444112809447, 5852.736626851887, 5901.114832775118, 5958.702064896825, 5741.081426971831, 5743.282089738783, 5744.971275143597, 5753.798617048383, 5765.625234359753, 5706.2011318414625, 5840.065589505659, 5842.006858833924, 5844.8310337933435, 5867.559933004956, 5683.175939488834, 5737.08206686928, 5743.62666426681, 5746.9712651436475, 5752.050911599539, 5512.4211957745465, 5679.574031947538, 5734.254546908875, 5740.712964351849, 5749.511291540099, 5603.719814009365, 5669.177969194526, 5677.914831277617, 5678.602497825113, 5695.288088988877, 5619.213310136666, 5669.177969194526, 5747.655140691572, 5749.338826035059, 5804.593678442468, 5656.43435656431, 5700.25896633435, 5707.600467967369, 5734.5125664319, 5767.452092051249], "value": 5338.196984201112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.7725698349152, 639.5746828359139, 642.1299139665753, 675.0785178231715, 678.067656971664, 37181.0, 41609.0, 41732.0, 41785.79107104464, 41828.79085604575, 40862.161202745876, 41523.58476415235, 41561.58438415615, 41718.582814171925, 41895.162096758046, 8744.30045596354, 8898.065703101178, 8904.109589041076, 8907.955460222689, 8913.910860891372, 8669.95665021674, 8725.869111963326, 8802.163794439519, 8809.0, 8813.779655508599, 8817.515036672981, 8873.11268873114, 8877.378583499158, 8883.73348799537, 8916.73249802507, 8795.0, 8804.383693141483, 8856.601452934665, 8860.734177974671, 8865.734027979172, 8751.124887511261, 8815.559222038872, 8826.602802873822, 8834.646614135427, 8887.289016878634, 36799.816000919825, 39024.41463378023, 39407.211855762725, 39469.40795888064, 39535.40696889549, 3519.0322661268356, 3521.7182625389905, 3592.4072528032843, 3977.244323578511, 4213.51544572375, 30343.241418964702, 30437.695623043706, 30701.385972280652, 30770.38459230825, 31724.682753172405, 4155.743372648909, 4158.542560318362, 4234.830606775725, 4295.226859165349, 4308.3968244445805, 294818.0, 295693.0, 297250.0, 297753.0, 297851.0, 35340.823295883354, 35697.0, 35865.282694345966, 35865.82067089648, 36823.0, 37158.8142059288, 37174.62825371766, 37187.628123718416, 37205.81397092997, 37324.25351492955, 16147.596310092107, 16281.185940702917, 16287.348506059861, 16307.0, 16322.0, 12526.74946501065, 12558.120931534888, 12599.811002834966, 12633.810492842615, 12640.620781376574, 8530.957345213359, 8572.35707321941, 8605.096464871223, 8630.78423039429, 8671.913280867111, 3258.9348213035605, 3280.803151810899, 3323.135984643962, 3332.3168750406594, 3354.9496757548654, 1039.9220058495491, 1048.2871647279771, 1184.5321098166348, 1203.0676225924803, 1223.7797196504537, 31199.192072713566, 31540.36919261648, 31589.526157107663, 32031.839840800647, 32539.837300813346, 1152.2913408253864, 1177.5407591039502, 1184.7393573413835, 1191.624638238952, 1219.7377563823643, 16457.177141142896, 16741.832581674273, 16785.136916457286, 17069.658606827794, 17210.139493025297, 450775.0, 451737.70655733213, 452279.0, 454746.0, 456542.0, 58452.41547584555, 58695.70652146712, 59065.70467147637, 59068.704656476446, 59074.0, 1377.5178687459509, 1386.6533366658336, 1407.2189934586197, 1407.4651632379835, 1418.5389748331627, 30358.696413036472, 31221.687783121877, 31363.215919602193, 31636.209094772814, 32179.51730723994, 49375.753121234164, 56268.0, 57909.71045144747, 58017.129743054735, 62242.68878655578, 426002.0, 428067.0, 431755.0, 432267.0, 434035.0, 43966.56033439752, 44105.77947110244, 44147.558524414344, 44182.77908610565, 44224.77887610541, 48507.39179777916, 48530.0, 48534.514654853, 49498.50501494939, 49857.75071124621, 40186.598134018284, 40964.1807163861, 41196.38205426981, 41489.1702165961, 41716.0, 39736.0, 39765.409383624334, 41033.589664102976, 41391.7930410346, 41655.58344416638, 42114.36828447637, 42529.787351063045, 42820.35769463398, 43246.351304731084, 45690.31464528101, 35563.0, 36481.452778207815, 37116.6288337124, 37210.25579488449, 38119.42820857745, 28864.855675721486, 29162.854185728935, 29435.41129177447, 29459.85270073636, 29493.410131796816], "value": 18906.26740477875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.0638467677574, 393.9310620641387, 394.292245419472, 397.3106659944995, 412.8596277265731, 13788.379522921468, 18185.636287274247, 18725.812741872578, 19993.300234491795, 20025.198992040318, 18179.90910045451, 18490.352837650684, 18755.43733687988, 18829.623407531843, 18967.905160474213, 5569.554435645159, 5588.329400471949, 5714.9714251428895, 5765.1352297155345, 5776.162456443813, 5306.734663266821, 5603.775848966036, 5655.575831812631, 5710.2862142232225, 5731.914021289684, 5629.324481062258, 5641.68970706611, 5649.12438572022, 5664.943350566483, 5697.487226149646, 5403.891922161554, 5694.544436445074, 5738.942610573882, 5824.825255242349, 5847.06446968483, 5571.609987300891, 5619.157126431027, 5784.826455206308, 5799.5650326225705, 5818.941810581882, 21763.0, 21798.56402871934, 21883.562328753334, 22446.214382496535, 22533.774662253494, 2294.219965211836, 2296.552172326397, 2303.57383883982, 2322.1059891941554, 2479.6280557916275, 8934.687285945025, 9436.952815235949, 9445.433274003582, 9458.243340532741, 9725.708228753148, 1938.8255057044935, 2006.6989951507244, 2011.1050582490798, 2038.1847261095531, 2054.979450205509, 82598.3480330396, 82733.0, 82939.17060829375, 83058.16941830565, 83520.16479835185, 10662.147028237723, 10670.519826607666, 10676.0, 10895.891041089646, 11668.649940501798, 15282.541523754306, 15353.692926141637, 15358.462453814063, 15360.769588456182, 15377.538673839803, 8108.8378232435025, 8260.793480163044, 8363.20549547792, 8474.491530508189, 8488.193621605938, 8140.389470789714, 8155.918440815634, 8175.0, 8337.791555211168, 8408.32733381328, 4593.471750748707, 4609.216433206363, 4622.884427889329, 4683.672142949961, 4788.688735232199, 1930.9517262068562, 1992.4221975627195, 2006.5886493268845, 2015.1032790408128, 2053.856230063881, 912.2291663544295, 988.6391467114595, 1005.4603465353932, 1059.6344261229863, 1079.3416016230094, 16915.492535223962, 17771.911140444216, 18009.0, 18749.531261718294, 19018.33435829767, 1036.2487196782272, 1060.7613287010515, 1074.521837782195, 1109.312226419625, 1142.4801715219555, 10093.142082922886, 10236.0, 10539.57841686324, 10602.469876506144, 10894.346339219672, 477191.0, 484210.0, 489145.0, 501430.0, 508984.0, 77878.0, 88109.55945220233, 88974.55512722395, 98045.50977245068, 100616.49691751495, 1085.9728506787394, 1115.6764538283808, 1128.311729844794, 1130.3331034689572, 1136.7442325476702, 16589.0, 16726.996380217224, 16876.24056917466, 16894.324227030782, 17154.142292885055, 39550.60449395584, 39649.2070158601, 40234.39648405218, 40876.38685419781, 41006.1798764029, 57677.0, 57710.422895772186, 58395.70802145962, 58451.70774146102, 58923.70538147282, 6379.043143528323, 8220.054693710299, 8469.703560375603, 8511.063782983743, 8594.519219716025, 12799.936000319938, 13041.543545975845, 13313.0, 13390.26353550564, 13500.392482338515, 11648.475818588353, 11756.294622322688, 11798.41007949582, 11963.76072478563, 12067.57763478274, 11326.773464530828, 11487.770244595229, 11573.247738896946, 11680.76638467209, 11753.941230293794, 11885.76228475443, 12106.455209515418, 12161.45273462714, 12172.0, 12308.938455307667, 7481.738139165103, 7986.321162701235, 8368.916310836814, 8459.196376344238, 8509.702160424355, 12052.578159764365, 12162.635120946386, 12174.391280435766, 12348.197367171106, 12463.252204867737], "value": 9200.439959253477, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.2707500399808, 1551.2941611566737, 1681.9915900420497, 1810.1944634637584, 1827.5613852675363, 8137.471064380811, 8557.614907329169, 8603.397762156652, 9097.408668436556, 9147.6798312059, 8685.869711954325, 8979.057198994109, 9007.774805629846, 9032.548372581361, 9074.455532668027, 2349.2130136404326, 2372.323887692008, 2374.287713685896, 2391.8445301055376, 2392.35406440261, 2334.5681049005902, 2355.5759963206615, 2370.099362242354, 2374.4063984004, 2375.0974629640714, 2297.2304278066786, 2337.1469413663976, 2348.5655153796515, 2370.4547953970596, 2392.9880350598223, 2346.1905642553293, 2360.834741568091, 2382.916597919082, 2401.7117945846435, 2410.4696966667307, 2343.8593684378993, 2368.6683864259016, 2371.5968285391355, 2378.7859092681742, 2391.6173412253956, 9019.684311049083, 9101.863472047859, 9111.17999380059, 9126.58930348136, 9130.406523575946, 5207.3751149862255, 5341.572674186055, 5363.329583802025, 5372.946270537283, 5436.103042997901, 3309.420851351004, 3340.7828491148, 3391.6099648540485, 3396.235846934445, 3426.7601267911255, 1095.5015467962057, 1104.9116070714404, 1109.955601775928, 1114.1254115519278, 1175.142146233246, 12804.551840685532, 12820.551280705224, 12869.163504372185, 12872.935635321857, 12889.548865789746, 4650.0, 4749.715017098985, 4781.880452988703, 4839.322494850724, 4888.9755551222015, 10641.148708103334, 10688.0, 10698.679039628823, 10705.411202383788, 10726.785464290671, 5286.656367336188, 5313.415524292324, 5332.360116786011, 5350.51845333914, 5353.2773075634295, 4456.933146002748, 4763.880902977384, 4886.486918873538, 4892.0, 5036.84889453317, 4046.231216068996, 4088.9591104088577, 4164.292070348048, 4173.457450531411, 4191.1827193697545, 1270.7776139175608, 1309.1948451702135, 1321.0752473268751, 1325.2578556008666, 1325.4300650720284, 928.4475736936512, 1037.9532921018572, 1049.7113293844102, 1060.596973150198, 1061.124572227915, 6372.585781924253, 6464.967675161594, 6509.772157974543, 6533.216014078341, 6569.080328753978, 470.7575598566741, 472.376463068746, 474.0471651979546, 479.90641824844516, 488.38463535944254, 4521.095780843844, 4543.863684089482, 4578.496365399803, 4596.057808149321, 4629.1436084324505, 10820.729481763019, 11254.831177532344, 11289.60486382989, 11316.15128865322, 11341.659750207342, 10424.426656533811, 10966.177536684781, 11075.446227688582, 11190.160737944523, 11261.887381126247, 8230.42387032902, 8499.447535910145, 8538.0, 8538.871916921127, 8556.61495232716, 6245.094461302995, 6392.360763923571, 6550.672466376567, 6552.83617909537, 6595.670216489252, 10322.690319290434, 10521.053105220566, 10521.21090918169, 10633.0, 10640.201984851012, 10501.527431265764, 10608.151347892304, 10676.306040107369, 10793.838092428463, 10817.026467617952, 7950.204979502004, 8076.959615202121, 8099.109097999333, 8176.264136227768, 8179.304759095306, 8143.348532117535, 8161.387895907712, 8200.917990820015, 8210.3021243195, 8231.547264900488, 7835.0, 7916.129225785044, 7987.201279871966, 8022.799430014063, 8041.718539851077, 7359.852802944019, 7449.553026818408, 7508.0990281166605, 7528.20953799865, 7536.7362142324755, 7121.14546254432, 7124.679389427592, 7245.021922040668, 7307.26927307265, 7373.5575865448245, 5387.542058925035, 5396.460353964572, 5421.9186712200135, 5499.0, 5610.803621873215, 5540.667559946416, 5558.583106266965, 5619.522340601095, 5664.207011018543, 5696.629719068247], "value": 4701.011983737892, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.3033423640527, 333.13219064337386, 333.3017328696381, 346.91500582357327, 348.242572405019, 5421.322334708161, 5471.425500322469, 5545.778168873246, 5637.0, 5671.432856714332, 5499.752511136999, 5545.805896793613, 5549.361823390312, 5583.413741557129, 5589.441055894418, 4282.678799090065, 4298.7205831621, 4302.763348015857, 4304.0, 4316.97841510792, 4223.852165174219, 4302.97848510757, 4340.52254252032, 4357.912841743163, 4363.759993200372, 4272.957270427302, 4276.850310239142, 4280.72175308604, 4301.333293339538, 4306.763128027973, 4310.568943105695, 4318.330658747899, 4322.805473753689, 4330.610245077943, 4333.65330773539, 3558.448440491728, 3833.271678381099, 3837.827297771607, 3860.749051311656, 3864.536255649312, 5315.255864179018, 5316.0, 5348.545373643245, 5358.142697168473, 5361.651492652965, 4930.679505832145, 4941.50584941503, 4946.0, 4950.183219768734, 4952.950470495285, 5515.365732940683, 5798.9130163047585, 5810.099434587646, 5811.331696854873, 5852.215074402306, 4144.668426525871, 4156.625903668654, 4176.997520595066, 4185.727927684691, 4192.664586833046, 6127.387261273837, 6144.446999769997, 6156.507479401637, 6158.5381096417495, 6170.8765824683705, 4108.541467778955, 4160.812763425653, 4175.561566035583, 4214.136102099063, 4240.49114106303, 4192.119654872511, 4201.2017716634355, 4201.516825565099, 4207.453031105998, 4224.4508213931995, 4844.346013288232, 4877.902471943784, 4878.268259760993, 4895.951040489621, 4918.409790825124, 5699.686517241508, 5891.410858914074, 5907.4683278505145, 5957.79147729836, 5963.5527335450015, 1476.3799204334205, 1494.8654621083938, 1495.4691084665005, 1497.09425797391, 1503.2859391788813, 909.8680691299725, 914.4970266353479, 926.4487629860222, 939.52553960251, 940.4545363689044, 4965.205567109318, 5015.724135172528, 5082.898342033212, 5106.66806657566, 5113.284140220372, 5351.438098999627, 5386.542143917811, 5387.515123638891, 5388.218708287276, 5424.647397919122, 5027.698338099725, 5063.696178229318, 5106.4382917879, 5204.76578553966, 5735.225744524436, 3150.692462627984, 3171.7938334008218, 3192.2498212919945, 3209.534617480499, 3223.9194020149685, 6093.878122437526, 6186.350433204539, 6224.844378890473, 6235.944387250214, 6253.6873156342, 6093.542984276197, 6103.8474038148515, 6145.938540614626, 6181.567290289727, 6217.751289948352, 6132.62016046386, 6145.201123853871, 6152.78465253714, 6169.8457538561, 6172.135900973867, 5776.595638305278, 5873.823785286449, 5985.533544281679, 5989.8918700038885, 5995.100734889801, 5811.692369216892, 5911.852203694942, 5932.940670593412, 5966.164736936746, 5974.40255974399, 5863.009864968812, 6132.9080063799975, 6139.986902161093, 6166.568340216142, 6168.673735146965, 4184.974681203124, 4243.639290660205, 4257.148570286017, 4277.786110694515, 4279.550647182125, 5985.162077309267, 6042.728077236623, 6059.424354686298, 6074.787382441594, 6086.865264388754, 5534.612577119563, 5836.0, 5836.270466191726, 5860.0330945395235, 5871.442212989759, 5783.843231353662, 5807.2670773319605, 5844.6785426801935, 5856.443637854397, 5903.881922361615, 5634.549236061187, 5766.163906233656, 5777.682227477361, 5780.797672081457, 5836.299644042742, 5887.4406931341455, 5924.644521328734, 5934.376890426443, 5935.495482884004, 5948.73230704628, 5734.426557344399, 5777.422257774189, 5778.682172480554, 5796.739146738322, 5799.565032622486], "value": 4398.954055196905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.4934271995522, 720.0639169080198, 748.8127968007998, 763.7899577616155, 827.648249493965, 13362.599122026342, 14285.785713214296, 14313.427462901485, 15051.322690478928, 15419.229038548056, 15505.0, 15570.065796052244, 15585.142817145052, 15629.765553516707, 15701.607459813482, 681.6012632609929, 785.2618538573751, 798.5647821937043, 810.4367464612097, 811.3995643224001, 798.3732769775727, 798.4450806689354, 798.9640466179036, 804.1315379390245, 806.5644551941973, 838.4214891724721, 839.0895877972407, 844.9197326254027, 845.1379592815325, 846.1876598465481, 843.1189407069596, 843.2200214801314, 844.3034496540317, 844.4933040175907, 844.624142256696, 607.4563265877015, 608.9817305480842, 609.5580703989616, 612.0757655939519, 612.1613389656155, 14644.853551464563, 14718.33767480466, 14719.116852988855, 14755.55733328002, 14763.92618036903, 14001.299935003311, 14067.0, 14085.366158522891, 14184.57446276603, 14237.145771253758, 11282.774344513064, 11345.886541134565, 11437.656870293904, 11439.42802859862, 11681.2407193533, 3733.178700685845, 3763.2849758546063, 3782.9054273643105, 3795.4876091727538, 3824.7513911595656, 57855.42144578585, 58124.41875581232, 58447.41552584462, 59065.0, 59437.702811485666, 10945.562177512811, 10955.17836162291, 10998.560057597606, 11098.0, 11130.888691113147, 19916.50208744793, 19942.401727948185, 20041.29855455052, 20126.29557965464, 20247.19011239534, 8181.181881811771, 8262.686447794627, 8313.875291870627, 8378.581070946428, 8450.492970421794, 4708.788104535236, 4762.14281429346, 4786.976065119652, 4891.26631005352, 4954.3063971044085, 3513.3149035937745, 3599.262151258986, 3599.8380072896784, 3621.311950729353, 3624.0, 629.7827249598881, 631.3024108360196, 639.5459223951046, 640.5676168586278, 656.0094257670951, 587.0548417048549, 591.8165368735706, 646.9644169570624, 792.7598573429625, 810.295043312322, 9318.394304370195, 9559.235261179088, 9632.662856799978, 9695.93344732079, 9897.554610042565, 736.6758626204453, 752.8306131120454, 754.8226166850785, 759.4304271796153, 765.4947734495206, 4485.6411487081605, 4772.308015337757, 4881.365422495053, 4883.121038213085, 4943.3573635427165, 63999.68000159969, 64605.0, 64738.05785826433, 65406.67296663486, 65630.0, 58494.12258816121, 59264.70367648134, 59428.40571594229, 59569.10646340222, 59662.0, 52758.472415275355, 53289.73355133199, 53301.46698533043, 53356.46643533515, 53550.7322463393, 8697.652093916173, 8732.60703268367, 8814.0, 8846.557672116498, 9151.542422878963, 34488.0, 34625.30749385049, 34767.65232347745, 34919.65080349164, 35268.823655881555, 44375.77812111048, 47759.761201193774, 48071.519284808106, 48106.27840582464, 48310.309139181365, 11495.425228738697, 11613.0, 11770.470328835052, 11972.64082077539, 12021.519139234682, 12842.357882106044, 13041.804372934224, 13112.344382780631, 13132.868671313547, 13356.799648005483, 11140.387278699425, 11166.707951449096, 11353.148513861328, 11366.669987050569, 11616.303021818718, 11231.943840280746, 11614.702560052834, 11753.706157345792, 11769.882301176878, 11776.11679124052, 12096.455659495168, 12297.32364719949, 12468.875311246771, 12516.186447881222, 12523.185992910432, 9902.712647355798, 10521.579586755995, 10560.471976401303, 10647.893521065, 11261.21171517986, 9650.704701153894, 10252.179825613784, 10283.537240824331, 10368.948155259175, 10678.305910115818], "value": 6806.473581414909, "unit": "score"}], "name": "dromaeo_css", "value": 7123.915599738697, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 105.0, 112.0, 110.0, 113.0, 106.0, 111.0, 114.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 127.0, 152.0, 215.0, 126.0, 155.0, 152.0, 128.0, 186.0, 125.0], "value": 153.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 162.0, 162.0, 166.0, 168.0, 163.0, 164.0, 164.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 164.0, 75.0, 76.0, 75.0, 76.0, 180.0, 75.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 190.0, 91.0, 91.0, 91.0, 151.0, 91.0, 93.0, 145.0], "value": 112.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 149.0, 147.0, 148.0, 147.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 57.0, 60.0, 60.0, 59.0, 58.0, 108.0, 56.0, 56.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 59.0, 58.0, 58.0, 60.0, 59.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 80.0, 79.0, 81.0, 81.0, 86.0, 79.0, 80.0, 80.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 126.0, 139.0, 128.0, 141.0, 124.0, 127.0, 132.0, 192.0, 127.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 166.0, 164.0, 165.0, 164.0, 165.0, 164.0, 168.0, 165.0, 163.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 69.0, 65.0, 63.0, 63.0, 64.0, 64.0, 64.0, 63.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1563.5000000000002, "alertThreshold": 2.0}]} 06:19:47 INFO - Return code: 0 06:19:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:19:47 ERROR - # TBPL SUCCESS # 06:19:47 INFO - Running post-action listener: _resource_record_post_action 06:19:47 INFO - ##### 06:19:47 INFO - ##### Finished run-tests step (success) 06:19:47 INFO - ##### 06:19:47 INFO - Running post-run listener: _resource_record_post_run 06:19:48 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 270336; Write bytes: 551452672; Read time: 44; Write time: 452736 06:19:48 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244891648; Read time: 0; Write time: 256452 06:19:48 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 270336; Write bytes: 291270656; Read time: 44; Write time: 190976 06:19:48 INFO - Running post-run listener: _upload_blobber_files 06:19:48 INFO - Blob upload gear active. 06:19:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:19:48 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 06:19:48 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:19:48 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:19:49 INFO - (blobuploader) - INFO - Open directory for files ... 06:19:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:19:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:19:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:49 INFO - (blobuploader) - INFO - Done attempting. 06:19:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:19:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:19:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:50 INFO - (blobuploader) - INFO - Done attempting. 06:19:50 INFO - (blobuploader) - INFO - Iteration through files over. 06:19:50 INFO - Return code: 0 06:19:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:19:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:19:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0201c4784c3c7603ff08e7f42d1bd24390978aaa98906d8f271c9a44a0efed4d4fd92970eb72190a4c213795cd7bb460af35b489d6e361301a00796f8295ba8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f46e4806c605ac3d533724d70d02ee213f427020a50b9121608960b7ceb42d13040617fce93b8bee1c2925fb56caa9400be1bbb037e571a8996ecdc5ca7bd66"} 06:19:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:19:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:19:50 INFO - Contents: 06:19:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0201c4784c3c7603ff08e7f42d1bd24390978aaa98906d8f271c9a44a0efed4d4fd92970eb72190a4c213795cd7bb460af35b489d6e361301a00796f8295ba8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f46e4806c605ac3d533724d70d02ee213f427020a50b9121608960b7ceb42d13040617fce93b8bee1c2925fb56caa9400be1bbb037e571a8996ecdc5ca7bd66"} 06:19:50 INFO - Running post-run listener: copy_logs_to_upload_dir 06:19:50 INFO - Copying logs to upload dir... 06:19:50 INFO - mkdir: /builds/slave/test/build/upload/logs 06:19:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1227.626479 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-05-11 06:19:50.609699) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 06:19:50.613049) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479 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/Fx-Team/sha512/f0201c4784c3c7603ff08e7f42d1bd24390978aaa98906d8f271c9a44a0efed4d4fd92970eb72190a4c213795cd7bb460af35b489d6e361301a00796f8295ba8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f46e4806c605ac3d533724d70d02ee213f427020a50b9121608960b7ceb42d13040617fce93b8bee1c2925fb56caa9400be1bbb037e571a8996ecdc5ca7bd66"} build_url:https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011524 build_url: 'https://queue.taskcluster.net/v1/task/AIrobmLnT2yDINrPwj330g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0201c4784c3c7603ff08e7f42d1bd24390978aaa98906d8f271c9a44a0efed4d4fd92970eb72190a4c213795cd7bb460af35b489d6e361301a00796f8295ba8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f46e4806c605ac3d533724d70d02ee213f427020a50b9121608960b7ceb42d13040617fce93b8bee1c2925fb56caa9400be1bbb037e571a8996ecdc5ca7bd66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 06:19:50.639928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 06:19:50.640381) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462971560.861002-859796479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 06:19:50.723404) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 06:19:50.723749) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 06:19:50.724056) ========= ========= Total master_lag: 0.19 =========