builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1462792973.52 results: success (0) buildid: 20160509024850 builduid: 19c9eb1de1f448e0a508eb57f0ef353e revision: cb53cee341a60d134fd5dc55ead4a95632a22dce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.522120) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.522555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.522828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.598981) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.599282) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-09 04:22:53-- 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% 224M=0s 2016-05-09 04:22:53 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158637 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.770926) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.771231) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016347 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:53.820158) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:22:53.820512) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cb53cee341a60d134fd5dc55ead4a95632a22dce --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 cb53cee341a60d134fd5dc55ead4a95632a22dce --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=7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-09 04:22:53,922 truncating revision to first 12 chars 2016-05-09 04:22:53,922 Setting DEBUG logging. 2016-05-09 04:22:53,922 attempt 1/10 2016-05-09 04:22:53,923 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cb53cee341a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 04:22:55,004 unpacking tar archive at: fx-team-cb53cee341a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.390842 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:22:55.224496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:55.224827) ========= script_repo_revision: cb53cee341a60d134fd5dc55ead4a95632a22dce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:55.225199) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:55.225473) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:22:55.234487) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-09 04:22:55.234811) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:22:55 INFO - MultiFileLogger online at 20160509 04:22:55 in /builds/slave/test 04:22:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 04:22:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:55 INFO - {'append_to_log': False, 04:22:55 INFO - 'base_work_dir': '/builds/slave/test', 04:22:55 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:22:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:55 INFO - 'branch': 'Fx-Team-Non-PGO', 04:22:55 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:55 INFO - 'config_files': ('talos/linux_config.py',), 04:22:55 INFO - 'default_actions': ('clobber', 04:22:55 INFO - 'read-buildbot-config', 04:22:55 INFO - 'download-and-extract', 04:22:55 INFO - 'populate-webroot', 04:22:55 INFO - 'create-virtualenv', 04:22:55 INFO - 'install', 04:22:55 INFO - 'run-tests'), 04:22:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:55 INFO - 'download_minidump_stackwalk': True, 04:22:55 INFO - 'download_symbols': 'ondemand', 04:22:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:22:55 INFO - 'tooltool.py': '/tools/tooltool.py', 04:22:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:22:55 INFO - '/tools/misc-python/virtualenv.py')}, 04:22:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:55 INFO - 'installer_path': 'installer.exe', 04:22:55 INFO - 'log_level': 'info', 04:22:55 INFO - 'log_name': 'talos', 04:22:55 INFO - 'log_to_console': True, 04:22:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:22:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:22:55 INFO - 'opt_config_files': (), 04:22:55 INFO - 'pip_index': False, 04:22:55 INFO - 'sps_profile': False, 04:22:55 INFO - 'sps_profile_interval': 0, 04:22:55 INFO - 'suite': 'dromaeojs-e10s', 04:22:55 INFO - 'system_bits': '32', 04:22:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:55 INFO - 'title': 'talos-linux64-ix-005', 04:22:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:55 INFO - 'use_talos_json': True, 04:22:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:55 INFO - 'work_dir': 'build'} 04:22:55 INFO - ##### 04:22:55 INFO - ##### Running clobber step. 04:22:55 INFO - ##### 04:22:55 INFO - Running pre-action listener: _resource_record_pre_action 04:22:55 INFO - Running main action method: clobber 04:22:55 INFO - rmtree: /builds/slave/test/build 04:22:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:55 INFO - Running post-action listener: _resource_record_post_action 04:22:55 INFO - ##### 04:22:55 INFO - ##### Finished clobber step (success) 04:22:55 INFO - ##### 04:22:55 INFO - ##### 04:22:55 INFO - ##### Running read-buildbot-config step. 04:22:55 INFO - ##### 04:22:55 INFO - Running pre-action listener: _resource_record_pre_action 04:22:55 INFO - Running main action method: read_buildbot_config 04:22:55 INFO - Using buildbot properties: 04:22:55 INFO - { 04:22:55 INFO - "project": "", 04:22:55 INFO - "product": "firefox", 04:22:55 INFO - "script_repo_revision": "production", 04:22:55 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 04:22:55 INFO - "repository": "", 04:22:55 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 04:22:55 INFO - "buildid": "20160509024850", 04:22:55 INFO - "pgo_build": "False", 04:22:55 INFO - "basedir": "/builds/slave/test", 04:22:55 INFO - "buildnumber": 1263, 04:22:55 INFO - "slavename": "talos-linux64-ix-005", 04:22:55 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:22:55 INFO - "platform": "ubuntu64_hw", 04:22:55 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:22:55 INFO - "branch": "fx-team", 04:22:55 INFO - "repo_path": "integration/fx-team", 04:22:55 INFO - "revision": "cb53cee341a60d134fd5dc55ead4a95632a22dce", 04:22:55 INFO - "stage_platform": "linux64", 04:22:55 INFO - "builduid": "19c9eb1de1f448e0a508eb57f0ef353e", 04:22:55 INFO - "slavebuilddir": "test" 04:22:55 INFO - } 04:22:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 04:22:55 INFO - Running post-action listener: _resource_record_post_action 04:22:55 INFO - ##### 04:22:55 INFO - ##### Finished read-buildbot-config step (success) 04:22:55 INFO - ##### 04:22:55 INFO - ##### 04:22:55 INFO - ##### Running download-and-extract step. 04:22:55 INFO - ##### 04:22:55 INFO - Running pre-action listener: _resource_record_pre_action 04:22:55 INFO - Running main action method: download_and_extract 04:22:55 INFO - mkdir: /builds/slave/test/build/tests 04:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:55 INFO - https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:22:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:22:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:22:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:22:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:22:57 INFO - Downloaded 1361 bytes. 04:22:57 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:22:57 INFO - Using the following test package requirements: 04:22:57 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:22:57 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:22:57 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:22:57 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'jsshell-linux-x86_64.zip'], 04:22:57 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:22:57 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:22:57 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:22:57 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:22:57 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:22:57 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:22:57 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:57 INFO - https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:22:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:22:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:22:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:22:58 INFO - Downloaded 22322863 bytes. 04:22:58 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'] 04:22:58 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 04:22:59 INFO - Return code: 0 04:22:59 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 04:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:59 INFO - https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:22:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:22:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:22:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:23:03 INFO - Downloaded 22322863 bytes. 04:23:03 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'] 04:23:03 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 04:23:04 INFO - Return code: 0 04:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:04 INFO - https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:23:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:23:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:23:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:23:05 INFO - Downloaded 10840152 bytes. 04:23:05 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'] 04:23:05 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 04:23:06 INFO - Return code: 0 04:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:06 INFO - https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:23:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:23:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:23:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:23:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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 04:23:07 INFO - Downloaded 54863623 bytes. 04:23:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:23:07 INFO - mkdir: /builds/slave/test/properties 04:23:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:23:07 INFO - Writing to file /builds/slave/test/properties/build_url 04:23:07 INFO - Contents: 04:23:07 INFO - build_url:https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:23:08 INFO - Running post-action listener: _resource_record_post_action 04:23:08 INFO - Running post-action listener: set_extra_try_arguments 04:23:08 INFO - ##### 04:23:08 INFO - ##### Finished download-and-extract step (success) 04:23:08 INFO - ##### 04:23:08 INFO - ##### 04:23:08 INFO - ##### Running populate-webroot step. 04:23:08 INFO - ##### 04:23:08 INFO - Running pre-action listener: _resource_record_pre_action 04:23:08 INFO - Running main action method: populate_webroot 04:23:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:23:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:23:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:23:08 INFO - '--tppagecycles', 04:23:08 INFO - '7'], 04:23:08 INFO - 'tests': ['tsvgm']}}, 04:23:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:23:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:23:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:23:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:23:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:23:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:23:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['damp', 'tps']}, 04:23:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'tests': ['damp', 'tps']}, 04:23:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['dromaeo_dom']}, 04:23:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:23:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['a11yr', 04:23:08 INFO - 'ts_paint', 04:23:08 INFO - 'tpaint', 04:23:08 INFO - 'sessionrestore', 04:23:08 INFO - 'sessionrestore_no_auto_restore', 04:23:08 INFO - 'tabpaint']}, 04:23:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:23:08 INFO - 'ts_paint', 04:23:08 INFO - 'tpaint', 04:23:08 INFO - 'sessionrestore', 04:23:08 INFO - 'sessionrestore_no_auto_restore', 04:23:08 INFO - 'tabpaint']}, 04:23:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:23:08 INFO - 'ts_paint', 04:23:08 INFO - 'tpaint', 04:23:08 INFO - 'sessionrestore', 04:23:08 INFO - 'sessionrestore_no_auto_restore', 04:23:08 INFO - 'tabpaint']}, 04:23:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:23:08 INFO - 'ts_paint', 04:23:08 INFO - 'tpaint', 04:23:08 INFO - 'sessionrestore', 04:23:08 INFO - 'sessionrestore_no_auto_restore', 04:23:08 INFO - 'tabpaint']}, 04:23:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['a11yr', 04:23:08 INFO - 'ts_paint', 04:23:08 INFO - 'tpaint', 04:23:08 INFO - 'sessionrestore', 04:23:08 INFO - 'sessionrestore_no_auto_restore', 04:23:08 INFO - 'tabpaint']}, 04:23:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['a11yr', 04:23:08 INFO - 'ts_paint', 04:23:08 INFO - 'tpaint', 04:23:08 INFO - 'sessionrestore', 04:23:08 INFO - 'sessionrestore_no_auto_restore', 04:23:08 INFO - 'tabpaint']}, 04:23:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['tsvgx', 04:23:08 INFO - 'tsvgr_opacity', 04:23:08 INFO - 'tart', 04:23:08 INFO - 'tscrollx', 04:23:08 INFO - 'cart']}, 04:23:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:23:08 INFO - 'tsvgr_opacity', 04:23:08 INFO - 'tart', 04:23:08 INFO - 'tscrollx', 04:23:08 INFO - 'cart']}, 04:23:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'talos_options': ['--disable-e10s'], 04:23:08 INFO - 'tests': ['tp5o']}, 04:23:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'tests': ['tp5o']}, 04:23:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'talos_options': ['--disable-e10s', 04:23:08 INFO - '--xperf_path', 04:23:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:23:08 INFO - 'tests': ['tp5n']}, 04:23:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:08 INFO - 'talos_options': ['--xperf_path', 04:23:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:23:08 INFO - 'tests': ['tp5n']}}, 04:23:08 INFO - 'talos.zip': {'path': '', 04:23:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:23:08 INFO - Running post-action listener: _resource_record_post_action 04:23:08 INFO - ##### 04:23:08 INFO - ##### Finished populate-webroot step (success) 04:23:08 INFO - ##### 04:23:08 INFO - ##### 04:23:08 INFO - ##### Running create-virtualenv step. 04:23:08 INFO - ##### 04:23:08 INFO - Running pre-action listener: _resource_record_pre_action 04:23:08 INFO - Running main action method: create_virtualenv 04:23:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:23:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:23:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:23:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:23:08 INFO - Using real prefix '/usr' 04:23:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:23:08 INFO - Installing distribute.............................................................................................................................................................................................done. 04:23:10 INFO - Installing pip.................done. 04:23:10 INFO - Return code: 0 04:23:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:23:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:10 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:23:10 INFO - Using env: {'DISPLAY': ':0', 04:23:10 INFO - 'HOME': '/home/cltbld', 04:23:10 INFO - 'LANG': 'en_US.UTF-8', 04:23:10 INFO - 'LANGUAGE': 'en_US:en', 04:23:10 INFO - 'LOGNAME': 'cltbld', 04:23:10 INFO - 'MAIL': '/var/mail/cltbld', 04:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:10 INFO - 'MOZ_NO_REMOTE': '1', 04:23:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:10 INFO - 'NO_EM_RESTART': '1', 04:23:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:10 INFO - 'PWD': '/builds/slave/test', 04:23:10 INFO - 'SHELL': '/bin/bash', 04:23:10 INFO - 'SHLVL': '1', 04:23:10 INFO - 'TERM': 'linux', 04:23:10 INFO - 'TMOUT': '86400', 04:23:10 INFO - 'USER': 'cltbld', 04:23:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:10 INFO - '_': '/tools/buildbot/bin/python'} 04:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:23:10 INFO - Cleaning up... 04:23:10 INFO - Return code: 0 04:23:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:23:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:10 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:23:10 INFO - Using env: {'DISPLAY': ':0', 04:23:10 INFO - 'HOME': '/home/cltbld', 04:23:10 INFO - 'LANG': 'en_US.UTF-8', 04:23:10 INFO - 'LANGUAGE': 'en_US:en', 04:23:10 INFO - 'LOGNAME': 'cltbld', 04:23:10 INFO - 'MAIL': '/var/mail/cltbld', 04:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:10 INFO - 'MOZ_NO_REMOTE': '1', 04:23:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:10 INFO - 'NO_EM_RESTART': '1', 04:23:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:10 INFO - 'PWD': '/builds/slave/test', 04:23:10 INFO - 'SHELL': '/bin/bash', 04:23:10 INFO - 'SHLVL': '1', 04:23:10 INFO - 'TERM': 'linux', 04:23:10 INFO - 'TMOUT': '86400', 04:23:10 INFO - 'USER': 'cltbld', 04:23:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:10 INFO - '_': '/tools/buildbot/bin/python'} 04:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:11 INFO - Downloading/unpacking psutil>=0.7.1 04:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:13 INFO - Installing collected packages: psutil 04:23:13 INFO - Running setup.py install for psutil 04:23:13 INFO - building 'psutil._psutil_linux' extension 04:23:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:23:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:23:13 INFO - building 'psutil._psutil_posix' extension 04:23:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:23:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:13 INFO - Successfully installed psutil 04:23:13 INFO - Cleaning up... 04:23:13 INFO - Return code: 0 04:23:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:23:13 INFO - Using env: {'DISPLAY': ':0', 04:23:13 INFO - 'HOME': '/home/cltbld', 04:23:13 INFO - 'LANG': 'en_US.UTF-8', 04:23:13 INFO - 'LANGUAGE': 'en_US:en', 04:23:13 INFO - 'LOGNAME': 'cltbld', 04:23:13 INFO - 'MAIL': '/var/mail/cltbld', 04:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:13 INFO - 'MOZ_NO_REMOTE': '1', 04:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:13 INFO - 'NO_EM_RESTART': '1', 04:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:13 INFO - 'PWD': '/builds/slave/test', 04:23:13 INFO - 'SHELL': '/bin/bash', 04:23:13 INFO - 'SHLVL': '1', 04:23:13 INFO - 'TERM': 'linux', 04:23:13 INFO - 'TMOUT': '86400', 04:23:13 INFO - 'USER': 'cltbld', 04:23:13 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:13 INFO - '_': '/tools/buildbot/bin/python'} 04:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:23:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:23:15 INFO - Installing collected packages: mozsystemmonitor 04:23:15 INFO - Running setup.py install for mozsystemmonitor 04:23:15 INFO - Successfully installed mozsystemmonitor 04:23:15 INFO - Cleaning up... 04:23:15 INFO - Return code: 0 04:23:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:15 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:23:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:23:15 INFO - Using env: {'DISPLAY': ':0', 04:23:15 INFO - 'HOME': '/home/cltbld', 04:23:15 INFO - 'LANG': 'en_US.UTF-8', 04:23:15 INFO - 'LANGUAGE': 'en_US:en', 04:23:15 INFO - 'LOGNAME': 'cltbld', 04:23:15 INFO - 'MAIL': '/var/mail/cltbld', 04:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:15 INFO - 'MOZ_NO_REMOTE': '1', 04:23:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:15 INFO - 'NO_EM_RESTART': '1', 04:23:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:15 INFO - 'PWD': '/builds/slave/test', 04:23:15 INFO - 'SHELL': '/bin/bash', 04:23:15 INFO - 'SHLVL': '1', 04:23:15 INFO - 'TERM': 'linux', 04:23:15 INFO - 'TMOUT': '86400', 04:23:15 INFO - 'USER': 'cltbld', 04:23:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:15 INFO - '_': '/tools/buildbot/bin/python'} 04:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:16 INFO - Downloading/unpacking blobuploader==1.2.4 04:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:18 INFO - Downloading blobuploader-1.2.4.tar.gz 04:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:23:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:23:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:18 INFO - Downloading docopt-0.6.1.tar.gz 04:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:23:18 INFO - Installing collected packages: blobuploader, requests, docopt 04:23:18 INFO - Running setup.py install for blobuploader 04:23:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:23:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:23:18 INFO - Running setup.py install for requests 04:23:19 INFO - Running setup.py install for docopt 04:23:19 INFO - Successfully installed blobuploader requests docopt 04:23:19 INFO - Cleaning up... 04:23:19 INFO - Return code: 0 04:23:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:19 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:19 INFO - Using env: {'DISPLAY': ':0', 04:23:19 INFO - 'HOME': '/home/cltbld', 04:23:19 INFO - 'LANG': 'en_US.UTF-8', 04:23:19 INFO - 'LANGUAGE': 'en_US:en', 04:23:19 INFO - 'LOGNAME': 'cltbld', 04:23:19 INFO - 'MAIL': '/var/mail/cltbld', 04:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:19 INFO - 'MOZ_NO_REMOTE': '1', 04:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:19 INFO - 'NO_EM_RESTART': '1', 04:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:19 INFO - 'PWD': '/builds/slave/test', 04:23:19 INFO - 'SHELL': '/bin/bash', 04:23:19 INFO - 'SHLVL': '1', 04:23:19 INFO - 'TERM': 'linux', 04:23:19 INFO - 'TMOUT': '86400', 04:23:19 INFO - 'USER': 'cltbld', 04:23:19 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:19 INFO - '_': '/tools/buildbot/bin/python'} 04:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:19 INFO - Running setup.py (path:/tmp/pip-BvI_3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:19 INFO - Running setup.py (path:/tmp/pip-pK_IDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:19 INFO - Running setup.py (path:/tmp/pip-7DIICa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:19 INFO - Running setup.py (path:/tmp/pip-KDUdrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:19 INFO - Running setup.py (path:/tmp/pip-zt8gcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:19 INFO - Running setup.py (path:/tmp/pip-6mVKpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:20 INFO - Running setup.py (path:/tmp/pip-BODnym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:20 INFO - Running setup.py (path:/tmp/pip-RjiOBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:20 INFO - Running setup.py (path:/tmp/pip-B6pWT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:20 INFO - Running setup.py (path:/tmp/pip-n9d5TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:20 INFO - Running setup.py (path:/tmp/pip-9dqbQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:20 INFO - Running setup.py (path:/tmp/pip-ZMAk4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:20 INFO - Running setup.py (path:/tmp/pip-109ODi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:20 INFO - Running setup.py (path:/tmp/pip-zhpFd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:20 INFO - Running setup.py (path:/tmp/pip-bfyp2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:20 INFO - Running setup.py (path:/tmp/pip-349Uus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:20 INFO - Running setup.py (path:/tmp/pip-r4Uw9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:23:20 INFO - Running setup.py install for manifestparser 04:23:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:23:20 INFO - Running setup.py install for mozcrash 04:23:21 INFO - Running setup.py install for mozdebug 04:23:21 INFO - Running setup.py install for mozdevice 04:23:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Running setup.py install for mozfile 04:23:21 INFO - Running setup.py install for mozhttpd 04:23:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Running setup.py install for mozinfo 04:23:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Running setup.py install for mozInstall 04:23:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Running setup.py install for mozleak 04:23:21 INFO - Running setup.py install for mozlog 04:23:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Running setup.py install for moznetwork 04:23:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:23:21 INFO - Running setup.py install for mozprocess 04:23:22 INFO - Running setup.py install for mozprofile 04:23:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:23:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:23:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:23:22 INFO - Running setup.py install for mozrunner 04:23:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:23:22 INFO - Running setup.py install for mozscreenshot 04:23:22 INFO - Running setup.py install for moztest 04:23:22 INFO - Running setup.py install for mozversion 04:23:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:23:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:23:22 INFO - Cleaning up... 04:23:22 INFO - Return code: 0 04:23:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:22 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:22 INFO - Using env: {'DISPLAY': ':0', 04:23:22 INFO - 'HOME': '/home/cltbld', 04:23:22 INFO - 'LANG': 'en_US.UTF-8', 04:23:22 INFO - 'LANGUAGE': 'en_US:en', 04:23:22 INFO - 'LOGNAME': 'cltbld', 04:23:22 INFO - 'MAIL': '/var/mail/cltbld', 04:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:22 INFO - 'MOZ_NO_REMOTE': '1', 04:23:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:22 INFO - 'NO_EM_RESTART': '1', 04:23:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:22 INFO - 'PWD': '/builds/slave/test', 04:23:22 INFO - 'SHELL': '/bin/bash', 04:23:22 INFO - 'SHLVL': '1', 04:23:22 INFO - 'TERM': 'linux', 04:23:22 INFO - 'TMOUT': '86400', 04:23:22 INFO - 'USER': 'cltbld', 04:23:22 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:22 INFO - '_': '/tools/buildbot/bin/python'} 04:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:22 INFO - Running setup.py (path:/tmp/pip-OM2isO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:22 INFO - Running setup.py (path:/tmp/pip-5MV9s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:23 INFO - Running setup.py (path:/tmp/pip-T4TCZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:23 INFO - Running setup.py (path:/tmp/pip-p52cnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:23 INFO - Running setup.py (path:/tmp/pip-ecqoyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:23 INFO - Running setup.py (path:/tmp/pip-r_ypRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:23 INFO - Running setup.py (path:/tmp/pip-8ykXL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:23 INFO - Running setup.py (path:/tmp/pip-RBK88q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:23 INFO - Running setup.py (path:/tmp/pip-o43LcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:23 INFO - Running setup.py (path:/tmp/pip-COGCGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:23 INFO - Running setup.py (path:/tmp/pip-80IBJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:23 INFO - Running setup.py (path:/tmp/pip-HkiWbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:23 INFO - Running setup.py (path:/tmp/pip-uH7wnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:23 INFO - Running setup.py (path:/tmp/pip-TpUSQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:23 INFO - Running setup.py (path:/tmp/pip-NFJ0Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:23 INFO - Running setup.py (path:/tmp/pip-2XiOyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:24 INFO - Running setup.py (path:/tmp/pip-YS4yNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:23:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:25 INFO - Downloading blessings-1.6.tar.gz 04:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:26 INFO - Installing collected packages: blessings 04:23:26 INFO - Running setup.py install for blessings 04:23:26 INFO - Successfully installed blessings 04:23:26 INFO - Cleaning up... 04:23:26 INFO - Return code: 0 04:23:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:26 INFO - Reading from file tmpfile_stdout 04:23:26 INFO - Current package versions: 04:23:26 INFO - argparse == 1.2.1 04:23:26 INFO - blessings == 1.6 04:23:26 INFO - blobuploader == 1.2.4 04:23:26 INFO - docopt == 0.6.1 04:23:26 INFO - manifestparser == 1.1 04:23:26 INFO - mozInstall == 1.12 04:23:26 INFO - mozcrash == 0.17 04:23:26 INFO - mozdebug == 0.1 04:23:26 INFO - mozdevice == 0.48 04:23:26 INFO - mozfile == 1.2 04:23:26 INFO - mozhttpd == 0.7 04:23:26 INFO - mozinfo == 0.9 04:23:26 INFO - mozleak == 0.1 04:23:26 INFO - mozlog == 3.1 04:23:26 INFO - moznetwork == 0.27 04:23:26 INFO - mozprocess == 0.22 04:23:26 INFO - mozprofile == 0.28 04:23:26 INFO - mozrunner == 6.11 04:23:26 INFO - mozscreenshot == 0.1 04:23:26 INFO - mozsystemmonitor == 0.0 04:23:26 INFO - moztest == 0.7 04:23:26 INFO - mozversion == 1.4 04:23:26 INFO - psutil == 3.1.1 04:23:26 INFO - requests == 1.2.3 04:23:26 INFO - wsgiref == 0.1.2 04:23:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23: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')]} 04:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23: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')]} 04:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:26 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:26 INFO - Using env: {'DISPLAY': ':0', 04:23:26 INFO - 'HOME': '/home/cltbld', 04:23:26 INFO - 'LANG': 'en_US.UTF-8', 04:23:26 INFO - 'LANGUAGE': 'en_US:en', 04:23:26 INFO - 'LOGNAME': 'cltbld', 04:23:26 INFO - 'MAIL': '/var/mail/cltbld', 04:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:26 INFO - 'MOZ_NO_REMOTE': '1', 04:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:26 INFO - 'NO_EM_RESTART': '1', 04:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:26 INFO - 'PWD': '/builds/slave/test', 04:23:26 INFO - 'SHELL': '/bin/bash', 04:23:26 INFO - 'SHLVL': '1', 04:23:26 INFO - 'TERM': 'linux', 04:23:26 INFO - 'TMOUT': '86400', 04:23:26 INFO - 'USER': 'cltbld', 04:23:26 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:26 INFO - '_': '/tools/buildbot/bin/python'} 04:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:23:26 INFO - Cleaning up... 04:23:26 INFO - Return code: 0 04:23:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23: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')]} 04:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23: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')]} 04:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:23:26 INFO - Using env: {'DISPLAY': ':0', 04:23:26 INFO - 'HOME': '/home/cltbld', 04:23:26 INFO - 'LANG': 'en_US.UTF-8', 04:23:26 INFO - 'LANGUAGE': 'en_US:en', 04:23:26 INFO - 'LOGNAME': 'cltbld', 04:23:26 INFO - 'MAIL': '/var/mail/cltbld', 04:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:26 INFO - 'MOZ_NO_REMOTE': '1', 04:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:26 INFO - 'NO_EM_RESTART': '1', 04:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:26 INFO - 'PWD': '/builds/slave/test', 04:23:26 INFO - 'SHELL': '/bin/bash', 04:23:26 INFO - 'SHLVL': '1', 04:23:26 INFO - 'TERM': 'linux', 04:23:26 INFO - 'TMOUT': '86400', 04:23:26 INFO - 'USER': 'cltbld', 04:23:26 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:26 INFO - '_': '/tools/buildbot/bin/python'} 04:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:26 INFO - Downloading/unpacking jsonschema 04:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:23:28 INFO - Downloading/unpacking functools32 (from jsonschema) 04:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:28 INFO - Downloading functools32-3.2.3-2.tar.gz 04:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:23:29 INFO - warning: no files found matching '*.txt' 04:23:29 INFO - no previously-included directories found matching 'build' 04:23:29 INFO - no previously-included directories found matching 'dist' 04:23:29 INFO - no previously-included directories found matching '.git*' 04:23:29 INFO - Installing collected packages: jsonschema, functools32 04:23:29 INFO - Running setup.py install for functools32 04:23:29 INFO - warning: no files found matching '*.txt' 04:23:29 INFO - no previously-included directories found matching 'build' 04:23:29 INFO - no previously-included directories found matching 'dist' 04:23:29 INFO - no previously-included directories found matching '.git*' 04:23:29 INFO - Successfully installed jsonschema functools32 04:23:29 INFO - Cleaning up... 04:23:29 INFO - Return code: 0 04:23:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:29 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 0x7f61b2f66f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:23:29 INFO - Using env: {'DISPLAY': ':0', 04:23:29 INFO - 'HOME': '/home/cltbld', 04:23:29 INFO - 'LANG': 'en_US.UTF-8', 04:23:29 INFO - 'LANGUAGE': 'en_US:en', 04:23:29 INFO - 'LOGNAME': 'cltbld', 04:23:29 INFO - 'MAIL': '/var/mail/cltbld', 04:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:29 INFO - 'MOZ_NO_REMOTE': '1', 04:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:29 INFO - 'NO_EM_RESTART': '1', 04:23:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:29 INFO - 'PWD': '/builds/slave/test', 04:23:29 INFO - 'SHELL': '/bin/bash', 04:23:29 INFO - 'SHLVL': '1', 04:23:29 INFO - 'TERM': 'linux', 04:23:29 INFO - 'TMOUT': '86400', 04:23:29 INFO - 'USER': 'cltbld', 04:23:29 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:29 INFO - '_': '/tools/buildbot/bin/python'} 04:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:29 INFO - Downloading/unpacking flake8 04:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:23:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:23:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:23:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:23:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:23:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:23:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:23:32 INFO - no previously-included directories found matching 'docs/_build' 04:23:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:23:32 INFO - Running setup.py install for pep8 04:23:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:23:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:23:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:23:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:23:32 INFO - no previously-included directories found matching 'docs/_build' 04:23:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:23:32 INFO - Cleaning up... 04:23:32 INFO - Return code: 0 04:23:32 INFO - Running post-action listener: _resource_record_post_action 04:23:32 INFO - Running post-action listener: _start_resource_monitoring 04:23:32 INFO - Starting resource monitoring. 04:23:32 INFO - ##### 04:23:32 INFO - ##### Finished create-virtualenv step (success) 04:23:32 INFO - ##### 04:23:32 INFO - ##### 04:23:32 INFO - ##### Running install step. 04:23:32 INFO - ##### 04:23:32 INFO - Running pre-action listener: _resource_record_pre_action 04:23:32 INFO - Running main action method: install 04:23:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:32 INFO - Reading from file tmpfile_stdout 04:23:32 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:32 INFO - Reading from file tmpfile_stdout 04:23:32 INFO - Output received: 04:23:32 INFO - Usage: mozinstall [options] installer 04:23:32 INFO - Options: 04:23:32 INFO - -h, --help show this help message and exit 04:23:32 INFO - -d DEST, --destination=DEST 04:23:32 INFO - Directory to install application into. [default: 04:23:32 INFO - "/builds/slave/test"] 04:23:32 INFO - --app=APP Application being installed. [default: firefox] 04:23:32 INFO - mkdir: /builds/slave/test/build/application 04:23:32 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'] 04:23:32 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 04:23:48 INFO - Reading from file tmpfile_stdout 04:23:48 INFO - Output received: 04:23:48 INFO - /builds/slave/test/build/application/firefox/firefox 04:23:48 INFO - Running post-action listener: _resource_record_post_action 04:23:48 INFO - ##### 04:23:48 INFO - ##### Finished install step (success) 04:23:48 INFO - ##### 04:23:48 INFO - ##### 04:23:48 INFO - ##### Running run-tests step. 04:23:48 INFO - ##### 04:23:48 INFO - Running pre-action listener: _resource_record_pre_action 04:23:48 INFO - Running main action method: run_tests 04:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:23:48 INFO - Python 2.7.3 04:23:48 INFO - Return code: 0 04:23:48 INFO - grabbing minidump binary from tooltool 04:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf719c0>, '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 0xf728c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:23:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:23:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:23:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:23:48 INFO - Return code: 0 04:23:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:23:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:23:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:23:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:23:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:23:49 INFO - Return code: 0 04:23:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 04:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 04:23:49 INFO - Using env: {'DISPLAY': ':0', 04:23:49 INFO - 'HOME': '/home/cltbld', 04:23:49 INFO - 'LANG': 'en_US.UTF-8', 04:23:49 INFO - 'LANGUAGE': 'en_US:en', 04:23:49 INFO - 'LOGNAME': 'cltbld', 04:23:49 INFO - 'MAIL': '/var/mail/cltbld', 04:23:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:23:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:49 INFO - 'MOZ_NO_REMOTE': '1', 04:23:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:23:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:49 INFO - 'NO_EM_RESTART': '1', 04:23:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:49 INFO - 'PWD': '/builds/slave/test', 04:23:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:23:49 INFO - 'SHELL': '/bin/bash', 04:23:49 INFO - 'SHLVL': '1', 04:23:49 INFO - 'TERM': 'linux', 04:23:49 INFO - 'TMOUT': '86400', 04:23:49 INFO - 'USER': 'cltbld', 04:23:49 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341', 04:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:49 INFO - '_': '/tools/buildbot/bin/python'} 04:23:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 04:23:49 INFO - mozversion application_buildid: 20160509024850 04:23:49 INFO - mozversion application_changeset: cb53cee341a60d134fd5dc55ead4a95632a22dce 04:23:49 INFO - mozversion application_display_name: Nightly 04:23:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:23:49 INFO - mozversion application_name: Firefox 04:23:49 INFO - mozversion application_remotingname: firefox 04:23:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:23:49 INFO - mozversion application_vendor: Mozilla 04:23:49 INFO - mozversion application_version: 49.0a1 04:23:49 INFO - mozversion platform_buildid: 20160509024850 04:23:49 INFO - mozversion platform_changeset: cb53cee341a60d134fd5dc55ead4a95632a22dce 04:23:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:23:49 INFO - mozversion platform_version: 49.0a1 04:23:49 INFO - using testdate: 1462793029 04:23:49 INFO - actual date: 1462793029 04:23:49 INFO - starting webserver on 'localhost:53802' 04:23:49 INFO - SUITE-START | Running 2 tests 04:23:49 INFO - TEST-START | dromaeo_css 04:23:49 INFO - operating with platform_type : linux_ 04:23:49 INFO - Initialising browser for dromaeo_css test... 04:23:49 INFO - TEST-INFO | started process 5684 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmJXg2Y/profile http://localhost:53802/getInfo.html) 04:23:53 INFO - PROCESS | 5684 | 04:23:53 INFO - PROCESS | 5684 | (process:5751): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:23:54 INFO - PROCESS | 5684 | __metrics Screen width/height:1600/1200 04:23:54 INFO - PROCESS | 5684 | colorDepth:24 04:23:54 INFO - PROCESS | 5684 | Browser inner width/height: 1024/697 04:23:54 INFO - PROCESS | 5684 | __metrics 04:23:54 ERROR - PROCESS | 5684 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 04:23:55 INFO - TEST-INFO | 5684: exit 0 04:23:55 INFO - Browser initialized. 04:23:55 INFO - Running cycle 1/1 for dromaeo_css test... 04:23:55 INFO - TEST-INFO | started process 5802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmJXg2Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:23:57 INFO - PROCESS | 5802 | 04:23:57 INFO - PROCESS | 5802 | (process:5862): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:24:07 INFO - PROCESS | 5802 | RSS: Main: 164876288 04:24:07 INFO - PROCESS | 5802 | 04:24:57 INFO - PROCESS | 5802 | 1462793097195 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:27:07 INFO - PROCESS | 5802 | Cycle 1(1): loaded http://localhost:53802/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53802/tests/dromaeo/cssquery-ext.html) 04:27:08 INFO - PROCESS | 5802 | RSS: Main: 167579648 04:27:08 INFO - PROCESS | 5802 | 04:30:11 INFO - PROCESS | 5802 | Cycle 1(1): loaded http://localhost:53802/tests/dromaeo/cssquery-ext.html (next: http://localhost:53802/tests/dromaeo/cssquery-jquery.html) 04:30:11 INFO - PROCESS | 5802 | RSS: Main: 166957056 04:30:11 INFO - PROCESS | 5802 | 04:33:13 INFO - PROCESS | 5802 | Cycle 1(1): loaded http://localhost:53802/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53802/tests/dromaeo/cssquery-mootools.html) 04:33:14 INFO - PROCESS | 5802 | RSS: Main: 167804928 04:33:14 INFO - PROCESS | 5802 | 04:36:16 INFO - PROCESS | 5802 | Cycle 1(1): loaded http://localhost:53802/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53802/tests/dromaeo/cssquery-prototype.html) 04:36:16 INFO - PROCESS | 5802 | RSS: Main: 165539840 04:36:16 INFO - PROCESS | 5802 | 04:39:19 INFO - PROCESS | 5802 | Cycle 1(1): loaded http://localhost:53802/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53802/tests/dromaeo/cssquery-yui.html) 04:39:20 INFO - PROCESS | 5802 | RSS: Main: 167075840 04:39:20 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | Cycle 1(1): loaded http://localhost:53802/tests/dromaeo/cssquery-yui.html (next: http://localhost:53802/tests/dromaeo/cssquery-dojo.html) 04:42:22 INFO - PROCESS | 5802 | RSS: Main: 168611840 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | __start_tp_report 04:42:22 INFO - PROCESS | 5802 | _x_x_mozilla_page_load 04:42:22 INFO - PROCESS | 5802 | _x_x_mozilla_page_load_details 04:42:22 INFO - PROCESS | 5802 | |i|pagename|runs| 04:42:22 INFO - PROCESS | 5802 | |0;dojo.html;2626.3565426470514;2664.733526647334;2668.7598116169543;2694.7305269473068;2710.078573285084;4712.835050773224;4719.268513380427;4770.284457331393;4783.35424717663;4793.976030119853;4688.859334219979;4757.381540399751;4762.309465127554;4779.832705855296;4782.282657601361;10.205342228094768;4384.276594361934;4483.865484035484;4492.168948744476;4494.528074552174;4256.808443620044;4386.298192289234;4391.36325232841;4427.335899615051;4495.775211239441;4474.97762511187;4492.011757413364;4493.550644935481;4499.527549607294;4536;4308.633766129883;4474.970756725938;4506.954930450686;4513.435820522434;4522.3668686383935;4354.869353919387;4380.058287468178;4396.824127034914;4413.337999300099;4422.181896349153;4667.253239481666;4690.812367505296;4696.178168820477;4714.575688188045;4778.092162489116;4928.28539861718;5105.081085404626;5214.8174813881715;5226.895462090775;5247.47525247476;5198.844034678966;5235.240890070956;5241.816536421207;5253.133233016548;5294.391145018334;4886.9266960995255;4893.070316639827;4894.094592500399;4901.65688401812;4940.209566469383;5201.297824793604;5224.92162617561;5235.60732945023;5332.520073193431;5339.919901201485;5047.823326183639;5050.722210278396;5077.289179514871;5096.464871188546;5125.615578831603;5149.510796474329;5170.146925757208;5175.9741201293755;5179.533841954242;5223.373195216598;4931.186354251508;5101.413337466165;5111.591072714174;5125.8718532036255;5146;5061.924071138936;5087.592992560586;5099.796008159707;5143.228515722672;5194.92207616886;4434.955650443519;4450.599446049806;4456.977715111469;4462.397576327219;4501.662375321864;3735.7198210134347;3792.3742582474133;3829.0427393151713;3859.4982652255026;3897.4738410314812;4363.323684828857;4363.563643635611;4391.187630288343;4401.823927042947;4415.205263052681;5149.253358263106;5151.2273159026445;5152.124138896396;5154.278401023859;5162.5095615916425;4278.379634952915;4377.277749171415;4398.120375924827;4438.556144385536;4455.952851079994;4644.3265726470145;4662.463816661059;4679;4681.906361872811;4743.525647435229;4867.732274724854;4881.682690625098;4884.462709102067;4890.559849613552;4936.703797772145;5050;5117.078925793393;5128.487151284841;5161.09680805865;5192.42883282831;4848.151573474698;4848.7090774553635;4876.5854902333695;4884.92672609904;4924.729139897197;4926.556609905126;5080.898382032265;5103.157978933435;5114.667546609458;5168.01807656544;5120.462351453044;5174.4566820484815;5184.948150518447;5198.934218485277;5213.895722085613;4986.052649996489;5014.974925125351;5017.272495488207;5028.170351891897;5037.269595908646;4826.493218212177;4993.925091123709;5007.47421520735;5053.924191137209;5057.115004874058;4947.876303092451;4966.379202599675;4977.676451030671;5010.974945125251;5044.747762611928;5040.773165207501;5095.719735414587;5099.77051032697;5102.183650615883;5139.254808052886;4961.05739909416;4984.352034235526;5004.499550044967;5007.899842003212;5020.246962955586 04:42:22 INFO - PROCESS | 5802 | |1;ext.html;552.5745176214315;598.5301538292439;600.8347704381295;613.2395829171828;616.9568130230883;33922.321553568945;34506;35012.64987350126;35025.474617880755;35069.64930350696;38031.619683803154;38152.80923595385;38548.229035419274;38604.80697596515;38619.80690096552;7677.884831727514;7888.881666775003;7916.168802275735;7931.960340198291;7934.920650793505;7817.960910195441;7872.921270787276;7877;7913.327367173797;7917.208279172094;7850.9214907850765;7912.090109637408;7923.722669706562;7943.443958922849;7960.681572737083;7823.647935842901;7842.568658723768;7868.763937081897;7897.24976127267;7899.0126234220725;7802.687892484294;7877.684892604347;7906.169852165497;7917.643706033243;7926.841463170762;29852.8507357464;37456.62543374558;37763.433548496796;37820;37912;3168.3346497235607;3264.6245681746427;3318.1704573856537;3446.3796516627;3491.30173965208;28067.29831754202;28094.157175284774;29044.27389315263;30053;31378.21554461134;3199.5056215612826;3229.40256052631;3260.3805276997296;3281.8359082045804;3304.322613864152;238348;241774;244288;246103;246641;34220.657793422244;35613;60485;61727.69136154291;61856;37029.25941481155;37093.44359834605;37125.44311835324;37161.25677486435;37256.62743372531;12400;12410.255384676768;12439.626811195678;12545.560905368267;12548.874511254953;9616.23070154386;9632.518374081268;9648.276379271443;9650.227981761442;9652.131308182297;8529.488230706178;8597.914020859711;8602.225799678059;8610.052894181636;8680.913190868137;3330.966690333066;3338.6995170434293;3356.9496457553155;3372.409828280041;3421.3733442550974;944.5654998700675;961.1541843177959;970.6997998865096;1014.6195176808696;1040.5837664934088;29195.41609167805;29268.268293292836;29373.412531749247;29541.704582953895;29806.40387192244;1019.6380284998867;1037.0459177556666;1047.8899715529913;1048.3814549415881;1109.1570406491126;15512.689746205013;15550.989185702892;15713.664338531353;15740.763888541682;15781.68436631261;375391;379946;382100;383139;385420;48765.756171219626;49890.25164622533;49916.75041624768;50012.499875001515;50099.74950125226;1171.9906082907444;1202.8857258560422;1204.2172587817813;1212.1090998116376;1273.8726127387188;27571.17286481409;27979.860100699367;28684;28788;28866;43447.565524344354;43768.562314376446;43773.12453750843;43866.99999999873;43897.56102439063;293759;297752;298096;299842;300180;26028.34929126787;26543.33641658974;26555.468890622466;26562.335941601614;26598.468030639666;40870;41537.37693934654;42060.57939420567;42096;42448;36752.63247367599;36758.63241367552;36779.44830827486;36847.63152368442;37405.438918415704;36138.638613613526;36150;36180;36219;36237.0940726484;33976.66023339735;35108.64891351156;37390;37456.81271593625;38216.80891595524;32093.358132836747;32389.676103238668;32699.67300326966;33470.16324591807;33614.159646009044;25340.36649083714;25566.233013009638;25667.871660641576;25692.357691057125;25724 04:42:22 INFO - PROCESS | 5802 | |2;jquery.html;374.69649583837077;380.89906174863665;384.73837790302593;384.77298393947564;399.301222859995;15985;16471.670566588677;17281.13594320285;17394.999999999985;17935.463347714598;10787.78424431513;11348.205625606211;11355.943220283907;11361.886381136206;11377.374244416555;4665.346851440801;4715.858524244278;4721.976390118045;4800.039992001596;4825.879353016185;4710.90578188436;4787.329773831666;4788.688735232199;4796.616270698353;4828.613710903137;4684.906301873961;4751.049790041988;4789.688670236441;4794.448638406593;4820.542048505386;4770.546798054179;4775.9761201194115;4803.423589169322;4825.469198388157;4826.155422801014;4548.20406428877;4646.047560250175;4680.3681502997;4684.086603112394;4689.95310046902;19176.80823191778;20265.797342026686;20410.591788164304;20412.79587204124;20636.89681551598;1927.5277556998485;1963.5091227193202;1997.7802441731399;2035.4809523571544;2503.399184195798;9284.93223279318;9404.62381504739;9485.430874147572;9517.857232141523;9779.41323520591;1762.7972783129976;1794.6590147871862;1797.4338083503656;1805.7562229099167;1840.1075478392968;65026.67486662536;65301.67349163271;66691;66942.99585506173;66952.6652366735;10216.284860059874;10242;10502.632407865687;10566.31318964265;10598.629047983284;14407.78388324176;14426.350814213385;14510.419583216553;14603;14638.487652932095;6844.2129155147795;6913.89629155563;7115.252898445692;7116.786496405116;7146.535475194096;6450.967745161244;6474.546781725234;6481.773137940244;6542.345765423514;6573.704183311763;4391.802368893407;4424.867253982386;4498;4537.909241815145;4546.74992875395;1853.8980356080276;1892.4133518609276;1895.3889194184833;1913.015695515001;1922.2311075569605;836.5231817863881;939.1547607153616;971.504532688334;980.480345416916;1023.1098943918838;16974.57563560921;17085.829141708426;17146.22841972114;17540.210690518958;17620.559486012695;895.167494230372;928.0533855467386;928.076563818991;929.5863340813273;940.4968341937031;9604.711858644252;9878.654247101316;9905.158061564704;9918.900810991798;9936.50317484123;416670;424547;425233;439511;443817;56263;68862.31137688659;77787;78410;80470;943.8914524829732;1004.1815920025156;1004.8743907011769;1021.7394564386034;1024.2676486312323;15693.686126277638;16041.438549650706;16122.274497647868;16189.352425902833;16387.754183687015;34574.48138277877;36118.45822312615;36392.636073638925;36498.8175059123;36779.44830827593;48741.31922744283;49083.754581226865;49734.75132624459;50023.24965125453;50431.24353134633;7567.432442566719;7576.8484630308185;7623.123340815655;7773.844523109393;7871.567063811316;7593.810154746176;8004.599770011592;11618.360990145624;11756.647300580997;11999.64001079969;10341.345384738406;10342.948285258526;10402.27184097106;10511.94744026275;10935;10134.645287414905;10520.78958420812;10572.524236409226;10576.153907687212;11013.229073964745;10528.263021588722;10616.787664246825;10716.571337146426;10843.078338341329;11042.116630669367;7626.389888808771;7710.460267781202;7713.730019449293;7809.2190780921455;8007.358491509343;10784.245102842726;10824.025837674648;10904.563817447213;10915.78168436611;10982.890171098506 04:42:22 INFO - PROCESS | 5802 | |3;mootools.html;1475.549957263035;1485.4503833581573;1643.2276829889954;1797.9550511237217;1798.8920664760108;6469.514786391016;7004.614746188958;7197.60413177275;7449.21783212763;7451;7214.134303883528;7396.630168491567;7403.925960740391;7497.2502749725;7509.361704255131;2169.60947029535;2202.4603972026875;2207.878566678832;2209.1826024371007;2215.3021798133536;2143.239150101715;2157.7087093242367;2179.030331502482;2189.1462329691435;2196.187410658051;2194.7914948079906;2194.9231776887814;2202.7246594175726;2208.5251670890757;2232.2521955145044;2147.559750251203;2151.741790985076;2151.7633060363346;2153.3755210988793;2160.2439146298825;2113.8731676099483;2123.3311506875284;2130.222468798893;2162.0919213930188;2164.621191291518;7391.002214701;7391.630418479054;7451.627418629101;7466.887996680054;7480.588567628778;4161.791910404498;4215.199112168678;4237.703360764748;4247.617714405687;4267.381229721704;2846.9572956405464;2860.1705505403406;2968.421157874194;3019.3659331540402;3021.7884748067645;920.7606022434148;982.2289502740387;990.658222913101;1011.4184344002228;1037.092544023979;9721.270904682176;9751.853722194173;9772.071653192936;9772.706818795448;9787.755306117333;4089.3457046872954;4141.088960428701;4240.618344349024;4245.511766146933;4300.8709738707885;8444.746657600283;8448.450850694686;8448.493090414595;8477.576121194039;8525.445846020113;4351.804168812411;4403.9559604404185;4443.577860103285;4470.865874023784;4503.729776213438;3464.7228221742703;3943.5859234779937;3997.340438827619;4014.7189696720948;4067.51189857219;3610.765300255475;3617.764845285101;3641.908952276214;3687.520622319135;3809.5238095238096;1198.8561372635165;1213.2720367779439;1223.920445171061;1234.6419538333962;1239.7954337534206;893.9016708162097;904.5703290936752;917.7968888578812;927.5501381829807;941.275218082073;5138.203578445347;5168.224766285088;5191.377034755854;5235.60732945023;5292.02097611943;406.4797059763508;410.4786920610808;414.05801800903185;414.69727099217135;416.1012213618569;3483.8819256765055;3522.939080637804;3600.4959305697225;3608.585012723518;3643.1620727232885;8804;9194.12655797698;9214.953925230331;9221.769455763526;9282.953585232031;8995.055519170524;9007.549622518978;9012.63949442015;9118.908810911806;9122.543872806466;7000.299970002959;7059.364657180879;7100.183478899992;7103.751368702071;7110;5098.745062746922;5114.386273647037;5139.691618502902;5225.268462415341;5226;7527.134379546423;7546.547207167368;7569.091708994956;7596.620168991418;7617.847643047219;8431.915680843358;8587.141285871363;8710.085441028601;8726.476411415335;8752.47485150893;5700.771969121354;6045.697715114139;6213.565050446607;6432.421082102634;6582.473402127723;7082.327178917994;7090.07828982229;7118.60847653384;7162.677679504539;7193.568385896863;6275.246970363585;6519;6529.7388104475285;6596.60420374779;6623.138991931018;6468.514861385321;6490.51321150925;6490.8052758417325;6509.967450162719;6562.376574225441;6133.325334213322;6135.509159267159;6164.969175154096;6192.969035154975;6195.256569211723;4467.754273514988;4534.410526631516;4536.274196128604;4562.634989200922;4572.5198854119835;4617.2612382018715;4619.745913974763;4627.5141110184295;4628.421447319085;4645.140648979949 04:42:22 INFO - PROCESS | 5802 | |4;prototype.html;315.9905202843915;318.41411802283795;318.58742927908355;323.2258740316941;324.39986025852164;5011.34852469179;5048.091343558158;5064.215046667763;5077.669951453153;5091.1090559152135;4946.826861059863;4961.60307175426;4961.900761984758;4975.5273249041375;5017.422996355411;3746.7751934883927;3851.9984803950892;3953.881383558498;3957.9010524736823;3978.5225772907293;3877.592852750463;3935.9016024599327;3937.0944682722993;3982.780947047911;3987.003249187703;3931.960680393188;3952;3967.1668949520636;3967.7817720025387;3968.2063587282514;3829.48301979232;3914.667253283446;3921.5490218624936;3935.9016024599473;3946.9605303947023;3469.4275444551618;3476.6870981611523;3482.199094208346;3493.7205023598303;3499.7200223982277;4753.31076993834;4760.738159401231;4762.238041913277;4771.141194584973;4774.498677638832;4417.182821178092;4518.638508919278;4522.728636281834;4538.773061346952;4548.795304211319;5194.532492075731;5197.454267301954;5219.19102539107;5225.660332078441;5257.684538927677;3731.7982390389802;3776.622337766229;3784.9432258516144;3804.923901521982;3812.7331086823933;5469.234307196995;5496.615236933376;5552.3892371839065;5552.444755524456;5552.7223638817895;3617.5297211362354;3842.289176502356;3847.51870529848;3855.1133239354544;3859.536855577349;3867.7043190531485;3871.7870517121264;3881.7476864004316;3889.9611003890163;3908.159745654698;4406.779661016936;4430.889227769267;4445.355423463644;4457.108578284357;4468.642508599304;5044.924326135111;5181.689098654093;5260.447652996457;5288.180332048539;5305;1290.9612711618665;1299.5061876486875;1302.2707283921034;1305.2560040776666;1306.660268330241;836.9455985360933;851.5784686580182;887.1350433327455;887.3211992825494;892.9196372326522;4480.305552639346;4552.9772351138035;4554.111948170142;4583.679142459996;4601.907961840744;5343.171808369709;5379.35447746273;5387.784488620411;5402.216678581583;5407.891842163135;4408.162449134718;4554.407926969473;4566.977165114153;4567.657425693086;4578.862634120982;2758.8620568971473;2792.064658339479;2792.3437992071845;2805.859707014641;2810.1990932584117;4917.819723266462;4919.729414882144;4961.925571116436;5000.724815172181;5007.148784706608;5183.45573714762;5405;5436.510714035756;5474.644148130359;5484.287042684426;5011.574016208663;5015.147424937696;5036.194208926554;5049.772760225798;5056.519630635157;5229.816956406507;5243.7152897540345;5262.184361423985;5273.312539987167;5303.143899635097;4907.975460122677;5103.7192954387865;5175.534201921845;5176.689398636094;5207.661502002357;4890.119778439915;4897.853064408074;4962.33008543842;4971.676841005323;4975.825846095369;3675.6140605236064;3686.207465394953;3696.5933747288313;3697;3717.9442308364855;5009.173486374853;5313.2561441397465;5317.627766056339;5325.440828712929;5426;5279.39286982002;5283.516747128843;5291.623593569662;5304.867378315573;5304.9469505306;5248.8425347239645;5299.2512470885285;5301.469853014668;5312.920306195488;5316.803719163157;5113.616478764182;5120.142832345634;5166.819161329336;5238.68567885928;5263.315768950012;5050.292958985819;5150.536451719363;5302.628815982845;5303.46965303482;5342.332208473941;5142.76857541404;5185.792568297376;5213.921791173059;5215.243789650555;5233.921491177559 04:42:22 INFO - PROCESS | 5802 | |5;yui.html;718.7727105297215;737.4469148138896;749.7113611259665;760.5664771080483;772.1892013385947;11229.270097443661;13133.67165820855;13503.729925401487;13657.453701851939;14060.15639061657;14350.354234059467;14395.136291822499;14421.63945901353;14462.204578748164;14491.34788934498;670.0384947600185;884.7876509637673;892.3262936482946;892.4600616626944;892.8839250897407;878.7275944457207;897.4525539420948;897.4794619120894;897.6588896219461;898.6854600889702;909.963601455941;919.8758167647383;921.5944984206961;924.6255266617031;924.8242833861544;899.4648184330313;903.7605034665819;907.6596276396351;911.6672414568701;912.1927094521396;668.6021817018866;669.554746093847;669.5948950884742;671.2917871645412;671.4762485261504;12018.819717704242;12075.516979320817;12104.87895121055;12116.939415302868;12133.696657583543;12814.935925320313;13088.280144592043;13102.737945241137;13197.27414992175;13452.394642241123;10015.44915029673;10035.79928401428;10044.146247568966;10179.745506362326;10283.897161028368;3174.841257937117;3217.2439476722952;3219.8390080495883;3247.805131692106;3263.8368081596063;44744.31534107964;45297.54702452966;45370;45756;45826.999999999665;10136.645217417356;10217.591296348062;10228.386296822215;10438.530266138043;10466.424346660855;18542.25830966746;18636.534086647942;18706;18749.25002999892;19230.807691923183;6796.184457864989;6800.557963732342;6818.386345228953;6909.136357955255;6927.064846245793;3618.8371523281517;3641.5812181598926;3649.671529562352;3735.7198210133806;3761.962380376161;2889.6387951506063;2977.374751302251;2995.7004299569867;3005.1285127312835;3012.1565961530805;518.8625014371195;540.7296351824087;545.0652131594255;546.3962321634536;548.5529293625762;546.5545580352;621.1986537366746;641.3073880209363;649.6069877724003;657.8618490117129;7919.366450683931;7999.680012799423;8009.639566219534;8115.269625733594;8169.223923727236;439.61533658049206;502.8617130289127;506.97465126743515;511.4348644747575;515.5023909160113;3699.7595156314756;3844.59631738669;3886.028492876781;3901.8634347797692;3921.274564205631;50486.485405437896;51283.487165128616;51314.23028654573;52409.73795131;52524;47691.76154119277;48133.75933120312;48593;48995.26507102396;49319.260211096866;44824.77587612041;44845.55154448479;44880.77559612181;45291;45964.77017614958;7246.094238220223;7273.199948005609;7473.738419155521;7519.661615227437;7603.581803000888;29815.85092074526;30181.245468863453;30255.394892101594;30377.696223038372;30765.23086922845;38707.6129238704;39592.60407396004;39622;39904.800475997436;39932.80033599814;9682.9031709682;9703.951480242553;9888.901110989085;10126.13927816144;10153.187744980236;10121.949390253296;10306.175505959356;10359.63741269052;10619.1504679627;10673.252872298865;10186.08325250731;10240.743981400226;10278.743031424274;10282.126019288444;10305.433201174008;9795.706128816148;9948.104670579683;10065.84901226497;10112.24158188124;10554.525046373084;9692.563834627317;9807.117359437685;9851.556679949563;9905.15806156456;10115.494225288854;8100.13330470863;8169.2647661710735;8244;8444.36667249971;8738.606762695821;7549.471536992358;7557.697692092254;7700.807479813049;7804.917017834802;7853.489523180975 04:42:22 INFO - PROCESS | 5802 | __end_tp_report 04:42:22 INFO - PROCESS | 5802 | __start_cc_report 04:42:22 INFO - PROCESS | 5802 | _x_x_mozilla_cycle_collect,365 04:42:22 INFO - PROCESS | 5802 | __end_cc_report 04:42:22 INFO - PROCESS | 5802 | __startTimestamp1462794142835__endTimestamp 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | ------- Summary: start ------- 04:42:22 INFO - PROCESS | 5802 | Number of tests: 6 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | [#0] dojo.html Cycles:165 Average:4738.55 Median:4901.66 stddev:624.40 (12.7%) stddev-sans-first:603.93 04:42:22 INFO - PROCESS | 5802 | Values: 2626.4 2664.7 2668.8 2694.7 2710.1 4712.8 4719.3 4770.3 4783.4 4794.0 4688.9 4757.4 4762.3 4779.8 4782.3 10.2 4384.3 4483.9 4492.2 4494.5 4256.8 4386.3 4391.4 4427.3 4495.8 4475.0 4492.0 4493.6 4499.5 4536.0 4308.6 4475.0 4507.0 4513.4 4522.4 4354.9 4380.1 4396.8 4413.3 4422.2 4667.3 4690.8 4696.2 4714.6 4778.1 4928.3 5105.1 5214.8 5226.9 5247.5 5198.8 5235.2 5241.8 5253.1 5294.4 4886.9 4893.1 4894.1 4901.7 4940.2 5201.3 5224.9 5235.6 5332.5 5339.9 5047.8 5050.7 5077.3 5096.5 5125.6 5149.5 5170.1 5176.0 5179.5 5223.4 4931.2 5101.4 5111.6 5125.9 5146.0 5061.9 5087.6 5099.8 5143.2 5194.9 4435.0 4450.6 4457.0 4462.4 4501.7 3735.7 3792.4 3829.0 3859.5 3897.5 4363.3 4363.6 4391.2 4401.8 4415.2 5149.3 5151.2 5152.1 5154.3 5162.5 4278.4 4377.3 4398.1 4438.6 4456.0 4644.3 4662.5 4679.0 4681.9 4743.5 4867.7 4881.7 4884.5 4890.6 4936.7 5050.0 5117.1 5128.5 5161.1 5192.4 4848.2 4848.7 4876.6 4884.9 4924.7 4926.6 5080.9 5103.2 5114.7 5168.0 5120.5 5174.5 5184.9 5198.9 5213.9 4986.1 5015.0 5017.3 5028.2 5037.3 4826.5 4993.9 5007.5 5053.9 5057.1 4947.9 4966.4 4977.7 5011.0 5044.7 5040.8 5095.7 5099.8 5102.2 5139.3 4961.1 4984.4 5004.5 5007.9 5020.2 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | [#1] ext.html Cycles:180 Average:45449.79 Median:36228.05 stddev:82410.68 (227.5%) stddev-sans-first:82572.92 04:42:22 INFO - PROCESS | 5802 | Values: 552.6 598.5 600.8 613.2 617.0 33922.3 34506.0 35012.6 35025.5 35069.6 38031.6 38152.8 38548.2 38604.8 38619.8 7677.9 7888.9 7916.2 7932.0 7934.9 7818.0 7872.9 7877.0 7913.3 7917.2 7850.9 7912.1 7923.7 7943.4 7960.7 7823.6 7842.6 7868.8 7897.2 7899.0 7802.7 7877.7 7906.2 7917.6 7926.8 29852.9 37456.6 37763.4 37820.0 37912.0 3168.3 3264.6 3318.2 3446.4 3491.3 28067.3 28094.2 29044.3 30053.0 31378.2 3199.5 3229.4 3260.4 3281.8 3304.3 238348.0 241774.0 244288.0 246103.0 246641.0 34220.7 35613.0 60485.0 61727.7 61856.0 37029.3 37093.4 37125.4 37161.3 37256.6 12400.0 12410.3 12439.6 12545.6 12548.9 9616.2 9632.5 9648.3 9650.2 9652.1 8529.5 8597.9 8602.2 8610.1 8680.9 3331.0 3338.7 3356.9 3372.4 3421.4 944.6 961.2 970.7 1014.6 1040.6 29195.4 29268.3 29373.4 29541.7 29806.4 1019.6 1037.0 1047.9 1048.4 1109.2 15512.7 15551.0 15713.7 15740.8 15781.7 375391.0 379946.0 382100.0 383139.0 385420.0 48765.8 49890.3 49916.8 50012.5 50099.7 1172.0 1202.9 1204.2 1212.1 1273.9 27571.2 27979.9 28684.0 28788.0 28866.0 43447.6 43768.6 43773.1 43867.0 43897.6 293759.0 297752.0 298096.0 299842.0 300180.0 26028.3 26543.3 26555.5 26562.3 26598.5 40870.0 41537.4 42060.6 42096.0 42448.0 36752.6 36758.6 36779.4 36847.6 37405.4 36138.6 36150.0 36180.0 36219.0 36237.1 33976.7 35108.6 37390.0 37456.8 38216.8 32093.4 32389.7 32699.7 33470.2 33614.2 25340.4 25566.2 25667.9 25692.4 25724.0 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | [#2] jquery.html Cycles:180 Average:24983.61 Median:214812.40 stddev:70663.97 (32.9%) stddev-sans-first:70838.04 04:42:22 INFO - PROCESS | 5802 | Values: 374.7 380.9 384.7 384.8 399.3 15985.0 16471.7 17281.1 17395.0 17935.5 10787.8 11348.2 11355.9 11361.9 11377.4 4665.3 4715.9 4722.0 4800.0 4825.9 4710.9 4787.3 4788.7 4796.6 4828.6 4684.9 4751.0 4789.7 4794.4 4820.5 4770.5 4776.0 4803.4 4825.5 4826.2 4548.2 4646.0 4680.4 4684.1 4690.0 19176.8 20265.8 20410.6 20412.8 20636.9 1927.5 1963.5 1997.8 2035.5 2503.4 9284.9 9404.6 9485.4 9517.9 9779.4 1762.8 1794.7 1797.4 1805.8 1840.1 65026.7 65301.7 66691.0 66943.0 66952.7 10216.3 10242.0 10502.6 10566.3 10598.6 14407.8 14426.4 14510.4 14603.0 14638.5 6844.2 6913.9 7115.3 7116.8 7146.5 6451.0 6474.5 6481.8 6542.3 6573.7 4391.8 4424.9 4498.0 4537.9 4546.7 1853.9 1892.4 1895.4 1913.0 1922.2 836.5 939.2 971.5 980.5 1023.1 16974.6 17085.8 17146.2 17540.2 17620.6 895.2 928.1 928.1 929.6 940.5 9604.7 9878.7 9905.2 9918.9 9936.5 416670.0 424547.0 425233.0 439511.0 443817.0 56263.0 68862.3 77787.0 78410.0 80470.0 943.9 1004.2 1004.9 1021.7 1024.3 15693.7 16041.4 16122.3 16189.4 16387.8 34574.5 36118.5 36392.6 36498.8 36779.4 48741.3 49083.8 49734.8 50023.2 50431.2 7567.4 7576.8 7623.1 7773.8 7871.6 7593.8 8004.6 11618.4 11756.6 11999.6 10341.3 10342.9 10402.3 10511.9 10935.0 10134.6 10520.8 10572.5 10576.2 11013.2 10528.3 10616.8 10716.6 10843.1 11042.1 7626.4 7710.5 7713.7 7809.2 8007.4 10784.2 10824.0 10904.6 10915.8 10982.9 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | [#3] mootools.html Cycles:180 Average:4908.36 Median:5153.96 stddev:2648.55 (51.4%) stddev-sans-first:2643.42 04:42:22 INFO - PROCESS | 5802 | Values: 1475.5 1485.5 1643.2 1798.0 1798.9 6469.5 7004.6 7197.6 7449.2 7451.0 7214.1 7396.6 7403.9 7497.3 7509.4 2169.6 2202.5 2207.9 2209.2 2215.3 2143.2 2157.7 2179.0 2189.1 2196.2 2194.8 2194.9 2202.7 2208.5 2232.3 2147.6 2151.7 2151.8 2153.4 2160.2 2113.9 2123.3 2130.2 2162.1 2164.6 7391.0 7391.6 7451.6 7466.9 7480.6 4161.8 4215.2 4237.7 4247.6 4267.4 2847.0 2860.2 2968.4 3019.4 3021.8 920.8 982.2 990.7 1011.4 1037.1 9721.3 9751.9 9772.1 9772.7 9787.8 4089.3 4141.1 4240.6 4245.5 4300.9 8444.7 8448.5 8448.5 8477.6 8525.4 4351.8 4404.0 4443.6 4470.9 4503.7 3464.7 3943.6 3997.3 4014.7 4067.5 3610.8 3617.8 3641.9 3687.5 3809.5 1198.9 1213.3 1223.9 1234.6 1239.8 893.9 904.6 917.8 927.6 941.3 5138.2 5168.2 5191.4 5235.6 5292.0 406.5 410.5 414.1 414.7 416.1 3483.9 3522.9 3600.5 3608.6 3643.2 8804.0 9194.1 9215.0 9221.8 9283.0 8995.1 9007.5 9012.6 9118.9 9122.5 7000.3 7059.4 7100.2 7103.8 7110.0 5098.7 5114.4 5139.7 5225.3 5226.0 7527.1 7546.5 7569.1 7596.6 7617.8 8431.9 8587.1 8710.1 8726.5 8752.5 5700.8 6045.7 6213.6 6432.4 6582.5 7082.3 7090.1 7118.6 7162.7 7193.6 6275.2 6519.0 6529.7 6596.6 6623.1 6468.5 6490.5 6490.8 6510.0 6562.4 6133.3 6135.5 6165.0 6193.0 6195.3 4467.8 4534.4 4536.3 4562.6 4572.5 4617.3 4619.7 4627.5 4628.4 4645.1 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | [#4] prototype.html Cycles:180 Average:4333.52 Median:4954.21 stddev:1256.88 (25.4%) stddev-sans-first:1223.70 04:42:22 INFO - PROCESS | 5802 | Values: 316.0 318.4 318.6 323.2 324.4 5011.3 5048.1 5064.2 5077.7 5091.1 4946.8 4961.6 4961.9 4975.5 5017.4 3746.8 3852.0 3953.9 3957.9 3978.5 3877.6 3935.9 3937.1 3982.8 3987.0 3932.0 3952.0 3967.2 3967.8 3968.2 3829.5 3914.7 3921.5 3935.9 3947.0 3469.4 3476.7 3482.2 3493.7 3499.7 4753.3 4760.7 4762.2 4771.1 4774.5 4417.2 4518.6 4522.7 4538.8 4548.8 5194.5 5197.5 5219.2 5225.7 5257.7 3731.8 3776.6 3784.9 3804.9 3812.7 5469.2 5496.6 5552.4 5552.4 5552.7 3617.5 3842.3 3847.5 3855.1 3859.5 3867.7 3871.8 3881.7 3890.0 3908.2 4406.8 4430.9 4445.4 4457.1 4468.6 5044.9 5181.7 5260.4 5288.2 5305.0 1291.0 1299.5 1302.3 1305.3 1306.7 836.9 851.6 887.1 887.3 892.9 4480.3 4553.0 4554.1 4583.7 4601.9 5343.2 5379.4 5387.8 5402.2 5407.9 4408.2 4554.4 4567.0 4567.7 4578.9 2758.9 2792.1 2792.3 2805.9 2810.2 4917.8 4919.7 4961.9 5000.7 5007.1 5183.5 5405.0 5436.5 5474.6 5484.3 5011.6 5015.1 5036.2 5049.8 5056.5 5229.8 5243.7 5262.2 5273.3 5303.1 4908.0 5103.7 5175.5 5176.7 5207.7 4890.1 4897.9 4962.3 4971.7 4975.8 3675.6 3686.2 3696.6 3697.0 3717.9 5009.2 5313.3 5317.6 5325.4 5426.0 5279.4 5283.5 5291.6 5304.9 5304.9 5248.8 5299.3 5301.5 5312.9 5316.8 5113.6 5120.1 5166.8 5238.7 5263.3 5050.3 5150.5 5302.6 5303.5 5342.3 5142.8 5185.8 5213.9 5215.2 5233.9 04:42:22 INFO - PROCESS | 5802 | 04:42:22 INFO - PROCESS | 5802 | [#5] yui.html Cycles:180 Average:12826.86 Median:45895.89 stddev:14761.07 (32.2%) stddev-sans-first:14774.47 04:42:22 INFO - PROCESS | 5802 | Values: 718.8 737.4 749.7 760.6 772.2 11229.3 13133.7 13503.7 13657.5 14060.2 14350.4 14395.1 14421.6 14462.2 14491.3 670.0 884.8 892.3 892.5 892.9 878.7 897.5 897.5 897.7 898.7 910.0 919.9 921.6 924.6 924.8 899.5 903.8 907.7 911.7 912.2 668.6 669.6 669.6 671.3 671.5 12018.8 12075.5 12104.9 12116.9 12133.7 12814.9 13088.3 13102.7 13197.3 13452.4 10015.4 10035.8 10044.1 10179.7 10283.9 3174.8 3217.2 3219.8 3247.8 3263.8 44744.3 45297.5 45370.0 45756.0 45827.0 10136.6 10217.6 10228.4 10438.5 10466.4 18542.3 18636.5 18706.0 18749.3 19230.8 6796.2 6800.6 6818.4 6909.1 6927.1 3618.8 3641.6 3649.7 3735.7 3762.0 2889.6 2977.4 2995.7 3005.1 3012.2 518.9 540.7 545.1 546.4 548.6 546.6 621.2 641.3 649.6 657.9 7919.4 7999.7 8009.6 8115.3 8169.2 439.6 502.9 507.0 511.4 515.5 3699.8 3844.6 3886.0 3901.9 3921.3 50486.5 51283.5 51314.2 52409.7 52524.0 47691.8 48133.8 48593.0 48995.3 49319.3 44824.8 44845.6 44880.8 45291.0 45964.8 7246.1 7273.2 7473.7 7519.7 7603.6 29815.9 30181.2 30255.4 30377.7 30765.2 38707.6 39592.6 39622.0 39904.8 39932.8 9682.9 9704.0 9888.9 10126.1 10153.2 10121.9 10306.2 10359.6 10619.2 10673.3 10186.1 10240.7 10278.7 10282.1 10305.4 9795.7 9948.1 10065.8 10112.2 10554.5 9692.6 9807.1 9851.6 9905.2 10115.5 8100.1 8169.3 8244.0 8444.4 8738.6 7549.5 7557.7 7700.8 7804.9 7853.5 04:42:22 INFO - PROCESS | 5802 | -------- Summary: end -------- 04:42:22 INFO - PROCESS | 5802 | 04:42:23 INFO - TEST-INFO | 5802: exit 0 04:42:23 INFO - TEST-OK | dromaeo_css | took 1114013ms 04:42:23 INFO - TEST-START | kraken 04:42:23 INFO - operating with platform_type : linux_ 04:42:23 INFO - Initialising browser for kraken test... 04:42:23 INFO - TEST-INFO | started process 6037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCZpCeD/profile http://localhost:53802/getInfo.html) 04:42:27 INFO - PROCESS | 6037 | 04:42:27 INFO - PROCESS | 6037 | (process:6103): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:42:28 INFO - PROCESS | 6037 | __metrics Screen width/height:1600/1200 04:42:28 INFO - PROCESS | 6037 | colorDepth:24 04:42:28 INFO - PROCESS | 6037 | Browser inner width/height: 1024/697 04:42:28 INFO - PROCESS | 6037 | __metrics 04:42:28 ERROR - PROCESS | 6037 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 04:42:29 INFO - TEST-INFO | 6037: exit 0 04:42:29 INFO - Browser initialized. 04:42:29 INFO - Running cycle 1/1 for kraken test... 04:42:29 INFO - TEST-INFO | started process 6154 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCZpCeD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:31 INFO - PROCESS | 6154 | 04:42:31 INFO - PROCESS | 6154 | (process:6213): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:42:41 INFO - PROCESS | 6154 | RSS: Main: 164409344 04:42:41 INFO - PROCESS | 6154 | 04:42:44 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53802/tests/kraken/driver.html?testName=audio-beat-detection) 04:42:45 INFO - PROCESS | 6154 | RSS: Main: 167464960 04:42:45 INFO - PROCESS | 6154 | 04:42:48 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53802/tests/kraken/driver.html?testName=audio-dft) 04:42:48 INFO - PROCESS | 6154 | RSS: Main: 167407616 04:42:48 INFO - PROCESS | 6154 | 04:42:51 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53802/tests/kraken/driver.html?testName=audio-fft) 04:42:52 INFO - PROCESS | 6154 | RSS: Main: 163917824 04:42:52 INFO - PROCESS | 6154 | 04:42:54 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53802/tests/kraken/driver.html?testName=audio-oscillator) 04:42:55 INFO - PROCESS | 6154 | RSS: Main: 164237312 04:42:55 INFO - PROCESS | 6154 | 04:42:57 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53802/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:42:58 INFO - PROCESS | 6154 | RSS: Main: 164364288 04:42:58 INFO - PROCESS | 6154 | 04:43:01 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53802/tests/kraken/driver.html?testName=imaging-darkroom) 04:43:01 INFO - PROCESS | 6154 | RSS: Main: 164438016 04:43:01 INFO - PROCESS | 6154 | 04:43:05 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53802/tests/kraken/driver.html?testName=imaging-desaturate) 04:43:06 INFO - PROCESS | 6154 | RSS: Main: 162721792 04:43:06 INFO - PROCESS | 6154 | 04:43:09 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53802/tests/kraken/driver.html?testName=json-parse-financial) 04:43:09 INFO - PROCESS | 6154 | RSS: Main: 163094528 04:43:09 INFO - PROCESS | 6154 | 04:43:11 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53802/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:43:12 INFO - PROCESS | 6154 | RSS: Main: 163540992 04:43:12 INFO - PROCESS | 6154 | 04:43:14 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:43:14 INFO - PROCESS | 6154 | RSS: Main: 163344384 04:43:14 INFO - PROCESS | 6154 | 04:43:17 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:43:17 INFO - PROCESS | 6154 | RSS: Main: 164458496 04:43:17 INFO - PROCESS | 6154 | 04:43:20 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:43:21 INFO - PROCESS | 6154 | RSS: Main: 164372480 04:43:21 INFO - PROCESS | 6154 | 04:43:24 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:43:24 INFO - PROCESS | 6154 | RSS: Main: 164171776 04:43:24 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | Cycle 1(1): loaded http://localhost:53802/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53802/tests/kraken/driver.html?testName=ai-astar) 04:43:26 INFO - PROCESS | 6154 | RSS: Main: 165941248 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | __start_tp_report 04:43:26 INFO - PROCESS | 6154 | _x_x_mozilla_page_load 04:43:26 INFO - PROCESS | 6154 | _x_x_mozilla_page_load_details 04:43:26 INFO - PROCESS | 6154 | |i|pagename|runs| 04:43:26 INFO - PROCESS | 6154 | |0;ai-astar;112;115;107;115;115;115;109;115;115;116 04:43:26 INFO - PROCESS | 6154 | |1;audio-beat-detection;158;132;159;215;133;157;158;132;189;131 04:43:26 INFO - PROCESS | 6154 | |2;audio-dft;162;163;167;168;161;164;162;165;166;161 04:43:26 INFO - PROCESS | 6154 | |3;audio-fft;76;75;77;160;77;75;75;77;162;77 04:43:26 INFO - PROCESS | 6154 | |4;audio-oscillator;94;94;186;93;94;93;142;94;92;151 04:43:26 INFO - PROCESS | 6154 | |5;imaging-gaussian-blur;117;118;117;117;118;117;117;117;117;124 04:43:26 INFO - PROCESS | 6154 | |6;imaging-darkroom;147;146;146;147;147;147;147;147;147;147 04:43:26 INFO - PROCESS | 6154 | |7;imaging-desaturate;95;95;95;94;95;96;95;95;95;95 04:43:26 INFO - PROCESS | 6154 | |8;json-parse-financial;65;66;70;66;65;65;65;64;65;64 04:43:26 INFO - PROCESS | 6154 | |9;json-stringify-tinderbox;65;66;65;65;65;65;66;66;65;65 04:43:26 INFO - PROCESS | 6154 | |10;stanford-crypto-aes;88;82;85;82;83;89;84;83;144;90 04:43:26 INFO - PROCESS | 6154 | |11;stanford-crypto-ccm;139;150;132;134;132;130;147;135;146;146 04:43:26 INFO - PROCESS | 6154 | |12;stanford-crypto-pbkdf2;175;174;176;178;173;172;172;170;172;176 04:43:26 INFO - PROCESS | 6154 | |13;stanford-crypto-sha256-iterative;70;70;72;71;69;69;70;68;72;66 04:43:26 INFO - PROCESS | 6154 | __end_tp_report 04:43:26 INFO - PROCESS | 6154 | __start_cc_report 04:43:26 INFO - PROCESS | 6154 | _x_x_mozilla_cycle_collect,926 04:43:26 INFO - PROCESS | 6154 | __end_cc_report 04:43:26 INFO - PROCESS | 6154 | __startTimestamp1462794206959__endTimestamp 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | ------- Summary: start ------- 04:43:26 INFO - PROCESS | 6154 | Number of tests: 14 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:3.06 (2.7%) stddev-sans-first:3.21 04:43:26 INFO - PROCESS | 6154 | Values: 112.0 115.0 107.0 115.0 115.0 115.0 109.0 115.0 115.0 116.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#1] audio-beat-detection Cycles:10 Average:156.40 Median:158.00 stddev:27.67 (17.5%) stddev-sans-first:29.35 04:43:26 INFO - PROCESS | 6154 | Values: 158.0 132.0 159.0 215.0 133.0 157.0 158.0 132.0 189.0 131.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.50 stddev:2.51 (1.5%) stddev-sans-first:2.57 04:43:26 INFO - PROCESS | 6154 | Values: 162.0 163.0 167.0 168.0 161.0 164.0 162.0 165.0 166.0 161.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#3] audio-fft Cycles:10 Average:93.10 Median:76.50 stddev:35.80 (46.8%) stddev-sans-first:37.43 04:43:26 INFO - PROCESS | 6154 | Values: 76.0 75.0 77.0 160.0 77.0 75.0 75.0 77.0 162.0 77.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#4] audio-oscillator Cycles:10 Average:113.30 Median:93.50 stddev:33.83 (36.2%) stddev-sans-first:35.15 04:43:26 INFO - PROCESS | 6154 | Values: 94.0 94.0 186.0 93.0 94.0 93.0 142.0 94.0 92.0 151.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:117.00 stddev:2.18 (1.9%) stddev-sans-first:2.29 04:43:26 INFO - PROCESS | 6154 | Values: 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 124.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 04:43:26 INFO - PROCESS | 6154 | Values: 147.0 146.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 04:43:26 INFO - PROCESS | 6154 | Values: 95.0 95.0 95.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:65.00 stddev:1.72 (2.6%) stddev-sans-first:1.81 04:43:26 INFO - PROCESS | 6154 | Values: 65.0 66.0 70.0 66.0 65.0 65.0 65.0 64.0 65.0 64.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 04:43:26 INFO - PROCESS | 6154 | Values: 65.0 66.0 65.0 65.0 65.0 65.0 66.0 66.0 65.0 65.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:84.50 stddev:18.85 (22.3%) stddev-sans-first:19.96 04:43:26 INFO - PROCESS | 6154 | Values: 88.0 82.0 85.0 82.0 83.0 89.0 84.0 83.0 144.0 90.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:142.50 stddev:7.48 (5.2%) stddev-sans-first:7.93 04:43:26 INFO - PROCESS | 6154 | Values: 139.0 150.0 132.0 134.0 132.0 130.0 147.0 135.0 146.0 146.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:174.50 stddev:2.44 (1.4%) stddev-sans-first:2.55 04:43:26 INFO - PROCESS | 6154 | Values: 175.0 174.0 176.0 178.0 173.0 172.0 172.0 170.0 172.0 176.0 04:43:26 INFO - PROCESS | 6154 | 04:43:26 INFO - PROCESS | 6154 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.83 (2.6%) stddev-sans-first:1.94 04:43:26 INFO - PROCESS | 6154 | Values: 70.0 70.0 72.0 71.0 69.0 69.0 70.0 68.0 72.0 66.0 04:43:26 INFO - PROCESS | 6154 | -------- Summary: end -------- 04:43:26 INFO - PROCESS | 6154 | 04:43:28 INFO - TEST-INFO | 6154: exit 0 04:43:28 INFO - TEST-OK | kraken | took 64486ms 04:43:28 INFO - SUITE-END | took 1178s 04:43:28 INFO - Completed test suite (00:19:38) 04:43:28 INFO - javascript benchmark 04:43:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2626.3565426470514, 2664.733526647334, 2668.7598116169543, 2694.7305269473068, 2710.078573285084, 4712.835050773224, 4719.268513380427, 4770.284457331393, 4783.35424717663, 4793.976030119853, 4688.859334219979, 4757.381540399751, 4762.309465127554, 4779.832705855296, 4782.282657601361, 10.205342228094768, 4384.276594361934, 4483.865484035484, 4492.168948744476, 4494.528074552174, 4256.808443620044, 4386.298192289234, 4391.36325232841, 4427.335899615051, 4495.775211239441, 4474.97762511187, 4492.011757413364, 4493.550644935481, 4499.527549607294, 4536.0, 4308.633766129883, 4474.970756725938, 4506.954930450686, 4513.435820522434, 4522.3668686383935, 4354.869353919387, 4380.058287468178, 4396.824127034914, 4413.337999300099, 4422.181896349153, 4667.253239481666, 4690.812367505296, 4696.178168820477, 4714.575688188045, 4778.092162489116, 4928.28539861718, 5105.081085404626, 5214.8174813881715, 5226.895462090775, 5247.47525247476, 5198.844034678966, 5235.240890070956, 5241.816536421207, 5253.133233016548, 5294.391145018334, 4886.9266960995255, 4893.070316639827, 4894.094592500399, 4901.65688401812, 4940.209566469383, 5201.297824793604, 5224.92162617561, 5235.60732945023, 5332.520073193431, 5339.919901201485, 5047.823326183639, 5050.722210278396, 5077.289179514871, 5096.464871188546, 5125.615578831603, 5149.510796474329, 5170.146925757208, 5175.9741201293755, 5179.533841954242, 5223.373195216598, 4931.186354251508, 5101.413337466165, 5111.591072714174, 5125.8718532036255, 5146.0, 5061.924071138936, 5087.592992560586, 5099.796008159707, 5143.228515722672, 5194.92207616886, 4434.955650443519, 4450.599446049806, 4456.977715111469, 4462.397576327219, 4501.662375321864, 3735.7198210134347, 3792.3742582474133, 3829.0427393151713, 3859.4982652255026, 3897.4738410314812, 4363.323684828857, 4363.563643635611, 4391.187630288343, 4401.823927042947, 4415.205263052681, 5149.253358263106, 5151.2273159026445, 5152.124138896396, 5154.278401023859, 5162.5095615916425, 4278.379634952915, 4377.277749171415, 4398.120375924827, 4438.556144385536, 4455.952851079994, 4644.3265726470145, 4662.463816661059, 4679.0, 4681.906361872811, 4743.525647435229, 4867.732274724854, 4881.682690625098, 4884.462709102067, 4890.559849613552, 4936.703797772145, 5050.0, 5117.078925793393, 5128.487151284841, 5161.09680805865, 5192.42883282831, 4848.151573474698, 4848.7090774553635, 4876.5854902333695, 4884.92672609904, 4924.729139897197, 4926.556609905126, 5080.898382032265, 5103.157978933435, 5114.667546609458, 5168.01807656544, 5120.462351453044, 5174.4566820484815, 5184.948150518447, 5198.934218485277, 5213.895722085613, 4986.052649996489, 5014.974925125351, 5017.272495488207, 5028.170351891897, 5037.269595908646, 4826.493218212177, 4993.925091123709, 5007.47421520735, 5053.924191137209, 5057.115004874058, 4947.876303092451, 4966.379202599675, 4977.676451030671, 5010.974945125251, 5044.747762611928, 5040.773165207501, 5095.719735414587, 5099.77051032697, 5102.183650615883, 5139.254808052886, 4961.05739909416, 4984.352034235526, 5004.499550044967, 5007.899842003212, 5020.246962955586], "value": 4701.250078865916, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.5745176214315, 598.5301538292439, 600.8347704381295, 613.2395829171828, 616.9568130230883, 33922.321553568945, 34506.0, 35012.64987350126, 35025.474617880755, 35069.64930350696, 38031.619683803154, 38152.80923595385, 38548.229035419274, 38604.80697596515, 38619.80690096552, 7677.884831727514, 7888.881666775003, 7916.168802275735, 7931.960340198291, 7934.920650793505, 7817.960910195441, 7872.921270787276, 7877.0, 7913.327367173797, 7917.208279172094, 7850.9214907850765, 7912.090109637408, 7923.722669706562, 7943.443958922849, 7960.681572737083, 7823.647935842901, 7842.568658723768, 7868.763937081897, 7897.24976127267, 7899.0126234220725, 7802.687892484294, 7877.684892604347, 7906.169852165497, 7917.643706033243, 7926.841463170762, 29852.8507357464, 37456.62543374558, 37763.433548496796, 37820.0, 37912.0, 3168.3346497235607, 3264.6245681746427, 3318.1704573856537, 3446.3796516627, 3491.30173965208, 28067.29831754202, 28094.157175284774, 29044.27389315263, 30053.0, 31378.21554461134, 3199.5056215612826, 3229.40256052631, 3260.3805276997296, 3281.8359082045804, 3304.322613864152, 238348.0, 241774.0, 244288.0, 246103.0, 246641.0, 34220.657793422244, 35613.0, 60485.0, 61727.69136154291, 61856.0, 37029.25941481155, 37093.44359834605, 37125.44311835324, 37161.25677486435, 37256.62743372531, 12400.0, 12410.255384676768, 12439.626811195678, 12545.560905368267, 12548.874511254953, 9616.23070154386, 9632.518374081268, 9648.276379271443, 9650.227981761442, 9652.131308182297, 8529.488230706178, 8597.914020859711, 8602.225799678059, 8610.052894181636, 8680.913190868137, 3330.966690333066, 3338.6995170434293, 3356.9496457553155, 3372.409828280041, 3421.3733442550974, 944.5654998700675, 961.1541843177959, 970.6997998865096, 1014.6195176808696, 1040.5837664934088, 29195.41609167805, 29268.268293292836, 29373.412531749247, 29541.704582953895, 29806.40387192244, 1019.6380284998867, 1037.0459177556666, 1047.8899715529913, 1048.3814549415881, 1109.1570406491126, 15512.689746205013, 15550.989185702892, 15713.664338531353, 15740.763888541682, 15781.68436631261, 375391.0, 379946.0, 382100.0, 383139.0, 385420.0, 48765.756171219626, 49890.25164622533, 49916.75041624768, 50012.499875001515, 50099.74950125226, 1171.9906082907444, 1202.8857258560422, 1204.2172587817813, 1212.1090998116376, 1273.8726127387188, 27571.17286481409, 27979.860100699367, 28684.0, 28788.0, 28866.0, 43447.565524344354, 43768.562314376446, 43773.12453750843, 43866.99999999873, 43897.56102439063, 293759.0, 297752.0, 298096.0, 299842.0, 300180.0, 26028.34929126787, 26543.33641658974, 26555.468890622466, 26562.335941601614, 26598.468030639666, 40870.0, 41537.37693934654, 42060.57939420567, 42096.0, 42448.0, 36752.63247367599, 36758.63241367552, 36779.44830827486, 36847.63152368442, 37405.438918415704, 36138.638613613526, 36150.0, 36180.0, 36219.0, 36237.0940726484, 33976.66023339735, 35108.64891351156, 37390.0, 37456.81271593625, 38216.80891595524, 32093.358132836747, 32389.676103238668, 32699.67300326966, 33470.16324591807, 33614.159646009044, 25340.36649083714, 25566.233013009638, 25667.871660641576, 25692.357691057125, 25724.0], "value": 16591.78265526074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.69649583837077, 380.89906174863665, 384.73837790302593, 384.77298393947564, 399.301222859995, 15985.0, 16471.670566588677, 17281.13594320285, 17394.999999999985, 17935.463347714598, 10787.78424431513, 11348.205625606211, 11355.943220283907, 11361.886381136206, 11377.374244416555, 4665.346851440801, 4715.858524244278, 4721.976390118045, 4800.039992001596, 4825.879353016185, 4710.90578188436, 4787.329773831666, 4788.688735232199, 4796.616270698353, 4828.613710903137, 4684.906301873961, 4751.049790041988, 4789.688670236441, 4794.448638406593, 4820.542048505386, 4770.546798054179, 4775.9761201194115, 4803.423589169322, 4825.469198388157, 4826.155422801014, 4548.20406428877, 4646.047560250175, 4680.3681502997, 4684.086603112394, 4689.95310046902, 19176.80823191778, 20265.797342026686, 20410.591788164304, 20412.79587204124, 20636.89681551598, 1927.5277556998485, 1963.5091227193202, 1997.7802441731399, 2035.4809523571544, 2503.399184195798, 9284.93223279318, 9404.62381504739, 9485.430874147572, 9517.857232141523, 9779.41323520591, 1762.7972783129976, 1794.6590147871862, 1797.4338083503656, 1805.7562229099167, 1840.1075478392968, 65026.67486662536, 65301.67349163271, 66691.0, 66942.99585506173, 66952.6652366735, 10216.284860059874, 10242.0, 10502.632407865687, 10566.31318964265, 10598.629047983284, 14407.78388324176, 14426.350814213385, 14510.419583216553, 14603.0, 14638.487652932095, 6844.2129155147795, 6913.89629155563, 7115.252898445692, 7116.786496405116, 7146.535475194096, 6450.967745161244, 6474.546781725234, 6481.773137940244, 6542.345765423514, 6573.704183311763, 4391.802368893407, 4424.867253982386, 4498.0, 4537.909241815145, 4546.74992875395, 1853.8980356080276, 1892.4133518609276, 1895.3889194184833, 1913.015695515001, 1922.2311075569605, 836.5231817863881, 939.1547607153616, 971.504532688334, 980.480345416916, 1023.1098943918838, 16974.57563560921, 17085.829141708426, 17146.22841972114, 17540.210690518958, 17620.559486012695, 895.167494230372, 928.0533855467386, 928.076563818991, 929.5863340813273, 940.4968341937031, 9604.711858644252, 9878.654247101316, 9905.158061564704, 9918.900810991798, 9936.50317484123, 416670.0, 424547.0, 425233.0, 439511.0, 443817.0, 56263.0, 68862.31137688659, 77787.0, 78410.0, 80470.0, 943.8914524829732, 1004.1815920025156, 1004.8743907011769, 1021.7394564386034, 1024.2676486312323, 15693.686126277638, 16041.438549650706, 16122.274497647868, 16189.352425902833, 16387.754183687015, 34574.48138277877, 36118.45822312615, 36392.636073638925, 36498.8175059123, 36779.44830827593, 48741.31922744283, 49083.754581226865, 49734.75132624459, 50023.24965125453, 50431.24353134633, 7567.432442566719, 7576.8484630308185, 7623.123340815655, 7773.844523109393, 7871.567063811316, 7593.810154746176, 8004.599770011592, 11618.360990145624, 11756.647300580997, 11999.64001079969, 10341.345384738406, 10342.948285258526, 10402.27184097106, 10511.94744026275, 10935.0, 10134.645287414905, 10520.78958420812, 10572.524236409226, 10576.153907687212, 11013.229073964745, 10528.263021588722, 10616.787664246825, 10716.571337146426, 10843.078338341329, 11042.116630669367, 7626.389888808771, 7710.460267781202, 7713.730019449293, 7809.2190780921455, 8007.358491509343, 10784.245102842726, 10824.025837674648, 10904.563817447213, 10915.78168436611, 10982.890171098506], "value": 8109.3708790002565, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.549957263035, 1485.4503833581573, 1643.2276829889954, 1797.9550511237217, 1798.8920664760108, 6469.514786391016, 7004.614746188958, 7197.60413177275, 7449.21783212763, 7451.0, 7214.134303883528, 7396.630168491567, 7403.925960740391, 7497.2502749725, 7509.361704255131, 2169.60947029535, 2202.4603972026875, 2207.878566678832, 2209.1826024371007, 2215.3021798133536, 2143.239150101715, 2157.7087093242367, 2179.030331502482, 2189.1462329691435, 2196.187410658051, 2194.7914948079906, 2194.9231776887814, 2202.7246594175726, 2208.5251670890757, 2232.2521955145044, 2147.559750251203, 2151.741790985076, 2151.7633060363346, 2153.3755210988793, 2160.2439146298825, 2113.8731676099483, 2123.3311506875284, 2130.222468798893, 2162.0919213930188, 2164.621191291518, 7391.002214701, 7391.630418479054, 7451.627418629101, 7466.887996680054, 7480.588567628778, 4161.791910404498, 4215.199112168678, 4237.703360764748, 4247.617714405687, 4267.381229721704, 2846.9572956405464, 2860.1705505403406, 2968.421157874194, 3019.3659331540402, 3021.7884748067645, 920.7606022434148, 982.2289502740387, 990.658222913101, 1011.4184344002228, 1037.092544023979, 9721.270904682176, 9751.853722194173, 9772.071653192936, 9772.706818795448, 9787.755306117333, 4089.3457046872954, 4141.088960428701, 4240.618344349024, 4245.511766146933, 4300.8709738707885, 8444.746657600283, 8448.450850694686, 8448.493090414595, 8477.576121194039, 8525.445846020113, 4351.804168812411, 4403.9559604404185, 4443.577860103285, 4470.865874023784, 4503.729776213438, 3464.7228221742703, 3943.5859234779937, 3997.340438827619, 4014.7189696720948, 4067.51189857219, 3610.765300255475, 3617.764845285101, 3641.908952276214, 3687.520622319135, 3809.5238095238096, 1198.8561372635165, 1213.2720367779439, 1223.920445171061, 1234.6419538333962, 1239.7954337534206, 893.9016708162097, 904.5703290936752, 917.7968888578812, 927.5501381829807, 941.275218082073, 5138.203578445347, 5168.224766285088, 5191.377034755854, 5235.60732945023, 5292.02097611943, 406.4797059763508, 410.4786920610808, 414.05801800903185, 414.69727099217135, 416.1012213618569, 3483.8819256765055, 3522.939080637804, 3600.4959305697225, 3608.585012723518, 3643.1620727232885, 8804.0, 9194.12655797698, 9214.953925230331, 9221.769455763526, 9282.953585232031, 8995.055519170524, 9007.549622518978, 9012.63949442015, 9118.908810911806, 9122.543872806466, 7000.299970002959, 7059.364657180879, 7100.183478899992, 7103.751368702071, 7110.0, 5098.745062746922, 5114.386273647037, 5139.691618502902, 5225.268462415341, 5226.0, 7527.134379546423, 7546.547207167368, 7569.091708994956, 7596.620168991418, 7617.847643047219, 8431.915680843358, 8587.141285871363, 8710.085441028601, 8726.476411415335, 8752.47485150893, 5700.771969121354, 6045.697715114139, 6213.565050446607, 6432.421082102634, 6582.473402127723, 7082.327178917994, 7090.07828982229, 7118.60847653384, 7162.677679504539, 7193.568385896863, 6275.246970363585, 6519.0, 6529.7388104475285, 6596.60420374779, 6623.138991931018, 6468.514861385321, 6490.51321150925, 6490.8052758417325, 6509.967450162719, 6562.376574225441, 6133.325334213322, 6135.509159267159, 6164.969175154096, 6192.969035154975, 6195.256569211723, 4467.754273514988, 4534.410526631516, 4536.274196128604, 4562.634989200922, 4572.5198854119835, 4617.2612382018715, 4619.745913974763, 4627.5141110184295, 4628.421447319085, 4645.140648979949], "value": 3966.6534170613154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.9905202843915, 318.41411802283795, 318.58742927908355, 323.2258740316941, 324.39986025852164, 5011.34852469179, 5048.091343558158, 5064.215046667763, 5077.669951453153, 5091.1090559152135, 4946.826861059863, 4961.60307175426, 4961.900761984758, 4975.5273249041375, 5017.422996355411, 3746.7751934883927, 3851.9984803950892, 3953.881383558498, 3957.9010524736823, 3978.5225772907293, 3877.592852750463, 3935.9016024599327, 3937.0944682722993, 3982.780947047911, 3987.003249187703, 3931.960680393188, 3952.0, 3967.1668949520636, 3967.7817720025387, 3968.2063587282514, 3829.48301979232, 3914.667253283446, 3921.5490218624936, 3935.9016024599473, 3946.9605303947023, 3469.4275444551618, 3476.6870981611523, 3482.199094208346, 3493.7205023598303, 3499.7200223982277, 4753.31076993834, 4760.738159401231, 4762.238041913277, 4771.141194584973, 4774.498677638832, 4417.182821178092, 4518.638508919278, 4522.728636281834, 4538.773061346952, 4548.795304211319, 5194.532492075731, 5197.454267301954, 5219.19102539107, 5225.660332078441, 5257.684538927677, 3731.7982390389802, 3776.622337766229, 3784.9432258516144, 3804.923901521982, 3812.7331086823933, 5469.234307196995, 5496.615236933376, 5552.3892371839065, 5552.444755524456, 5552.7223638817895, 3617.5297211362354, 3842.289176502356, 3847.51870529848, 3855.1133239354544, 3859.536855577349, 3867.7043190531485, 3871.7870517121264, 3881.7476864004316, 3889.9611003890163, 3908.159745654698, 4406.779661016936, 4430.889227769267, 4445.355423463644, 4457.108578284357, 4468.642508599304, 5044.924326135111, 5181.689098654093, 5260.447652996457, 5288.180332048539, 5305.0, 1290.9612711618665, 1299.5061876486875, 1302.2707283921034, 1305.2560040776666, 1306.660268330241, 836.9455985360933, 851.5784686580182, 887.1350433327455, 887.3211992825494, 892.9196372326522, 4480.305552639346, 4552.9772351138035, 4554.111948170142, 4583.679142459996, 4601.907961840744, 5343.171808369709, 5379.35447746273, 5387.784488620411, 5402.216678581583, 5407.891842163135, 4408.162449134718, 4554.407926969473, 4566.977165114153, 4567.657425693086, 4578.862634120982, 2758.8620568971473, 2792.064658339479, 2792.3437992071845, 2805.859707014641, 2810.1990932584117, 4917.819723266462, 4919.729414882144, 4961.925571116436, 5000.724815172181, 5007.148784706608, 5183.45573714762, 5405.0, 5436.510714035756, 5474.644148130359, 5484.287042684426, 5011.574016208663, 5015.147424937696, 5036.194208926554, 5049.772760225798, 5056.519630635157, 5229.816956406507, 5243.7152897540345, 5262.184361423985, 5273.312539987167, 5303.143899635097, 4907.975460122677, 5103.7192954387865, 5175.534201921845, 5176.689398636094, 5207.661502002357, 4890.119778439915, 4897.853064408074, 4962.33008543842, 4971.676841005323, 4975.825846095369, 3675.6140605236064, 3686.207465394953, 3696.5933747288313, 3697.0, 3717.9442308364855, 5009.173486374853, 5313.2561441397465, 5317.627766056339, 5325.440828712929, 5426.0, 5279.39286982002, 5283.516747128843, 5291.623593569662, 5304.867378315573, 5304.9469505306, 5248.8425347239645, 5299.2512470885285, 5301.469853014668, 5312.920306195488, 5316.803719163157, 5113.616478764182, 5120.142832345634, 5166.819161329336, 5238.68567885928, 5263.315768950012, 5050.292958985819, 5150.536451719363, 5302.628815982845, 5303.46965303482, 5342.332208473941, 5142.76857541404, 5185.792568297376, 5213.921791173059, 5215.243789650555, 5233.921491177559], "value": 3934.514635159373, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.7727105297215, 737.4469148138896, 749.7113611259665, 760.5664771080483, 772.1892013385947, 11229.270097443661, 13133.67165820855, 13503.729925401487, 13657.453701851939, 14060.15639061657, 14350.354234059467, 14395.136291822499, 14421.63945901353, 14462.204578748164, 14491.34788934498, 670.0384947600185, 884.7876509637673, 892.3262936482946, 892.4600616626944, 892.8839250897407, 878.7275944457207, 897.4525539420948, 897.4794619120894, 897.6588896219461, 898.6854600889702, 909.963601455941, 919.8758167647383, 921.5944984206961, 924.6255266617031, 924.8242833861544, 899.4648184330313, 903.7605034665819, 907.6596276396351, 911.6672414568701, 912.1927094521396, 668.6021817018866, 669.554746093847, 669.5948950884742, 671.2917871645412, 671.4762485261504, 12018.819717704242, 12075.516979320817, 12104.87895121055, 12116.939415302868, 12133.696657583543, 12814.935925320313, 13088.280144592043, 13102.737945241137, 13197.27414992175, 13452.394642241123, 10015.44915029673, 10035.79928401428, 10044.146247568966, 10179.745506362326, 10283.897161028368, 3174.841257937117, 3217.2439476722952, 3219.8390080495883, 3247.805131692106, 3263.8368081596063, 44744.31534107964, 45297.54702452966, 45370.0, 45756.0, 45826.999999999665, 10136.645217417356, 10217.591296348062, 10228.386296822215, 10438.530266138043, 10466.424346660855, 18542.25830966746, 18636.534086647942, 18706.0, 18749.25002999892, 19230.807691923183, 6796.184457864989, 6800.557963732342, 6818.386345228953, 6909.136357955255, 6927.064846245793, 3618.8371523281517, 3641.5812181598926, 3649.671529562352, 3735.7198210133806, 3761.962380376161, 2889.6387951506063, 2977.374751302251, 2995.7004299569867, 3005.1285127312835, 3012.1565961530805, 518.8625014371195, 540.7296351824087, 545.0652131594255, 546.3962321634536, 548.5529293625762, 546.5545580352, 621.1986537366746, 641.3073880209363, 649.6069877724003, 657.8618490117129, 7919.366450683931, 7999.680012799423, 8009.639566219534, 8115.269625733594, 8169.223923727236, 439.61533658049206, 502.8617130289127, 506.97465126743515, 511.4348644747575, 515.5023909160113, 3699.7595156314756, 3844.59631738669, 3886.028492876781, 3901.8634347797692, 3921.274564205631, 50486.485405437896, 51283.487165128616, 51314.23028654573, 52409.73795131, 52524.0, 47691.76154119277, 48133.75933120312, 48593.0, 48995.26507102396, 49319.260211096866, 44824.77587612041, 44845.55154448479, 44880.77559612181, 45291.0, 45964.77017614958, 7246.094238220223, 7273.199948005609, 7473.738419155521, 7519.661615227437, 7603.581803000888, 29815.85092074526, 30181.245468863453, 30255.394892101594, 30377.696223038372, 30765.23086922845, 38707.6129238704, 39592.60407396004, 39622.0, 39904.800475997436, 39932.80033599814, 9682.9031709682, 9703.951480242553, 9888.901110989085, 10126.13927816144, 10153.187744980236, 10121.949390253296, 10306.175505959356, 10359.63741269052, 10619.1504679627, 10673.252872298865, 10186.08325250731, 10240.743981400226, 10278.743031424274, 10282.126019288444, 10305.433201174008, 9795.706128816148, 9948.104670579683, 10065.84901226497, 10112.24158188124, 10554.525046373084, 9692.563834627317, 9807.117359437685, 9851.556679949563, 9905.15806156456, 10115.494225288854, 8100.13330470863, 8169.2647661710735, 8244.0, 8444.36667249971, 8738.606762695821, 7549.471536992358, 7557.697692092254, 7700.807479813049, 7804.917017834802, 7853.489523180975], "value": 5941.515930905428, "unit": "score"}], "name": "dromaeo_css", "value": 6233.407888373781, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 107.0, 115.0, 115.0, 115.0, 109.0, 115.0, 115.0, 116.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 132.0, 159.0, 215.0, 133.0, 157.0, 158.0, 132.0, 189.0, 131.0], "value": 156.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 167.0, 168.0, 161.0, 164.0, 162.0, 165.0, 166.0, 161.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 160.0, 77.0, 75.0, 75.0, 77.0, 162.0, 77.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 186.0, 93.0, 94.0, 93.0, 142.0, 94.0, 92.0, 151.0], "value": 113.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 124.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 70.0, 66.0, 65.0, 65.0, 65.0, 64.0, 65.0, 64.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 82.0, 85.0, 82.0, 83.0, 89.0, 84.0, 83.0, 144.0, 90.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 150.0, 132.0, 134.0, 132.0, 130.0, 147.0, 135.0, 146.0, 146.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 176.0, 178.0, 173.0, 172.0, 172.0, 170.0, 172.0, 176.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 72.0, 71.0, 69.0, 69.0, 70.0, 68.0, 72.0, 66.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1604.1999999999998, "alertThreshold": 2.0}]} 04:43:28 INFO - Return code: 0 04:43:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:43:28 ERROR - # TBPL SUCCESS # 04:43:28 INFO - Running post-action listener: _resource_record_post_action 04:43:28 INFO - ##### 04:43:28 INFO - ##### Finished run-tests step (success) 04:43:28 INFO - ##### 04:43:28 INFO - Running post-run listener: _resource_record_post_run 04:43:29 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 518623232; Read time: 12; Write time: 365764 04:43:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 234926080; Read time: 0; Write time: 203344 04:43:29 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283697152; Read time: 12; Write time: 162420 04:43:29 INFO - Running post-run listener: _upload_blobber_files 04:43:29 INFO - Blob upload gear active. 04:43:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:43:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:43:29 INFO - (blobuploader) - INFO - Open directory for files ... 04:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:30 INFO - (blobuploader) - INFO - Done attempting. 04:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:31 INFO - (blobuploader) - INFO - Done attempting. 04:43:31 INFO - (blobuploader) - INFO - Iteration through files over. 04:43:31 INFO - Return code: 0 04:43:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:43:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:43:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1bbea3985803fbdb6863b3bbd218e9358f2de866019640871c4a3b17a453f8ca1441213c6e51b06c1f22eb27ee6d86dff4330b55358b65e47adbc2cd2c90c11b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/47217b68f6efaf4d5f7ce09c04972b12ccad5228b8634a3e9d6fef6056711713ac5d7c55d485628c0bd50babffa2025cc9d8eb0f59c4e39371f7d116fe58a771"} 04:43:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:43:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:43:31 INFO - Contents: 04:43:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1bbea3985803fbdb6863b3bbd218e9358f2de866019640871c4a3b17a453f8ca1441213c6e51b06c1f22eb27ee6d86dff4330b55358b65e47adbc2cd2c90c11b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/47217b68f6efaf4d5f7ce09c04972b12ccad5228b8634a3e9d6fef6056711713ac5d7c55d485628c0bd50babffa2025cc9d8eb0f59c4e39371f7d116fe58a771"} 04:43:31 INFO - Running post-run listener: copy_logs_to_upload_dir 04:43:31 INFO - Copying logs to upload dir... 04:43:31 INFO - mkdir: /builds/slave/test/build/upload/logs 04:43:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.026523 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-09 04:43:31.284684) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 04:43:31.288052) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341 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-Non-PGO/sha512/1bbea3985803fbdb6863b3bbd218e9358f2de866019640871c4a3b17a453f8ca1441213c6e51b06c1f22eb27ee6d86dff4330b55358b65e47adbc2cd2c90c11b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/47217b68f6efaf4d5f7ce09c04972b12ccad5228b8634a3e9d6fef6056711713ac5d7c55d485628c0bd50babffa2025cc9d8eb0f59c4e39371f7d116fe58a771"} build_url:https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/GLgoP16ZRpGNOWTtW1jXtw/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-Non-PGO/sha512/1bbea3985803fbdb6863b3bbd218e9358f2de866019640871c4a3b17a453f8ca1441213c6e51b06c1f22eb27ee6d86dff4330b55358b65e47adbc2cd2c90c11b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/47217b68f6efaf4d5f7ce09c04972b12ccad5228b8634a3e9d6fef6056711713ac5d7c55d485628c0bd50babffa2025cc9d8eb0f59c4e39371f7d116fe58a771"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 04:43:31.314720) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:43:31.315180) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1462792966.265945-1034050341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004559 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:43:31.398992) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 04:43:31.399298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 04:43:31.399623) ========= ========= Total master_lag: 0.18 =========