builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1474406902.59 results: success (0) buildid: 20160920142044 builduid: 39f1a9a6c046479ebba1a28a465bce91 revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:22.588013) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:22.588489) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:22.588828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:22.611530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:22.611857) ========= 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=52faae308a556c440401f8e5000001e8-1474406902.611537-297648870 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 14:28:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-09-20 14:28:23 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.391548 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.017833) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.018207) ========= 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=52faae308a556c440401f8e5000001e8-1474406902.611537-297648870 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020997 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.067462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.067843) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --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=52faae308a556c440401f8e5000001e8-1474406902.611537-297648870 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 14:28:23,860 truncating revision to first 12 chars 2016-09-20 14:28:23,860 Setting DEBUG logging. 2016-09-20 14:28:23,860 attempt 1/10 2016-09-20 14:28:23,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6be9a5c9c8cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 14:28:24,211 unpacking tar archive at: mozilla-beta-6be9a5c9c8cd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.775722) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.776454) ========= script_repo_revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.777370) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.778140) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:23.789979) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 22 secs) (at 2016-09-20 14:28:23.790297) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1474406902.611537-297648870 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:28:24 INFO - MultiFileLogger online at 20160920 14:28:24 in /builds/slave/test 14:28:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:28:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:28:24 INFO - {'append_to_log': False, 14:28:24 INFO - 'base_work_dir': '/builds/slave/test', 14:28:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:28:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:28:24 INFO - 'branch': 'Mozilla-Beta', 14:28:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:28:24 INFO - 'config_files': ('talos/linux_config.py',), 14:28:24 INFO - 'default_actions': ('clobber', 14:28:24 INFO - 'read-buildbot-config', 14:28:24 INFO - 'download-and-extract', 14:28:24 INFO - 'populate-webroot', 14:28:24 INFO - 'create-virtualenv', 14:28:24 INFO - 'install', 14:28:24 INFO - 'run-tests'), 14:28:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:28:24 INFO - 'download_minidump_stackwalk': True, 14:28:24 INFO - 'download_symbols': 'ondemand', 14:28:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:28:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:28:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:28:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:28:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:28:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:28:24 INFO - 'installer_path': 'installer.exe', 14:28:24 INFO - 'log_level': 'info', 14:28:24 INFO - 'log_name': 'talos', 14:28:24 INFO - 'log_to_console': True, 14:28:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:28:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:28:24 INFO - 'opt_config_files': (), 14:28:24 INFO - 'pip_index': False, 14:28:24 INFO - 'sps_profile': False, 14:28:24 INFO - 'sps_profile_interval': 0, 14:28:24 INFO - 'suite': 'dromaeojs', 14:28:24 INFO - 'system_bits': '32', 14:28:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:28:24 INFO - 'title': 'talos-linux64-ix-056', 14:28:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:28:24 INFO - 'use_talos_json': True, 14:28:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:28:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:28:24 INFO - 'work_dir': 'build'} 14:28:24 INFO - [mozharness: 2016-09-20 21:28:24.678216Z] Running clobber step. 14:28:24 INFO - Running pre-action listener: _resource_record_pre_action 14:28:24 INFO - Running main action method: clobber 14:28:24 INFO - rmtree: /builds/slave/test/build 14:28:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:28:25 INFO - Running post-action listener: _resource_record_post_action 14:28:25 INFO - [mozharness: 2016-09-20 21:28:25.142504Z] Finished clobber step (success) 14:28:25 INFO - [mozharness: 2016-09-20 21:28:25.142605Z] Running read-buildbot-config step. 14:28:25 INFO - Running pre-action listener: _resource_record_pre_action 14:28:25 INFO - Running main action method: read_buildbot_config 14:28:25 INFO - Using buildbot properties: 14:28:25 INFO - { 14:28:25 INFO - "project": "", 14:28:25 INFO - "product": "firefox", 14:28:25 INFO - "script_repo_revision": "production", 14:28:25 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:28:25 INFO - "repository": "", 14:28:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:28:25 INFO - "buildid": "20160920142044", 14:28:25 INFO - "stage_platform": "linux64-pgo", 14:28:25 INFO - "basedir": "/builds/slave/test", 14:28:25 INFO - "buildnumber": 250, 14:28:25 INFO - "platform": "ubuntu64_hw", 14:28:25 INFO - "mozci_request": { 14:28:25 INFO - "priority": -1, 14:28:25 INFO - "type": "trigger_all_talos_jobs", 14:28:25 INFO - "times": 6 14:28:25 INFO - }, 14:28:25 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:28:25 INFO - "slavebuilddir": "test", 14:28:25 INFO - "branch": "mozilla-beta", 14:28:25 INFO - "revision": "6be9a5c9c8cd25d2082376495b788d85ce3cca98", 14:28:25 INFO - "slavename": "talos-linux64-ix-056", 14:28:25 INFO - "builduid": "39f1a9a6c046479ebba1a28a465bce91", 14:28:25 INFO - "repo_path": "releases/mozilla-beta" 14:28:25 INFO - } 14:28:25 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2. 14:28:25 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json. 14:28:25 INFO - Running post-action listener: _resource_record_post_action 14:28:25 INFO - [mozharness: 2016-09-20 21:28:25.145922Z] Finished read-buildbot-config step (success) 14:28:25 INFO - [mozharness: 2016-09-20 21:28:25.146010Z] Running download-and-extract step. 14:28:25 INFO - Running pre-action listener: _resource_record_pre_action 14:28:25 INFO - Running main action method: download_and_extract 14:28:25 INFO - mkdir: /builds/slave/test/build/tests 14:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:25 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:28:25 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:28:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:28:25 INFO - Downloaded 1327 bytes. 14:28:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:28:25 INFO - Using the following test package requirements: 14:28:25 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:28:25 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:28:25 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 14:28:25 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'jsshell-linux-x86_64.zip'], 14:28:25 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:28:25 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:28:25 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 14:28:25 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 14:28:25 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:28:25 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:25 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:28:25 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:25 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:25 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:28:25 INFO - Downloaded 21769913 bytes. 14:28:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:28:26 INFO - Return code: 0 14:28:26 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:28: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')]} 14:28:26 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:26 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:28:26 INFO - Downloaded 21769913 bytes. 14:28:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:28:28 INFO - Return code: 0 14:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:28 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:28:28 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:28:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:28:28 INFO - Downloaded 14413740 bytes. 14:28:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:28:28 INFO - Return code: 0 14:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:28 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:28:28 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:28:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:28:29 INFO - Downloaded 56771801 bytes. 14:28:29 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:28:29 INFO - mkdir: /builds/slave/test/properties 14:28:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:28:29 INFO - Writing to file /builds/slave/test/properties/build_url 14:28:29 INFO - Contents: 14:28:29 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:28:29 INFO - Running post-action listener: _resource_record_post_action 14:28:29 INFO - Running post-action listener: set_extra_try_arguments 14:28:29 INFO - [mozharness: 2016-09-20 21:28:29.698318Z] Finished download-and-extract step (success) 14:28:29 INFO - [mozharness: 2016-09-20 21:28:29.698647Z] Running populate-webroot step. 14:28:29 INFO - Running pre-action listener: _resource_record_pre_action 14:28:29 INFO - Running main action method: populate_webroot 14:28:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:28:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:28:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:28:29 INFO - '--tppagecycles', 14:28:29 INFO - '7'], 14:28:29 INFO - 'tests': ['tsvgm']}}, 14:28:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:28:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:28:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:28:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:28:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:28:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:28:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['damp', 'tps']}, 14:28:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'tests': ['damp', 'tps']}, 14:28:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['dromaeo_dom']}, 14:28:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:28:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['basic_compositor_video']}, 14:28:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:28:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['a11yr', 14:28:29 INFO - 'ts_paint', 14:28:29 INFO - 'tpaint', 14:28:29 INFO - 'sessionrestore', 14:28:29 INFO - 'sessionrestore_no_auto_restore', 14:28:29 INFO - 'tabpaint']}, 14:28:29 INFO - 'other-e10s': {'tests': ['a11yr', 14:28:29 INFO - 'ts_paint', 14:28:29 INFO - 'tpaint', 14:28:29 INFO - 'sessionrestore', 14:28:29 INFO - 'sessionrestore_no_auto_restore', 14:28:29 INFO - 'tabpaint']}, 14:28:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:28:29 INFO - 'ts_paint', 14:28:29 INFO - 'tpaint', 14:28:29 INFO - 'sessionrestore', 14:28:29 INFO - 'sessionrestore_no_auto_restore', 14:28:29 INFO - 'tabpaint']}, 14:28:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:28:29 INFO - 'ts_paint', 14:28:29 INFO - 'tpaint', 14:28:29 INFO - 'sessionrestore', 14:28:29 INFO - 'sessionrestore_no_auto_restore', 14:28:29 INFO - 'tabpaint']}, 14:28:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['a11yr', 14:28:29 INFO - 'ts_paint', 14:28:29 INFO - 'tpaint', 14:28:29 INFO - 'sessionrestore', 14:28:29 INFO - 'sessionrestore_no_auto_restore', 14:28:29 INFO - 'tabpaint']}, 14:28:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['a11yr', 14:28:29 INFO - 'ts_paint', 14:28:29 INFO - 'tpaint', 14:28:29 INFO - 'sessionrestore', 14:28:29 INFO - 'sessionrestore_no_auto_restore', 14:28:29 INFO - 'tabpaint']}, 14:28:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['tsvgx', 14:28:29 INFO - 'tsvgr_opacity', 14:28:29 INFO - 'tart', 14:28:29 INFO - 'tscrollx', 14:28:29 INFO - 'cart']}, 14:28:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:28:29 INFO - 'tsvgr_opacity', 14:28:29 INFO - 'tart', 14:28:29 INFO - 'tscrollx', 14:28:29 INFO - 'cart']}, 14:28:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'talos_options': ['--disable-e10s'], 14:28:29 INFO - 'tests': ['tp5o']}, 14:28:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'tests': ['tp5o']}, 14:28:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'talos_options': ['--disable-e10s', 14:28:29 INFO - '--xperf_path', 14:28:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:28:29 INFO - 'tests': ['tp5n']}, 14:28:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:29 INFO - 'talos_options': ['--xperf_path', 14:28:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:28:29 INFO - 'tests': ['tp5n']}}, 14:28:29 INFO - 'talos.zip': {'path': '', 14:28:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:28:29 INFO - Running post-action listener: _resource_record_post_action 14:28:29 INFO - [mozharness: 2016-09-20 21:28:29.717488Z] Finished populate-webroot step (success) 14:28:29 INFO - [mozharness: 2016-09-20 21:28:29.717580Z] Running create-virtualenv step. 14:28:29 INFO - Running pre-action listener: _resource_record_pre_action 14:28:29 INFO - Running main action method: create_virtualenv 14:28:29 INFO - Creating virtualenv /builds/slave/test/build/venv 14:28:29 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 14:28:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:28:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:28:29 INFO - Using real prefix '/usr' 14:28:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:28:30 INFO - Installing distribute.............................................................................................................................................................................................done. 14:28:32 INFO - Installing pip.................done. 14:28:32 INFO - Return code: 0 14:28:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:28:32 INFO - Using env: {'DISPLAY': ':0', 14:28:32 INFO - 'HOME': '/home/cltbld', 14:28:32 INFO - 'LANG': 'en_US.UTF-8', 14:28:32 INFO - 'LANGUAGE': 'en_US:en', 14:28:32 INFO - 'LOGNAME': 'cltbld', 14:28:32 INFO - 'MAIL': '/var/mail/cltbld', 14:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:32 INFO - 'MOZ_NO_REMOTE': '1', 14:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:32 INFO - 'NO_EM_RESTART': '1', 14:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:32 INFO - 'PWD': '/builds/slave/test', 14:28:32 INFO - 'SHELL': '/bin/bash', 14:28:32 INFO - 'SHLVL': '1', 14:28:32 INFO - 'TERM': 'linux', 14:28:32 INFO - 'TMOUT': '86400', 14:28:32 INFO - 'USER': 'cltbld', 14:28:32 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:32 INFO - '_': '/tools/buildbot/bin/python'} 14:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:32 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 14:28:32 INFO - Cleaning up... 14:28:32 INFO - Return code: 0 14:28:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:28:32 INFO - Using env: {'DISPLAY': ':0', 14:28:32 INFO - 'HOME': '/home/cltbld', 14:28:32 INFO - 'LANG': 'en_US.UTF-8', 14:28:32 INFO - 'LANGUAGE': 'en_US:en', 14:28:32 INFO - 'LOGNAME': 'cltbld', 14:28:32 INFO - 'MAIL': '/var/mail/cltbld', 14:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:32 INFO - 'MOZ_NO_REMOTE': '1', 14:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:32 INFO - 'NO_EM_RESTART': '1', 14:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:32 INFO - 'PWD': '/builds/slave/test', 14:28:32 INFO - 'SHELL': '/bin/bash', 14:28:32 INFO - 'SHLVL': '1', 14:28:32 INFO - 'TERM': 'linux', 14:28:32 INFO - 'TMOUT': '86400', 14:28:32 INFO - 'USER': 'cltbld', 14:28:32 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:32 INFO - '_': '/tools/buildbot/bin/python'} 14:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:32 INFO - Downloading/unpacking psutil>=3.1.1 14:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:28:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:28:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:28:34 INFO - Installing collected packages: psutil 14:28:34 INFO - Running setup.py install for psutil 14:28:34 INFO - building 'psutil._psutil_linux' extension 14:28:34 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 14:28:35 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 14:28:35 INFO - building 'psutil._psutil_posix' extension 14:28:35 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 14:28:35 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 14:28:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:28:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:28:35 INFO - Successfully installed psutil 14:28:35 INFO - Cleaning up... 14:28:35 INFO - Return code: 0 14:28:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:28:35 INFO - Using env: {'DISPLAY': ':0', 14:28:35 INFO - 'HOME': '/home/cltbld', 14:28:35 INFO - 'LANG': 'en_US.UTF-8', 14:28:35 INFO - 'LANGUAGE': 'en_US:en', 14:28:35 INFO - 'LOGNAME': 'cltbld', 14:28:35 INFO - 'MAIL': '/var/mail/cltbld', 14:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:35 INFO - 'MOZ_NO_REMOTE': '1', 14:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:35 INFO - 'NO_EM_RESTART': '1', 14:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:35 INFO - 'PWD': '/builds/slave/test', 14:28:35 INFO - 'SHELL': '/bin/bash', 14:28:35 INFO - 'SHLVL': '1', 14:28:35 INFO - 'TERM': 'linux', 14:28:35 INFO - 'TMOUT': '86400', 14:28:35 INFO - 'USER': 'cltbld', 14:28:35 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:35 INFO - '_': '/tools/buildbot/bin/python'} 14:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:28:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:28:37 INFO - Installing collected packages: mozsystemmonitor 14:28:37 INFO - Running setup.py install for mozsystemmonitor 14:28:37 INFO - Successfully installed mozsystemmonitor 14:28:37 INFO - Cleaning up... 14:28:37 INFO - Return code: 0 14:28:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:37 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:37 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==2.5.1'] in /builds/slave/test/build 14:28:37 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==2.5.1 14:28:37 INFO - Using env: {'DISPLAY': ':0', 14:28:37 INFO - 'HOME': '/home/cltbld', 14:28:37 INFO - 'LANG': 'en_US.UTF-8', 14:28:37 INFO - 'LANGUAGE': 'en_US:en', 14:28:37 INFO - 'LOGNAME': 'cltbld', 14:28:37 INFO - 'MAIL': '/var/mail/cltbld', 14:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:37 INFO - 'MOZ_NO_REMOTE': '1', 14:28:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:37 INFO - 'NO_EM_RESTART': '1', 14:28:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:37 INFO - 'PWD': '/builds/slave/test', 14:28:37 INFO - 'SHELL': '/bin/bash', 14:28:37 INFO - 'SHLVL': '1', 14:28:37 INFO - 'TERM': 'linux', 14:28:37 INFO - 'TMOUT': '86400', 14:28:37 INFO - 'USER': 'cltbld', 14:28:37 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:37 INFO - '_': '/tools/buildbot/bin/python'} 14:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:37 INFO - Downloading/unpacking jsonschema==2.5.1 14:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:28:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:39 INFO - Downloading functools32-3.2.3-2.tar.gz 14:28:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:28:40 INFO - warning: no files found matching '*.txt' 14:28:40 INFO - no previously-included directories found matching 'build' 14:28:40 INFO - no previously-included directories found matching 'dist' 14:28:40 INFO - no previously-included directories found matching '.git*' 14:28:40 INFO - Installing collected packages: jsonschema, functools32 14:28:40 INFO - Running setup.py install for functools32 14:28:40 INFO - warning: no files found matching '*.txt' 14:28:40 INFO - no previously-included directories found matching 'build' 14:28:40 INFO - no previously-included directories found matching 'dist' 14:28:40 INFO - no previously-included directories found matching '.git*' 14:28:40 INFO - Successfully installed jsonschema functools32 14:28:40 INFO - Cleaning up... 14:28:40 INFO - Return code: 0 14:28:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:28:40 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')]} 14:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:40 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')]} 14:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:40 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:40 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:28:40 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 functools32==3.2.3-2 14:28:40 INFO - Using env: {'DISPLAY': ':0', 14:28:40 INFO - 'HOME': '/home/cltbld', 14:28:40 INFO - 'LANG': 'en_US.UTF-8', 14:28:40 INFO - 'LANGUAGE': 'en_US:en', 14:28:40 INFO - 'LOGNAME': 'cltbld', 14:28:40 INFO - 'MAIL': '/var/mail/cltbld', 14:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:40 INFO - 'MOZ_NO_REMOTE': '1', 14:28:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:40 INFO - 'NO_EM_RESTART': '1', 14:28:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:40 INFO - 'PWD': '/builds/slave/test', 14:28:40 INFO - 'SHELL': '/bin/bash', 14:28:40 INFO - 'SHLVL': '1', 14:28:40 INFO - 'TERM': 'linux', 14:28:40 INFO - 'TMOUT': '86400', 14:28:40 INFO - 'USER': 'cltbld', 14:28:40 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:40 INFO - '_': '/tools/buildbot/bin/python'} 14:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:28:40 INFO - Cleaning up... 14:28:40 INFO - Return code: 0 14:28:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:28:40 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')]} 14:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:40 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')]} 14:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:40 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 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:40 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 14:28:40 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 14:28:40 INFO - Using env: {'DISPLAY': ':0', 14:28:40 INFO - 'HOME': '/home/cltbld', 14:28:40 INFO - 'LANG': 'en_US.UTF-8', 14:28:40 INFO - 'LANGUAGE': 'en_US:en', 14:28:40 INFO - 'LOGNAME': 'cltbld', 14:28:40 INFO - 'MAIL': '/var/mail/cltbld', 14:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:40 INFO - 'MOZ_NO_REMOTE': '1', 14:28:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:40 INFO - 'NO_EM_RESTART': '1', 14:28:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:40 INFO - 'PWD': '/builds/slave/test', 14:28:40 INFO - 'SHELL': '/bin/bash', 14:28:40 INFO - 'SHLVL': '1', 14:28:40 INFO - 'TERM': 'linux', 14:28:40 INFO - 'TMOUT': '86400', 14:28:40 INFO - 'USER': 'cltbld', 14:28:40 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:40 INFO - '_': '/tools/buildbot/bin/python'} 14:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:40 INFO - Downloading/unpacking blobuploader==1.2.4 14:28:40 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 14:28:40 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 14:28:40 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 14:28:40 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 14:28:40 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 14:28:40 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 14:28:42 INFO - Downloading blobuploader-1.2.4.tar.gz 14:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:28:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:28:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:43 INFO - Downloading docopt-0.6.1.tar.gz 14:28:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:28:43 INFO - Installing collected packages: blobuploader, requests, docopt 14:28:43 INFO - Running setup.py install for blobuploader 14:28:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:28:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:28:43 INFO - Running setup.py install for requests 14:28:43 INFO - Running setup.py install for docopt 14:28:43 INFO - Successfully installed blobuploader requests docopt 14:28:43 INFO - Cleaning up... 14:28:43 INFO - Return code: 0 14:28:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:43 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')]} 14:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:43 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')]} 14:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:43 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 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:43 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 14:28:43 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 14:28:43 INFO - Using env: {'DISPLAY': ':0', 14:28:43 INFO - 'HOME': '/home/cltbld', 14:28:43 INFO - 'LANG': 'en_US.UTF-8', 14:28:43 INFO - 'LANGUAGE': 'en_US:en', 14:28:43 INFO - 'LOGNAME': 'cltbld', 14:28:43 INFO - 'MAIL': '/var/mail/cltbld', 14:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:43 INFO - 'MOZ_NO_REMOTE': '1', 14:28:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:43 INFO - 'NO_EM_RESTART': '1', 14:28:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:43 INFO - 'PWD': '/builds/slave/test', 14:28:43 INFO - 'SHELL': '/bin/bash', 14:28:43 INFO - 'SHLVL': '1', 14:28:43 INFO - 'TERM': 'linux', 14:28:43 INFO - 'TMOUT': '86400', 14:28:43 INFO - 'USER': 'cltbld', 14:28:43 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:43 INFO - '_': '/tools/buildbot/bin/python'} 14:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:44 INFO - Running setup.py (path:/tmp/pip-X7sggq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:44 INFO - Running setup.py (path:/tmp/pip-TpAUt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:44 INFO - Running setup.py (path:/tmp/pip-WNp7Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:44 INFO - Running setup.py (path:/tmp/pip-HBLk6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:44 INFO - Running setup.py (path:/tmp/pip-b09XtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:44 INFO - Running setup.py (path:/tmp/pip-aOAnLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:44 INFO - Running setup.py (path:/tmp/pip-jJNtIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:44 INFO - Running setup.py (path:/tmp/pip-S3KI8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:44 INFO - Running setup.py (path:/tmp/pip-S9rQSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:44 INFO - Running setup.py (path:/tmp/pip-dzWOwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:44 INFO - Running setup.py (path:/tmp/pip-uw9veY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:44 INFO - Running setup.py (path:/tmp/pip-sNWtKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:45 INFO - Running setup.py (path:/tmp/pip-zHd_1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:45 INFO - Running setup.py (path:/tmp/pip-aaML7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:45 INFO - Running setup.py (path:/tmp/pip-urcglf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:45 INFO - Running setup.py (path:/tmp/pip-88Rll3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:45 INFO - Running setup.py (path:/tmp/pip-fHEhIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:28:45 INFO - Running setup.py install for manifestparser 14:28:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:28:45 INFO - Running setup.py install for mozcrash 14:28:45 INFO - Running setup.py install for mozdebug 14:28:45 INFO - Running setup.py install for mozdevice 14:28:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:28:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:28:45 INFO - Running setup.py install for mozfile 14:28:45 INFO - Running setup.py install for mozhttpd 14:28:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for mozinfo 14:28:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for mozInstall 14:28:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for mozleak 14:28:46 INFO - Running setup.py install for mozlog 14:28:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for moznetwork 14:28:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for mozprocess 14:28:46 INFO - Running setup.py install for mozprofile 14:28:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for mozrunner 14:28:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:28:46 INFO - Running setup.py install for mozscreenshot 14:28:46 INFO - Running setup.py install for moztest 14:28:47 INFO - Running setup.py install for mozversion 14:28:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:28:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:28:47 INFO - Cleaning up... 14:28:47 INFO - Return code: 0 14:28:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:28:47 INFO - Using env: {'DISPLAY': ':0', 14:28:47 INFO - 'HOME': '/home/cltbld', 14:28:47 INFO - 'LANG': 'en_US.UTF-8', 14:28:47 INFO - 'LANGUAGE': 'en_US:en', 14:28:47 INFO - 'LOGNAME': 'cltbld', 14:28:47 INFO - 'MAIL': '/var/mail/cltbld', 14:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:47 INFO - 'MOZ_NO_REMOTE': '1', 14:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:47 INFO - 'NO_EM_RESTART': '1', 14:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:47 INFO - 'PWD': '/builds/slave/test', 14:28:47 INFO - 'SHELL': '/bin/bash', 14:28:47 INFO - 'SHLVL': '1', 14:28:47 INFO - 'TERM': 'linux', 14:28:47 INFO - 'TMOUT': '86400', 14:28:47 INFO - 'USER': 'cltbld', 14:28:47 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:47 INFO - '_': '/tools/buildbot/bin/python'} 14:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:47 INFO - Running setup.py (path:/tmp/pip-w2FE77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:47 INFO - Running setup.py (path:/tmp/pip-16fXa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:47 INFO - Running setup.py (path:/tmp/pip-hM_1_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:47 INFO - Running setup.py (path:/tmp/pip-Ue6q4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:47 INFO - Running setup.py (path:/tmp/pip-7yaSvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:47 INFO - Running setup.py (path:/tmp/pip-pFEr_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:47 INFO - Running setup.py (path:/tmp/pip-qY0zua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:47 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)) 14:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:47 INFO - Running setup.py (path:/tmp/pip-6ba22K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:48 INFO - Running setup.py (path:/tmp/pip-OdIZoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:48 INFO - Running setup.py (path:/tmp/pip-T2KZVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:48 INFO - Running setup.py (path:/tmp/pip-65WAT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:48 INFO - Running setup.py (path:/tmp/pip-FsD2or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:48 INFO - Running setup.py (path:/tmp/pip-YeUr9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:48 INFO - Running setup.py (path:/tmp/pip-2m2xEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:48 INFO - Running setup.py (path:/tmp/pip-UxlGki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:48 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)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:48 INFO - Running setup.py (path:/tmp/pip-4BLOEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:48 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)) 14:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:48 INFO - Running setup.py (path:/tmp/pip-3fDYbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:48 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)) 14:28:48 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)) 14:28:48 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)) 14:28:48 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)) 14:28:48 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)) 14:28:48 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)) 14:28:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:28:48 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 14:28:48 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 14:28:48 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 14:28:48 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 14:28:48 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 14:28:48 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 14:28:50 INFO - Downloading blessings-1.6.tar.gz 14:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:28:50 INFO - Installing collected packages: blessings 14:28:50 INFO - Running setup.py install for blessings 14:28:50 INFO - Successfully installed blessings 14:28:50 INFO - Cleaning up... 14:28:50 INFO - Return code: 0 14:28:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:51 INFO - Reading from file tmpfile_stdout 14:28:51 INFO - Current package versions: 14:28:51 INFO - argparse == 1.2.1 14:28:51 INFO - blessings == 1.6 14:28:51 INFO - blobuploader == 1.2.4 14:28:51 INFO - docopt == 0.6.1 14:28:51 INFO - functools32 == 3.2.3-2 14:28:51 INFO - jsonschema == 2.5.1 14:28:51 INFO - manifestparser == 1.1 14:28:51 INFO - mozInstall == 1.12 14:28:51 INFO - mozcrash == 0.17 14:28:51 INFO - mozdebug == 0.1 14:28:51 INFO - mozdevice == 0.48 14:28:51 INFO - mozfile == 1.2 14:28:51 INFO - mozhttpd == 0.7 14:28:51 INFO - mozinfo == 0.9 14:28:51 INFO - mozleak == 0.1 14:28:51 INFO - mozlog == 3.3 14:28:51 INFO - moznetwork == 0.27 14:28:51 INFO - mozprocess == 0.23 14:28:51 INFO - mozprofile == 0.28 14:28:51 INFO - mozrunner == 6.12 14:28:51 INFO - mozscreenshot == 0.1 14:28:51 INFO - mozsystemmonitor == 0.3 14:28:51 INFO - moztest == 0.7 14:28:51 INFO - mozversion == 1.4 14:28:51 INFO - psutil == 3.1.1 14:28:51 INFO - requests == 1.2.3 14:28:51 INFO - wsgiref == 0.1.2 14:28:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:28:51 INFO - Using env: {'DISPLAY': ':0', 14:28:51 INFO - 'HOME': '/home/cltbld', 14:28:51 INFO - 'LANG': 'en_US.UTF-8', 14:28:51 INFO - 'LANGUAGE': 'en_US:en', 14:28:51 INFO - 'LOGNAME': 'cltbld', 14:28:51 INFO - 'MAIL': '/var/mail/cltbld', 14:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:51 INFO - 'MOZ_NO_REMOTE': '1', 14:28:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:51 INFO - 'NO_EM_RESTART': '1', 14:28:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:51 INFO - 'PWD': '/builds/slave/test', 14:28:51 INFO - 'SHELL': '/bin/bash', 14:28:51 INFO - 'SHLVL': '1', 14:28:51 INFO - 'TERM': 'linux', 14:28:51 INFO - 'TMOUT': '86400', 14:28:51 INFO - 'USER': 'cltbld', 14:28:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:51 INFO - '_': '/tools/buildbot/bin/python'} 14:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:28:51 INFO - Cleaning up... 14:28:51 INFO - Return code: 0 14:28:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c39030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, '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': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', '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 14:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:28:51 INFO - Using env: {'DISPLAY': ':0', 14:28:51 INFO - 'HOME': '/home/cltbld', 14:28:51 INFO - 'LANG': 'en_US.UTF-8', 14:28:51 INFO - 'LANGUAGE': 'en_US:en', 14:28:51 INFO - 'LOGNAME': 'cltbld', 14:28:51 INFO - 'MAIL': '/var/mail/cltbld', 14:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:51 INFO - 'MOZ_NO_REMOTE': '1', 14:28:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:51 INFO - 'NO_EM_RESTART': '1', 14:28:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:51 INFO - 'PWD': '/builds/slave/test', 14:28:51 INFO - 'SHELL': '/bin/bash', 14:28:51 INFO - 'SHLVL': '1', 14:28:51 INFO - 'TERM': 'linux', 14:28:51 INFO - 'TMOUT': '86400', 14:28:51 INFO - 'USER': 'cltbld', 14:28:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:51 INFO - '_': '/tools/buildbot/bin/python'} 14:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:28:51 INFO - Cleaning up... 14:28:51 INFO - Return code: 0 14:28:51 INFO - Running post-action listener: _resource_record_post_action 14:28:51 INFO - Running post-action listener: _start_resource_monitoring 14:28:51 INFO - Starting resource monitoring. 14:28:51 INFO - [mozharness: 2016-09-20 21:28:51.511329Z] Finished create-virtualenv step (success) 14:28:51 INFO - [mozharness: 2016-09-20 21:28:51.511765Z] Running install step. 14:28:51 INFO - Running pre-action listener: _resource_record_pre_action 14:28:51 INFO - Running main action method: install 14:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:51 INFO - Reading from file tmpfile_stdout 14:28:51 INFO - Detecting whether we're running mozinstall >=1.0... 14:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:28:51 INFO - Reading from file tmpfile_stdout 14:28:51 INFO - Output received: 14:28:51 INFO - Usage: mozinstall [options] installer 14:28:51 INFO - Options: 14:28:51 INFO - -h, --help show this help message and exit 14:28:51 INFO - -d DEST, --destination=DEST 14:28:51 INFO - Directory to install application into. [default: 14:28:51 INFO - "/builds/slave/test"] 14:28:51 INFO - --app=APP Application being installed. [default: firefox] 14:28:51 INFO - mkdir: /builds/slave/test/build/application 14:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:29:05 INFO - Reading from file tmpfile_stdout 14:29:05 INFO - Output received: 14:29:05 INFO - /builds/slave/test/build/application/firefox/firefox 14:29:05 INFO - Running post-action listener: _resource_record_post_action 14:29:05 INFO - [mozharness: 2016-09-20 21:29:05.375884Z] Finished install step (success) 14:29:05 INFO - [mozharness: 2016-09-20 21:29:05.376180Z] Running run-tests step. 14:29:05 INFO - Running pre-action listener: _resource_record_pre_action 14:29:05 INFO - Running main action method: run_tests 14:29:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:29:05 INFO - Python 2.7.3 14:29:05 INFO - Return code: 0 14:29:05 INFO - grabbing minidump binary from tooltool 14:29:05 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')]} 14:29:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef83b0>, '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 0x1ef92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:29:05 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 14:29:05 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 14:29:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:29:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:29:05 INFO - Return code: 0 14:29:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:29:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:29:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:29:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:29:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:29:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:29:05 INFO - Using env: {'DISPLAY': ':0', 14:29:05 INFO - 'HOME': '/home/cltbld', 14:29:05 INFO - 'LANG': 'en_US.UTF-8', 14:29:05 INFO - 'LANGUAGE': 'en_US:en', 14:29:05 INFO - 'LOGNAME': 'cltbld', 14:29:05 INFO - 'MAIL': '/var/mail/cltbld', 14:29:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:29:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:05 INFO - 'MOZ_NO_REMOTE': '1', 14:29:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:29:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:05 INFO - 'NO_EM_RESTART': '1', 14:29:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:05 INFO - 'PWD': '/builds/slave/test', 14:29:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:29:05 INFO - 'SHELL': '/bin/bash', 14:29:05 INFO - 'SHLVL': '1', 14:29:05 INFO - 'TERM': 'linux', 14:29:05 INFO - 'TMOUT': '86400', 14:29:05 INFO - 'USER': 'cltbld', 14:29:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1474406902.611537-297648870', 14:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:05 INFO - '_': '/tools/buildbot/bin/python'} 14:29:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:29:05 INFO - mozversion application_buildid: 20160920092516 14:29:05 INFO - mozversion application_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:29:05 INFO - mozversion application_display_name: Firefox 14:29:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:29:05 INFO - mozversion application_name: Firefox 14:29:05 INFO - mozversion application_remotingname: firefox 14:29:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:29:05 INFO - mozversion application_vendor: Mozilla 14:29:05 INFO - mozversion application_version: 50.0 14:29:05 INFO - mozversion platform_buildid: 20160920092516 14:29:05 INFO - mozversion platform_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:29:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:29:05 INFO - mozversion platform_version: 50.0 14:29:05 INFO - using testdate: 1474406945 14:29:05 INFO - actual date: 1474406945 14:29:05 INFO - starting webserver on 'localhost:34463' 14:29:05 INFO - SUITE-START | Running 2 tests 14:29:05 INFO - TEST-START | dromaeo_css 14:29:05 INFO - operating with platform_type : linux_ 14:29:05 INFO - Initialising browser for dromaeo_css test... 14:29:05 INFO - TEST-INFO | started process 21208 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5aUhFo/profile http://localhost:34463/getInfo.html) 14:29:09 INFO - PROCESS | 21208 | __metrics Screen width/height:1600/1200 14:29:09 INFO - PROCESS | 21208 | colorDepth:24 14:29:09 INFO - PROCESS | 21208 | Browser inner width/height: 1024/697 14:29:09 INFO - PROCESS | 21208 | __metrics 14:29:10 INFO - PROCESS | 21208 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:29:10 INFO - PROCESS | 21208 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:29:11 INFO - PROCESS | 21208 | console.error: PushService: 14:29:11 INFO - PROCESS | 21208 | stateChangeProcessEnqueue: Error transitioning state 14:29:11 INFO - PROCESS | 21208 | UnknownError 14:29:11 INFO - TEST-INFO | 21208: exit 0 14:29:11 INFO - Browser initialized. 14:29:11 INFO - Running cycle 1/1 for dromaeo_css test... 14:29:11 INFO - TEST-INFO | started process 21294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5aUhFo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:23 INFO - PROCESS | 21294 | RSS: Main: 143753216 14:29:23 INFO - PROCESS | 21294 | 14:30:14 INFO - PROCESS | 21294 | 1474407014377 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] 14:32:23 INFO - PROCESS | 21294 | Cycle 1(1): loaded http://localhost:34463/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34463/tests/dromaeo/cssquery-ext.html) 14:32:24 INFO - PROCESS | 21294 | RSS: Main: 219209728 14:32:24 INFO - PROCESS | 21294 | 14:35:26 INFO - PROCESS | 21294 | Cycle 1(1): loaded http://localhost:34463/tests/dromaeo/cssquery-ext.html (next: http://localhost:34463/tests/dromaeo/cssquery-jquery.html) 14:35:27 INFO - PROCESS | 21294 | RSS: Main: 225718272 14:35:27 INFO - PROCESS | 21294 | 14:38:29 INFO - PROCESS | 21294 | Cycle 1(1): loaded http://localhost:34463/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34463/tests/dromaeo/cssquery-mootools.html) 14:38:29 INFO - PROCESS | 21294 | RSS: Main: 175190016 14:38:29 INFO - PROCESS | 21294 | 14:41:31 INFO - PROCESS | 21294 | Cycle 1(1): loaded http://localhost:34463/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34463/tests/dromaeo/cssquery-prototype.html) 14:41:32 INFO - PROCESS | 21294 | RSS: Main: 201875456 14:41:32 INFO - PROCESS | 21294 | 14:44:34 INFO - PROCESS | 21294 | Cycle 1(1): loaded http://localhost:34463/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34463/tests/dromaeo/cssquery-yui.html) 14:44:35 INFO - PROCESS | 21294 | RSS: Main: 178036736 14:44:35 INFO - PROCESS | 21294 | 14:47:37 INFO - PROCESS | 21294 | Cycle 1(1): loaded http://localhost:34463/tests/dromaeo/cssquery-yui.html (next: http://localhost:34463/tests/dromaeo/cssquery-dojo.html) 14:47:38 INFO - PROCESS | 21294 | RSS: Main: 261234688 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | __start_tp_report 14:47:38 INFO - PROCESS | 21294 | _x_x_mozilla_page_load 14:47:38 INFO - PROCESS | 21294 | _x_x_mozilla_page_load_details 14:47:38 INFO - PROCESS | 21294 | |i|pagename|runs| 14:47:38 INFO - PROCESS | 21294 | |0;dojo.html;3181.7136457718802;3203.91990200245;3209.630892447368;3236.3850868335;3260.4131256373844;5628.43715628437;5729.656220626765;5747.080467125261;5753.510951569122;5779.797707080253;5697.4302569743;5739.913901291484;5777.855553611162;5781.364049954502;5787.189793428913;4988.376452943382;5067.34124563808;5204.349456317969;5211.192265198882;5261.184516399964;5114.23286507025;5181.378234611851;5184.611154163434;5199.220116982445;5202.687838729688;5127.48725127488;5144.048351055067;5166.405863325709;5203.661761985459;5219.817306394277;5251.973740131294;5283.841484755463;5285.154375299933;5294.973525132388;5324.148136298174;4962.578180854632;5036.622253331015;5057.848264552069;5142.665726727751;5143.279940808253;5497.725113744336;5523.861903452445;5535.557155427556;5543.916841247385;5563.109902415594;6048.941435248814;6049.092636104576;6053.092036194562;6061.878762424771;6089.969550152266;5949.613275137102;5995.430434108753;6047.183630209909;6059.030555111161;6130.846728831771;5686.630369026;5687.630304030307;5693.459121383504;5701.600887937846;5712.6286791358425;6083.969580152159;6161.322254551996;6164.6917654117115;6167.136600875881;6167.383261673886;5666.603337766399;5830.125481177857;5875.118732190205;5882.02946513829;5970.731317090741;5988.371221021817;5997.580169388189;6001.5798894077;6008.03871380577;6011.579189456696;5969.462748352669;5972.790952316648;5978.04351303798;5980.55145864062;5982.880342393215;5718.828435146869;5936.881262374728;5956.702164891738;6012.30858451275;6101.328853826164;4597.862064138081;4629.051044535862;4630.027694184259;4639.396878405786;4655.930161047587;4097.8770636880945;4139.710220284612;4148.315527937917;4163.958360416357;4173.728707633994;5511.283533140746;5645.096784514458;5706.201131841545;5710.600257981984;5748.338941021836;6092.147099405998;6094.908576371358;6095.085737139376;6097.207363042776;6097.298810636745;5718.971405142947;5729.427057294237;5732.369439361667;5733.942660573341;5741.368449470555;5076.517730815566;5215.947840521622;5238.083335416363;5244.423113457517;5332.040232758063;6296.024116261987;6314.842128946813;6326.240851097898;6326.304106548276;6335.841603959938;6086.069144037704;6123.475981923453;6127.571070025056;6137.41694539018;6146.354632763674;6069.544784141119;6232.594881332699;6250.718717657807;6261.530385221217;6359.872802544016;6033.78881739137;6053.788117415869;6053.969730151321;6056.333803281724;6094.421030002142;6170.7223174958135;6179.1040299155475;6186.907196392148;6188.876222475616;6190.783322583688;5753.798617048383;5786.07422812348;5797.797077102281;5819.15622234782;5917.260342457193;5779;5835.891180675658;5854.707264636836;5857.00430926752;5860.326062502867;5765.4811067004175;5767.307923049261;5768.653880767168;5822.806324703511;5826.941730582809;5744.3394009687745;5856.59003869725;5892.351841297368;5895.498882595028;5936.436038576328;5798.391168927202;5804.984127777538;5810.5061069809535;5813.651180929158;5849.064149736022 14:47:38 INFO - PROCESS | 21294 | |1;ext.html;597.7310210405319;645.9870802583948;660.3495556876477;666.8232918276655;672.2302963107239;35263.82368088159;37938.051548711264;37946.8102659487;37960.43059354112;38097.42853857191;38002.8099859501;38501.61498385015;47753.76123119379;48828;48918.26622600655;9162.496062716531;9299.116583924517;9299.30255230857;9300.441973481613;9304.488253146073;9123.771905702344;9294.209992150676;9306.62773489063;9314.208292295163;9321.766955826091;8733.475991440533;11603.767924641503;11822.822657660143;11840.230385024988;11844.230125041888;11346.149038822081;11406.657800265923;11429.828552571676;11433.59982400612;11435.828462573068;11108.722281943015;11113.83329250062;11189.216754827165;11191.384473853936;11207.21549491536;44499.33251001238;44729.10541789146;44772.328415073796;44861.10277794426;44882.77558612219;3783.9432408513894;3804.239152169577;3824.158685089276;3895;3914.178022615226;34670.306593868234;34676.653233467594;34680.13299667528;34709;34801;4532.750698711569;4603.746793926332;4615.515370886076;4642.234031384817;4683.929741053887;348784;348871.0451581934;351485;352761.99999999744;353093;60729.39270607326;61072.38927610756;61145;61414;61784;39461.80269098636;40056.79971600181;40268;42163;64996.67501662461;14982;15127.773083403757;15132.546023619308;15133.848661513244;15145.242737863284;11172.385518796382;11238.438078096062;11242.55029798799;11244.887551124548;11254.099672026217;18341.81658183428;19800.019998000083;20124.39626811198;20303.8984805075;20572.279970200972;7152.4278057755255;7542.358899493604;7571.772846814604;7606.80982975419;7742;1012.4431562640666;1080.6109800471825;1083.4041277297456;1135.2620796482188;1166.0904494494307;34607;34973.47539786905;34998.47502287468;35197.82401087978;35233.47149792755;1100.944952752359;1152.0610702277618;1159.2117360195148;1206.7586482703493;1228.5700004998357;16333.42833000863;17199.91400043017;17310.394136205174;17423.47729568115;17578.91210543939;647129;647489;647755;649347;650559;60716.39283607196;61138.69430652818;61230.38769612336;61292.69353653203;61308;1325.4896864707157;1394.9511767088104;1428.4429072661671;1442.3004842651305;1446.8914831387688;32383.838080809444;34212.48681269733;34710.826445867606;34739.478907816905;35027.82486087553;52098.218526722885;52687.736561316946;52795;52844.73577632087;52923.20615190698;455486;455608;455957;463208;530476;29803.701962980093;29866.253343666584;29961.250968725955;31233.06300810979;31548.369032619677;49604;49976;50044.49955500398;50073;50402.243966341266;41904.79047604743;42034.579654203066;42110.36834447424;42267.7886610565;43617.127657446035;41874.79062604668;42350;43157.56842431661;43774.12451750883;44670.55329446664;42192.578074218865;43142;43583.78208109066;43931;44307.33538996982;36093.819530902176;38245.42631860469;38525.80737096297;38929;39003.21993560169;28937.276568085967;29188.27029324284;29274.268143296587;29274.41451170922;29393.85303073471 14:47:38 INFO - PROCESS | 21294 | |2;jquery.html;410.25948163564755;410.4438485851669;418.5082528029565;423.67165446778745;440.35268155810945;19311;20214.191432342726;20621.484462888435;20832.791672083276;21114.788852111473;16655.25051372688;18612.255509779592;20227.494312642193;20745.481362965897;20755.96220188992;5755.338136114358;6004.129401236818;6037.607555508878;6056;6062.424069713392;4320.524742278347;4549.499555048942;5938.109283607472;6021.036634138539;6662.067310576524;6669.032990216416;6670.499712521556;6677.766278180265;6683.598984060972;6685.565438246523;6488.545801793876;6663.300353462865;6669;6704.765333213339;6705;5933.851653708649;5939.4357536034;5951.523878089785;5960.582759206857;5960.821175364746;24260.39349016271;24375.87812060946;24451;24592.754072459404;24910;2589.37854914821;2642.5111354399305;2747.6565429321336;2871.7128287171327;3078;15267.923660381626;15327.846721532753;15661.29524171415;15706.712461733765;15987;2049.5081180516568;2049.610573990937;2051.393857710566;2089.2165437960766;2129.7976692214215;61710;71788;80333;84810.57594712048;86301.13698862994;11022.283551569122;11131.721706957229;11307.49116289769;11361.602343917923;11416;15848.44530441429;15876.603084922739;15906.681866362607;15907.204639767966;15931.761023584424;7399.741009064657;7849.3328067114935;7879.606019698992;7897.3287270581495;7968.362530997622;7386.224446433154;7431.962840185764;7598.354139898171;7602.46782725204;7608.087029556488;6838.829029274308;6903.930960690429;6945.0971373721095;6989.5107342485535;7013.964930175419;2009.6033256886617;2015.1737787507052;2021.6866385710239;2022.150696707357;2082.6875968604527;911.3666002128457;975.1077763846143;1001.5793366785967;1035.6427032673716;1159.2349049627205;20728.37814865526;21269.36191914245;21527.354179374644;21560.13759449636;21594.352169434944;994.6469003503797;1030.948452577383;1039.1271332081087;1054.0408228512017;1066.1364294921138;11093.389863557582;11968.10239232061;12043.518259269706;12085.214461060003;12105.152639315163;516088;524542;535064;544670;551680;82172.58913705393;84758.57620711857;85754.57122714471;86599;86903.56548217345;940.8823897012874;1000.5347513406156;1057.836035414512;1096.2107282756403;1125.994370028161;17803.554911127325;19040.904795475933;19082.236710531422;19168.329108481135;19255.77603690292;41510;42447.57552424436;42937.35593966155;43585.12829743324;43993.560064398946;54488;54870.72564637151;55504.44495554993;56553.43446565482;58047.12929306148;8782.692605758768;8927.866082008644;9141.26869850397;9224.631014759603;9359.438433694;9257.444553326823;9739.561719722771;13551.186928784304;13733.176009439465;14197.503087391891;11956.940215298868;12235.26588404663;12408.379581021094;12426.192297500995;12450.81323780126;11981.400929953641;12129.757404851676;12146.514139434325;12341.444634991267;12477.5008999639;12254.01744433045;12540.498380064695;12616.432260548114;12629.684257893627;12637.74724505523;8675.86986195195;8966.910330896608;9136.588853501742;9151.313651476034;9230.076992300985;11143.665690029313;11894.643160705193;12084.093692972887;12139.939300303442;12245.632631021084 14:47:38 INFO - PROCESS | 21294 | |3;mootools.html;1546.3891762753708;1572.7090488259673;1663.8502534771876;1772.4151030160056;1798.5054110119706;8905.599248033837;9634.807303853928;9636;9647.710568682933;9649.179819715315;7939.206079392058;8940.73177804667;9252.491112988782;9260.907390926088;9375.76560585984;2431.064040344467;2480.2435257246534;2482.6772519572432;2489.26566662834;2491.813114016447;2386.200622791367;2523.3313172009434;2524.886380112899;2527.8736063196766;2528.9241322760345;2506.410993416546;2520.4707011527603;2584.883680234394;2589.2102908612865;2591.883365248568;2484.4906794107164;2487.45276039272;2493.8652912924576;2528.329992551975;2546.4652422991194;2456.914008009711;2460.2865169100937;2496.500699860017;2504.4114633061217;2505.8747062646794;9570.56932438042;9680.419174849532;9691.176250018756;9702.660406885725;9720.07659272368;5495.945040549624;5628.437156284339;5642.802501912413;5651.039323315046;5653.745581448845;3567.678908898212;3665.138692407282;3727.7576957497677;3747.925041499182;3754.3617585010616;1195.8983486403665;1276.5978716704208;1291.6577107066635;1300.8504022037491;1303.9282839443827;13083.934580327134;13503.12229705066;13586.592402227949;13590.728185436235;13616.863831361758;4831.661783675109;4862;4895.167821470359;5037.420696619863;5040.991801639687;10621.309614875163;10621.946890265499;10631.52158152885;10636.30863993838;10644.201684873666;5486.533644640251;5488.25908502355;5489.780408783684;5492.69790161537;5495.477929596682;4518.43519560055;5084.872878178001;5142.51146141116;5150.124478838607;5198.454162312978;4076.449679293317;4083.5508094109623;4152.875413737593;4198.874033778991;4259.148170365939;1235.6910772306924;1239.9318037507844;1242;1254.5609036837216;1257.5221415861913;908.5502676175204;927.3137877970254;931.1479995803763;966.2028826218398;1043.1550444140119;6100.755969761249;6195.349488303754;6252.093446450239;6409.487241020707;6553.5084868634085;458.9288660257665;463.3119817071646;468.6508551129362;469.63368572513497;474.90976714424147;4147.854825081107;4357.302831546937;4372.715773474714;4398.560143985575;4406.669499787528;11526.827097593543;11938.88061119395;12001.099917506222;12022.398880055962;12072.215306005257;11957.342346171044;11997.560292764749;12015.339156346485;12028.45871935765;12031.819522707166;8522.678984757373;8839.381243313035;8844.955775221082;8896.555172241491;9043.909560904438;5987.401259874152;6102.786402476069;6247.750089996349;6287.779927702509;6317.5893566918;11164.832527511931;11276.32342059479;11372.601958931398;11498.655040348804;11542.653720388402;11219.270747401393;11268.661940141808;11516.884831151916;11568.884311157117;11627.47676354583;8276.710315138942;8443.155684431507;8500.957495212731;8506.106858779738;8545.444546104483;8757.956210219163;8895.154960278764;8942.821143577223;8965.910340896768;9005.459672419676;7977.082635496992;8045.798855028437;8111.269985701315;8184.672613095409;8233.300169485661;7893.723719669784;7952.880706789519;7980.201979801974;8116.147804480444;8407.663693452194;7444.143923448656;7468.290512401313;7544.4341674373545;7592.734254301073;7613.847723045397;5205.089109405763;5255.816046438356;5279.709615971158;5341.599380046589;5372.409035006224;5497;5599.689789912103;5638.464345887134;5667.85830354228;5681.119426488901 14:47:38 INFO - PROCESS | 21294 | |4;prototype.html;341.1607445683618;349.49497975425504;349.64161734222404;353.9752217344785;355.39050528343887;5378.870437208181;5457.690154362954;5542.722863856806;5578.972105139474;5580.246666699994;5482.972585137069;5504.697241651707;5512.228288039668;5515.393306736256;5523;4369.388285640013;4373.103513779683;4375.518692943773;4380.605745482906;4389.23188442023;4145.606167414091;4301.763403012833;4366.541513141106;4371.8688439346715;4394.736315821045;4255.616994470497;4271.6155546000855;4272.607339889656;4276.572342765714;4289.485261768592;4279.529251782302;4327.117703798857;4331.155424692186;4340.370646256275;4347.956520434786;3728.459373390843;3728.5152930119184;3842.4620553122586;3849.5573009104032;3855.9807200964096;5324.387695415038;5337.879045400432;5358.946410535884;5405.729713514348;5425.728713564306;4885.340479035321;4936.407631084293;4956.430010548797;4959.429665588467;4960.851174464772;5427.83716488506;5729.627574207705;5762.74067666956;5763.942360576382;5778.682172480512;4191.287481128185;4206.242876282268;4207.64235040022;4209.137126888981;4212.726172798758;5992.760289588411;5999.820005399845;6005.1892994445625;6007.399260074001;6009.849753756191;4223.13425747721;4225.936610950838;4263.4031235627035;4323.092150648336;4328.891777705582;4308.009157893702;4316.374125751749;4330.891727706769;4333.956660433419;4348.543402942709;4842.346283251716;4906.533879281463;4918.5819205367225;4920.827771028068;4934.5805606523845;5679.659220446786;5778.27771528559;5806.21616081832;5810.240135935397;5818.8254352369495;1473.7126260379123;1489.7988771515707;1490.6571488557693;1494.1184701026446;1494.7384207763816;929.702495201529;946.990530094704;959.4387283439266;973.4986481961795;977.941323520591;4890.361728820813;4919.434265059493;4943.901121977612;5001.7499125043605;5031.9744204636145;5176.886969301619;5182.170852663556;5210.296609957683;5217.373915130132;5252.9737351313;4699.812007519729;4935.457099719028;4967.677100988424;4987.800487980513;4990.401151861727;2772.625695531118;2778.722127787205;2790.9860450697615;2819.3656427304104;2826.4064546445475;5363.417791751443;5365.302510673588;5384.623076384693;5417.805788655601;5448.700321482356;5125.769340379692;5133.589312854962;5292.232626269247;5294.788208471618;5324.547413469821;5320.813771518056;5371.57027437804;5396.4333744957;5398.703071331036;5406.756695948692;5227.4249832517635;5285.550728187994;5292.533390629144;5298.920516192337;5311.814086506954;4995.8751031223055;5097.706032285412;5179.860430705239;5204.583633309402;5239.842804715865;5333.43998880112;5342.706151161723;5370.892582148257;5406.756695948613;5413.702246376487;4057.5130984282073;4156.501219853637;4157.501099868035;4199.370094485852;4201.096764195712;5834.533237341087;5865.853353666193;5866.354700982974;5903.439173278532;5918;5594.8810237953385;5722.656640601577;5809.535237180931;5813.79651712188;5813.854653633693;5737.225474560881;5739.942600573941;5755.971220143872;5757.395473475225;5757.683327417032;5689.118186681071;5705.971470142623;5719.942800571941;5724.771009159587;5734.426557344233;5535.72678284005;5721.656700597978;5760.222369979999;5774;5774.007967251292;5607.906458240604;5611.3266408031295;5616.9047035827625;5634.126710359901;5639.4642508963225 14:47:38 INFO - PROCESS | 21294 | |5;yui.html;799.1568894815969;808.7371604228623;816.3836303590788;851.987220191697;905.4431524612365;15685.607859803511;17477;17607.29570817169;17611.55971100723;17706.468805935827;15672.608184795386;15868.206589670528;16149.677006459893;16182.19089045546;16189.35242590298;862.1809281182885;889.3596610440505;890.8574628059512;890.9153630405101;891.3404080980093;874.654511467973;875.6059773102098;877.2630989968425;878.3544095090103;878.4553576782404;918.8805455290803;921.2123634292684;925.4586067150725;925.7037747920668;926.2219735422244;910.7677542226722;917.3991035871514;918.1369512658114;918.7611221082533;919.4115765909823;685.4208194075998;685.4379408884716;685.4961603221628;685.9348361905611;685.9519833611649;15327.386904523806;15362.385504579803;15397.692046159125;15413.075215487106;15419.922900385427;14175.387514770213;14215.715685686228;14238.715225695429;14296.714065718732;14466.638334041629;13732.244726540037;13827.930860345634;13859.930700346535;13945.16329020262;14325.212113333762;4707.270373092176;4778.522147785228;4813.3983252093485;5012.523810238022;5021.046001259785;51996.74001629968;65513.344866551204;67996;68690.3130968689;83736.16263837405;12168.513259469699;12431.440585173688;12516.561920332744;12778.872211277954;12820.871791282154;22053.558928821654;22106.557868842534;22299.219527316465;22463.88768056182;22484;8448.366372522085;8617.741467755977;8706.782330441789;8724.389292749447;8970.955145224363;4584.587387135174;4593.908121837545;4727.834525791581;4849.442314133849;4899.461059283476;3744.64425879541;3893.7469064510715;3922.588128246493;3924.7056470764805;3925.2738243425056;654.8722999015241;660.3858411677186;662.4038365471115;662.8243515468405;673.0308355967392;632.34552238433;644.3878315600199;709.9183593886665;712.8895021271711;748.8951546783454;9495;10010.899891000996;10084.445355505371;10135.239857010754;10475.423851688078;671.1107782188619;700.9859802803916;717.153758564888;738.966746496409;744.5941961630924;4478.41780568524;4676.906461870743;4696.2955556666775;4732.361131247307;4823.782929768169;72473.6376318115;76265;88721.55639221762;133261;133501.3324933369;106490.46754766177;106548.46725766321;107924.46037769761;110376;114129.42935285436;62933.68533157305;94954;98777.50611246898;99196;102556.48721756194;8894.28845692356;9160.541972901461;9184.908150918673;9206.723798286062;9228.261739060994;39286.41070384004;47838.52161478341;48660;52086;58355.416445836694;47722.52277477181;49240.753796232224;57357;64179;76947;13900.374483148486;14317.212553309668;14394.20831854258;14405.639859003608;14527.418903243752;13696.452141914211;13982.510612128526;14098.718025639635;14149.292535372984;14424.639384015063;13949.58151255464;14225.786613201017;14528.055676381002;14812.703745924806;15025.098494090389;13718.245496497788;13914.234717090658;14292.14247145133;14353.85646143567;15004.099754014795;13077.869221307665;13210.933945330213;13221.933890330487;13250.204987700768;13323.33383330849;11845.822312665488;11882.940585297018;11980.28118312904;12005.219660722025;12154.817677735018;11948.522059117538;12030.09774266916;12061.336626485627;12133.150679452354;12303.753924921273 14:47:38 INFO - PROCESS | 21294 | __end_tp_report 14:47:38 INFO - PROCESS | 21294 | __start_cc_report 14:47:38 INFO - PROCESS | 21294 | _x_x_mozilla_cycle_collect,1005 14:47:38 INFO - PROCESS | 21294 | __end_cc_report 14:47:38 INFO - PROCESS | 21294 | __startTimestamp1474408058181__endTimestamp 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | ------- Summary: start ------- 14:47:38 INFO - PROCESS | 21294 | Number of tests: 6 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | [#0] dojo.html Cycles:165 Average:5616.03 Median:5786.07 stddev:639.41 (11.1%) stddev-sans-first:612.19 14:47:38 INFO - PROCESS | 21294 | Values: 3181.7 3203.9 3209.6 3236.4 3260.4 5628.4 5729.7 5747.1 5753.5 5779.8 5697.4 5739.9 5777.9 5781.4 5787.2 4988.4 5067.3 5204.3 5211.2 5261.2 5114.2 5181.4 5184.6 5199.2 5202.7 5127.5 5144.0 5166.4 5203.7 5219.8 5252.0 5283.8 5285.2 5295.0 5324.1 4962.6 5036.6 5057.8 5142.7 5143.3 5497.7 5523.9 5535.6 5543.9 5563.1 6048.9 6049.1 6053.1 6061.9 6090.0 5949.6 5995.4 6047.2 6059.0 6130.8 5686.6 5687.6 5693.5 5701.6 5712.6 6084.0 6161.3 6164.7 6167.1 6167.4 5666.6 5830.1 5875.1 5882.0 5970.7 5988.4 5997.6 6001.6 6008.0 6011.6 5969.5 5972.8 5978.0 5980.6 5982.9 5718.8 5936.9 5956.7 6012.3 6101.3 4597.9 4629.1 4630.0 4639.4 4655.9 4097.9 4139.7 4148.3 4164.0 4173.7 5511.3 5645.1 5706.2 5710.6 5748.3 6092.1 6094.9 6095.1 6097.2 6097.3 5719.0 5729.4 5732.4 5733.9 5741.4 5076.5 5215.9 5238.1 5244.4 5332.0 6296.0 6314.8 6326.2 6326.3 6335.8 6086.1 6123.5 6127.6 6137.4 6146.4 6069.5 6232.6 6250.7 6261.5 6359.9 6033.8 6053.8 6054.0 6056.3 6094.4 6170.7 6179.1 6186.9 6188.9 6190.8 5753.8 5786.1 5797.8 5819.2 5917.3 5779.0 5835.9 5854.7 5857.0 5860.3 5765.5 5767.3 5768.7 5822.8 5826.9 5744.3 5856.6 5892.4 5895.5 5936.4 5798.4 5805.0 5810.5 5813.7 5849.1 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | [#1] ext.html Cycles:180 Average:64870.14 Median:38513.71 stddev:134786.78 (350.0%) stddev-sans-first:135078.51 14:47:38 INFO - PROCESS | 21294 | Values: 597.7 646.0 660.3 666.8 672.2 35263.8 37938.1 37946.8 37960.4 38097.4 38002.8 38501.6 47753.8 48828.0 48918.3 9162.5 9299.1 9299.3 9300.4 9304.5 9123.8 9294.2 9306.6 9314.2 9321.8 8733.5 11603.8 11822.8 11840.2 11844.2 11346.1 11406.7 11429.8 11433.6 11435.8 11108.7 11113.8 11189.2 11191.4 11207.2 44499.3 44729.1 44772.3 44861.1 44882.8 3783.9 3804.2 3824.2 3895.0 3914.2 34670.3 34676.7 34680.1 34709.0 34801.0 4532.8 4603.7 4615.5 4642.2 4683.9 348784.0 348871.0 351485.0 352762.0 353093.0 60729.4 61072.4 61145.0 61414.0 61784.0 39461.8 40056.8 40268.0 42163.0 64996.7 14982.0 15127.8 15132.5 15133.8 15145.2 11172.4 11238.4 11242.6 11244.9 11254.1 18341.8 19800.0 20124.4 20303.9 20572.3 7152.4 7542.4 7571.8 7606.8 7742.0 1012.4 1080.6 1083.4 1135.3 1166.1 34607.0 34973.5 34998.5 35197.8 35233.5 1100.9 1152.1 1159.2 1206.8 1228.6 16333.4 17199.9 17310.4 17423.5 17578.9 647129.0 647489.0 647755.0 649347.0 650559.0 60716.4 61138.7 61230.4 61292.7 61308.0 1325.5 1395.0 1428.4 1442.3 1446.9 32383.8 34212.5 34710.8 34739.5 35027.8 52098.2 52687.7 52795.0 52844.7 52923.2 455486.0 455608.0 455957.0 463208.0 530476.0 29803.7 29866.3 29961.3 31233.1 31548.4 49604.0 49976.0 50044.5 50073.0 50402.2 41904.8 42034.6 42110.4 42267.8 43617.1 41874.8 42350.0 43157.6 43774.1 44670.6 42192.6 43142.0 43583.8 43931.0 44307.3 36093.8 38245.4 38525.8 38929.0 39003.2 28937.3 29188.3 29274.3 29274.4 29393.9 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | [#2] jquery.html Cycles:180 Average:30476.23 Median:2695.08 stddev:87617.78 (3251.0%) stddev-sans-first:87834.49 14:47:38 INFO - PROCESS | 21294 | Values: 410.3 410.4 418.5 423.7 440.4 19311.0 20214.2 20621.5 20832.8 21114.8 16655.3 18612.3 20227.5 20745.5 20756.0 5755.3 6004.1 6037.6 6056.0 6062.4 4320.5 4549.5 5938.1 6021.0 6662.1 6669.0 6670.5 6677.8 6683.6 6685.6 6488.5 6663.3 6669.0 6704.8 6705.0 5933.9 5939.4 5951.5 5960.6 5960.8 24260.4 24375.9 24451.0 24592.8 24910.0 2589.4 2642.5 2747.7 2871.7 3078.0 15267.9 15327.8 15661.3 15706.7 15987.0 2049.5 2049.6 2051.4 2089.2 2129.8 61710.0 71788.0 80333.0 84810.6 86301.1 11022.3 11131.7 11307.5 11361.6 11416.0 15848.4 15876.6 15906.7 15907.2 15931.8 7399.7 7849.3 7879.6 7897.3 7968.4 7386.2 7432.0 7598.4 7602.5 7608.1 6838.8 6903.9 6945.1 6989.5 7014.0 2009.6 2015.2 2021.7 2022.2 2082.7 911.4 975.1 1001.6 1035.6 1159.2 20728.4 21269.4 21527.4 21560.1 21594.4 994.6 1030.9 1039.1 1054.0 1066.1 11093.4 11968.1 12043.5 12085.2 12105.2 516088.0 524542.0 535064.0 544670.0 551680.0 82172.6 84758.6 85754.6 86599.0 86903.6 940.9 1000.5 1057.8 1096.2 1126.0 17803.6 19040.9 19082.2 19168.3 19255.8 41510.0 42447.6 42937.4 43585.1 43993.6 54488.0 54870.7 55504.4 56553.4 58047.1 8782.7 8927.9 9141.3 9224.6 9359.4 9257.4 9739.6 13551.2 13733.2 14197.5 11956.9 12235.3 12408.4 12426.2 12450.8 11981.4 12129.8 12146.5 12341.4 12477.5 12254.0 12540.5 12616.4 12629.7 12637.7 8675.9 8966.9 9136.6 9151.3 9230.1 11143.7 11894.6 12084.1 12139.9 12245.6 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | [#3] mootools.html Cycles:180 Average:6212.39 Median:472.27 stddev:3621.72 (766.9%) stddev-sans-first:3614.90 14:47:38 INFO - PROCESS | 21294 | Values: 1546.4 1572.7 1663.9 1772.4 1798.5 8905.6 9634.8 9636.0 9647.7 9649.2 7939.2 8940.7 9252.5 9260.9 9375.8 2431.1 2480.2 2482.7 2489.3 2491.8 2386.2 2523.3 2524.9 2527.9 2528.9 2506.4 2520.5 2584.9 2589.2 2591.9 2484.5 2487.5 2493.9 2528.3 2546.5 2456.9 2460.3 2496.5 2504.4 2505.9 9570.6 9680.4 9691.2 9702.7 9720.1 5495.9 5628.4 5642.8 5651.0 5653.7 3567.7 3665.1 3727.8 3747.9 3754.4 1195.9 1276.6 1291.7 1300.9 1303.9 13083.9 13503.1 13586.6 13590.7 13616.9 4831.7 4862.0 4895.2 5037.4 5041.0 10621.3 10621.9 10631.5 10636.3 10644.2 5486.5 5488.3 5489.8 5492.7 5495.5 4518.4 5084.9 5142.5 5150.1 5198.5 4076.4 4083.6 4152.9 4198.9 4259.1 1235.7 1239.9 1242.0 1254.6 1257.5 908.6 927.3 931.1 966.2 1043.2 6100.8 6195.3 6252.1 6409.5 6553.5 458.9 463.3 468.7 469.6 474.9 4147.9 4357.3 4372.7 4398.6 4406.7 11526.8 11938.9 12001.1 12022.4 12072.2 11957.3 11997.6 12015.3 12028.5 12031.8 8522.7 8839.4 8845.0 8896.6 9043.9 5987.4 6102.8 6247.8 6287.8 6317.6 11164.8 11276.3 11372.6 11498.7 11542.7 11219.3 11268.7 11516.9 11568.9 11627.5 8276.7 8443.2 8501.0 8506.1 8545.4 8758.0 8895.2 8942.8 8965.9 9005.5 7977.1 8045.8 8111.3 8184.7 8233.3 7893.7 7952.9 7980.2 8116.1 8407.7 7444.1 7468.3 7544.4 7592.7 7613.8 5205.1 5255.8 5279.7 5341.6 5372.4 5497.0 5599.7 5638.5 5667.9 5681.1 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | [#4] prototype.html Cycles:180 Average:4685.29 Median:5233.63 stddev:1336.96 (25.5%) stddev-sans-first:1300.34 14:47:38 INFO - PROCESS | 21294 | Values: 341.2 349.5 349.6 354.0 355.4 5378.9 5457.7 5542.7 5579.0 5580.2 5483.0 5504.7 5512.2 5515.4 5523.0 4369.4 4373.1 4375.5 4380.6 4389.2 4145.6 4301.8 4366.5 4371.9 4394.7 4255.6 4271.6 4272.6 4276.6 4289.5 4279.5 4327.1 4331.2 4340.4 4348.0 3728.5 3728.5 3842.5 3849.6 3856.0 5324.4 5337.9 5358.9 5405.7 5425.7 4885.3 4936.4 4956.4 4959.4 4960.9 5427.8 5729.6 5762.7 5763.9 5778.7 4191.3 4206.2 4207.6 4209.1 4212.7 5992.8 5999.8 6005.2 6007.4 6009.8 4223.1 4225.9 4263.4 4323.1 4328.9 4308.0 4316.4 4330.9 4334.0 4348.5 4842.3 4906.5 4918.6 4920.8 4934.6 5679.7 5778.3 5806.2 5810.2 5818.8 1473.7 1489.8 1490.7 1494.1 1494.7 929.7 947.0 959.4 973.5 977.9 4890.4 4919.4 4943.9 5001.7 5032.0 5176.9 5182.2 5210.3 5217.4 5253.0 4699.8 4935.5 4967.7 4987.8 4990.4 2772.6 2778.7 2791.0 2819.4 2826.4 5363.4 5365.3 5384.6 5417.8 5448.7 5125.8 5133.6 5292.2 5294.8 5324.5 5320.8 5371.6 5396.4 5398.7 5406.8 5227.4 5285.6 5292.5 5298.9 5311.8 4995.9 5097.7 5179.9 5204.6 5239.8 5333.4 5342.7 5370.9 5406.8 5413.7 4057.5 4156.5 4157.5 4199.4 4201.1 5834.5 5865.9 5866.4 5903.4 5918.0 5594.9 5722.7 5809.5 5813.8 5813.9 5737.2 5739.9 5756.0 5757.4 5757.7 5689.1 5706.0 5719.9 5724.8 5734.4 5535.7 5721.7 5760.2 5774.0 5774.0 5607.9 5611.3 5616.9 5634.1 5639.5 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - PROCESS | 21294 | [#5] yui.html Cycles:180 Average:20468.05 Median:4635.41 stddev:29218.28 (630.3%) stddev-sans-first:29262.92 14:47:38 INFO - PROCESS | 21294 | Values: 799.2 808.7 816.4 852.0 905.4 15685.6 17477.0 17607.3 17611.6 17706.5 15672.6 15868.2 16149.7 16182.2 16189.4 862.2 889.4 890.9 890.9 891.3 874.7 875.6 877.3 878.4 878.5 918.9 921.2 925.5 925.7 926.2 910.8 917.4 918.1 918.8 919.4 685.4 685.4 685.5 685.9 686.0 15327.4 15362.4 15397.7 15413.1 15419.9 14175.4 14215.7 14238.7 14296.7 14466.6 13732.2 13827.9 13859.9 13945.2 14325.2 4707.3 4778.5 4813.4 5012.5 5021.0 51996.7 65513.3 67996.0 68690.3 83736.2 12168.5 12431.4 12516.6 12778.9 12820.9 22053.6 22106.6 22299.2 22463.9 22484.0 8448.4 8617.7 8706.8 8724.4 8971.0 4584.6 4593.9 4727.8 4849.4 4899.5 3744.6 3893.7 3922.6 3924.7 3925.3 654.9 660.4 662.4 662.8 673.0 632.3 644.4 709.9 712.9 748.9 9495.0 10010.9 10084.4 10135.2 10475.4 671.1 701.0 717.2 739.0 744.6 4478.4 4676.9 4696.3 4732.4 4823.8 72473.6 76265.0 88721.6 133261.0 133501.3 106490.5 106548.5 107924.5 110376.0 114129.4 62933.7 94954.0 98777.5 99196.0 102556.5 8894.3 9160.5 9184.9 9206.7 9228.3 39286.4 47838.5 48660.0 52086.0 58355.4 47722.5 49240.8 57357.0 64179.0 76947.0 13900.4 14317.2 14394.2 14405.6 14527.4 13696.5 13982.5 14098.7 14149.3 14424.6 13949.6 14225.8 14528.1 14812.7 15025.1 13718.2 13914.2 14292.1 14353.9 15004.1 13077.9 13210.9 13221.9 13250.2 13323.3 11845.8 11882.9 11980.3 12005.2 12154.8 11948.5 12030.1 12061.3 12133.2 12303.8 14:47:38 INFO - PROCESS | 21294 | -------- Summary: end -------- 14:47:38 INFO - PROCESS | 21294 | 14:47:38 INFO - TEST-INFO | 21294: exit 0 14:47:38 INFO - TEST-OK | dromaeo_css | took 1113270ms 14:47:38 INFO - TEST-START | kraken 14:47:38 INFO - operating with platform_type : linux_ 14:47:38 INFO - Initialising browser for kraken test... 14:47:38 INFO - TEST-INFO | started process 21488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp95JtuB/profile http://localhost:34463/getInfo.html) 14:47:42 INFO - PROCESS | 21488 | __metrics Screen width/height:1600/1200 14:47:42 INFO - PROCESS | 21488 | colorDepth:24 14:47:42 INFO - PROCESS | 21488 | Browser inner width/height: 1024/697 14:47:42 INFO - PROCESS | 21488 | __metrics 14:47:43 INFO - PROCESS | 21488 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:47:43 INFO - PROCESS | 21488 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:47:44 INFO - TEST-INFO | 21488: exit 0 14:47:44 INFO - Browser initialized. 14:47:44 INFO - Running cycle 1/1 for kraken test... 14:47:44 INFO - TEST-INFO | started process 21577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp95JtuB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:56 INFO - PROCESS | 21577 | RSS: Main: 143622144 14:47:56 INFO - PROCESS | 21577 | 14:47:58 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34463/tests/kraken/driver.html?testName=audio-beat-detection) 14:47:59 INFO - PROCESS | 21577 | RSS: Main: 199028736 14:47:59 INFO - PROCESS | 21577 | 14:48:02 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34463/tests/kraken/driver.html?testName=audio-dft) 14:48:02 INFO - PROCESS | 21577 | RSS: Main: 183627776 14:48:02 INFO - PROCESS | 21577 | 14:48:05 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34463/tests/kraken/driver.html?testName=audio-fft) 14:48:06 INFO - PROCESS | 21577 | RSS: Main: 274214912 14:48:06 INFO - PROCESS | 21577 | 14:48:08 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34463/tests/kraken/driver.html?testName=audio-oscillator) 14:48:09 INFO - PROCESS | 21577 | RSS: Main: 180187136 14:48:09 INFO - PROCESS | 21577 | 14:48:11 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34463/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:48:11 INFO - PROCESS | 21577 | RSS: Main: 178302976 14:48:11 INFO - PROCESS | 21577 | 14:48:14 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34463/tests/kraken/driver.html?testName=imaging-darkroom) 14:48:15 INFO - PROCESS | 21577 | RSS: Main: 214904832 14:48:15 INFO - PROCESS | 21577 | 14:48:18 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34463/tests/kraken/driver.html?testName=imaging-desaturate) 14:48:19 INFO - PROCESS | 21577 | RSS: Main: 213590016 14:48:19 INFO - PROCESS | 21577 | 14:48:22 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34463/tests/kraken/driver.html?testName=json-parse-financial) 14:48:22 INFO - PROCESS | 21577 | RSS: Main: 213086208 14:48:22 INFO - PROCESS | 21577 | 14:48:24 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34463/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:48:24 INFO - PROCESS | 21577 | RSS: Main: 175394816 14:48:24 INFO - PROCESS | 21577 | 14:48:26 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:48:27 INFO - PROCESS | 21577 | RSS: Main: 182558720 14:48:27 INFO - PROCESS | 21577 | 14:48:28 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:48:29 INFO - PROCESS | 21577 | RSS: Main: 184532992 14:48:29 INFO - PROCESS | 21577 | 14:48:32 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:48:32 INFO - PROCESS | 21577 | RSS: Main: 185335808 14:48:32 INFO - PROCESS | 21577 | 14:48:35 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:48:35 INFO - PROCESS | 21577 | RSS: Main: 182718464 14:48:35 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | Cycle 1(1): loaded http://localhost:34463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34463/tests/kraken/driver.html?testName=ai-astar) 14:48:37 INFO - PROCESS | 21577 | RSS: Main: 233857024 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | __start_tp_report 14:48:37 INFO - PROCESS | 21577 | _x_x_mozilla_page_load 14:48:37 INFO - PROCESS | 21577 | _x_x_mozilla_page_load_details 14:48:37 INFO - PROCESS | 21577 | |i|pagename|runs| 14:48:37 INFO - PROCESS | 21577 | |0;ai-astar;112;109;120;109;111;110;111;110;111;110 14:48:37 INFO - PROCESS | 21577 | |1;audio-beat-detection;150;117;142;164;118;146;150;116;176;121 14:48:37 INFO - PROCESS | 21577 | |2;audio-dft;163;160;162;161;162;160;159;164;161;160 14:48:37 INFO - PROCESS | 21577 | |3;audio-fft;76;75;77;169;76;74;74;75;170;76 14:48:37 INFO - PROCESS | 21577 | |4;audio-oscillator;84;84;151;84;84;85;140;84;84;149 14:48:37 INFO - PROCESS | 21577 | |5;imaging-gaussian-blur;116;117;117;117;116;117;116;118;117;117 14:48:37 INFO - PROCESS | 21577 | |6;imaging-darkroom;145;146;145;146;147;146;147;146;146;147 14:48:37 INFO - PROCESS | 21577 | |7;imaging-desaturate;95;94;95;94;95;95;95;95;95;95 14:48:37 INFO - PROCESS | 21577 | |8;json-parse-financial;51;51;53;53;52;54;52;50;51;52 14:48:37 INFO - PROCESS | 21577 | |9;json-stringify-tinderbox;59;60;60;59;60;60;60;61;60;60 14:48:37 INFO - PROCESS | 21577 | |10;stanford-crypto-aes;73;74;72;74;78;74;72;72;73;73 14:48:37 INFO - PROCESS | 21577 | |11;stanford-crypto-ccm;126;119;118;118;117;117;127;116;150;125 14:48:37 INFO - PROCESS | 21577 | |12;stanford-crypto-pbkdf2;156;156;155;153;154;161;153;150;151;152 14:48:37 INFO - PROCESS | 21577 | |13;stanford-crypto-sha256-iterative;64;65;68;61;63;62;63;62;60;60 14:48:37 INFO - PROCESS | 21577 | __end_tp_report 14:48:37 INFO - PROCESS | 21577 | __start_cc_report 14:48:37 INFO - PROCESS | 21577 | _x_x_mozilla_cycle_collect,1638 14:48:37 INFO - PROCESS | 21577 | __end_cc_report 14:48:37 INFO - PROCESS | 21577 | __startTimestamp1474408117822__endTimestamp 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | ------- Summary: start ------- 14:48:37 INFO - PROCESS | 21577 | Number of tests: 14 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:3.20 (2.9%) stddev-sans-first:3.38 14:48:37 INFO - PROCESS | 21577 | Values: 112.0 109.0 120.0 109.0 111.0 110.0 111.0 110.0 111.0 110.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:148.00 stddev:21.24 (14.4%) stddev-sans-first:22.22 14:48:37 INFO - PROCESS | 21577 | Values: 150.0 117.0 142.0 164.0 118.0 146.0 150.0 116.0 176.0 121.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.55 (1.0%) stddev-sans-first:1.50 14:48:37 INFO - PROCESS | 21577 | Values: 163.0 160.0 162.0 161.0 162.0 160.0 159.0 164.0 161.0 160.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.50 stddev:39.70 (52.6%) stddev-sans-first:41.56 14:48:37 INFO - PROCESS | 21577 | Values: 76.0 75.0 77.0 169.0 76.0 74.0 74.0 75.0 170.0 76.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#4] audio-oscillator Cycles:10 Average:102.90 Median:84.00 stddev:30.33 (36.1%) stddev-sans-first:31.39 14:48:37 INFO - PROCESS | 21577 | Values: 84.0 84.0 151.0 84.0 84.0 85.0 140.0 84.0 84.0 149.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 14:48:37 INFO - PROCESS | 21577 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 116.0 118.0 117.0 117.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 14:48:37 INFO - PROCESS | 21577 | Values: 145.0 146.0 145.0 146.0 147.0 146.0 147.0 146.0 146.0 147.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 14:48:37 INFO - PROCESS | 21577 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.20 (2.3%) stddev-sans-first:1.22 14:48:37 INFO - PROCESS | 21577 | Values: 51.0 51.0 53.0 53.0 52.0 54.0 52.0 50.0 51.0 52.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 14:48:37 INFO - PROCESS | 21577 | Values: 59.0 60.0 60.0 59.0 60.0 60.0 60.0 61.0 60.0 60.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.50 stddev:1.78 (2.4%) stddev-sans-first:1.88 14:48:37 INFO - PROCESS | 21577 | Values: 73.0 74.0 72.0 74.0 78.0 74.0 72.0 72.0 73.0 73.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#11] stanford-crypto-ccm Cycles:10 Average:123.30 Median:122.00 stddev:10.24 (8.4%) stddev-sans-first:10.82 14:48:37 INFO - PROCESS | 21577 | Values: 126.0 119.0 118.0 118.0 117.0 117.0 127.0 116.0 150.0 125.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:154.50 stddev:3.14 (2.0%) stddev-sans-first:3.26 14:48:37 INFO - PROCESS | 21577 | Values: 156.0 156.0 155.0 153.0 154.0 161.0 153.0 150.0 151.0 152.0 14:48:37 INFO - PROCESS | 21577 | 14:48:37 INFO - PROCESS | 21577 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:2.44 (3.9%) stddev-sans-first:2.55 14:48:37 INFO - PROCESS | 21577 | Values: 64.0 65.0 68.0 61.0 63.0 62.0 63.0 62.0 60.0 60.0 14:48:37 INFO - PROCESS | 21577 | -------- Summary: end -------- 14:48:37 INFO - PROCESS | 21577 | 14:48:38 INFO - TEST-INFO | 21577: exit 0 14:48:38 INFO - TEST-OK | kraken | took 59924ms 14:48:38 INFO - SUITE-END | took 1173s 14:48:38 INFO - Completed test suite (00:19:33) 14:48:38 INFO - javascript benchmark 14:48:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3181.7136457718802, 3203.91990200245, 3209.630892447368, 3236.3850868335, 3260.4131256373844, 5628.43715628437, 5729.656220626765, 5747.080467125261, 5753.510951569122, 5779.797707080253, 5697.4302569743, 5739.913901291484, 5777.855553611162, 5781.364049954502, 5787.189793428913, 4988.376452943382, 5067.34124563808, 5204.349456317969, 5211.192265198882, 5261.184516399964, 5114.23286507025, 5181.378234611851, 5184.611154163434, 5199.220116982445, 5202.687838729688, 5127.48725127488, 5144.048351055067, 5166.405863325709, 5203.661761985459, 5219.817306394277, 5251.973740131294, 5283.841484755463, 5285.154375299933, 5294.973525132388, 5324.148136298174, 4962.578180854632, 5036.622253331015, 5057.848264552069, 5142.665726727751, 5143.279940808253, 5497.725113744336, 5523.861903452445, 5535.557155427556, 5543.916841247385, 5563.109902415594, 6048.941435248814, 6049.092636104576, 6053.092036194562, 6061.878762424771, 6089.969550152266, 5949.613275137102, 5995.430434108753, 6047.183630209909, 6059.030555111161, 6130.846728831771, 5686.630369026, 5687.630304030307, 5693.459121383504, 5701.600887937846, 5712.6286791358425, 6083.969580152159, 6161.322254551996, 6164.6917654117115, 6167.136600875881, 6167.383261673886, 5666.603337766399, 5830.125481177857, 5875.118732190205, 5882.02946513829, 5970.731317090741, 5988.371221021817, 5997.580169388189, 6001.5798894077, 6008.03871380577, 6011.579189456696, 5969.462748352669, 5972.790952316648, 5978.04351303798, 5980.55145864062, 5982.880342393215, 5718.828435146869, 5936.881262374728, 5956.702164891738, 6012.30858451275, 6101.328853826164, 4597.862064138081, 4629.051044535862, 4630.027694184259, 4639.396878405786, 4655.930161047587, 4097.8770636880945, 4139.710220284612, 4148.315527937917, 4163.958360416357, 4173.728707633994, 5511.283533140746, 5645.096784514458, 5706.201131841545, 5710.600257981984, 5748.338941021836, 6092.147099405998, 6094.908576371358, 6095.085737139376, 6097.207363042776, 6097.298810636745, 5718.971405142947, 5729.427057294237, 5732.369439361667, 5733.942660573341, 5741.368449470555, 5076.517730815566, 5215.947840521622, 5238.083335416363, 5244.423113457517, 5332.040232758063, 6296.024116261987, 6314.842128946813, 6326.240851097898, 6326.304106548276, 6335.841603959938, 6086.069144037704, 6123.475981923453, 6127.571070025056, 6137.41694539018, 6146.354632763674, 6069.544784141119, 6232.594881332699, 6250.718717657807, 6261.530385221217, 6359.872802544016, 6033.78881739137, 6053.788117415869, 6053.969730151321, 6056.333803281724, 6094.421030002142, 6170.7223174958135, 6179.1040299155475, 6186.907196392148, 6188.876222475616, 6190.783322583688, 5753.798617048383, 5786.07422812348, 5797.797077102281, 5819.15622234782, 5917.260342457193, 5779.0, 5835.891180675658, 5854.707264636836, 5857.00430926752, 5860.326062502867, 5765.4811067004175, 5767.307923049261, 5768.653880767168, 5822.806324703511, 5826.941730582809, 5744.3394009687745, 5856.59003869725, 5892.351841297368, 5895.498882595028, 5936.436038576328, 5798.391168927202, 5804.984127777538, 5810.5061069809535, 5813.651180929158, 5849.064149736022], "value": 5572.3579884775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.7310210405319, 645.9870802583948, 660.3495556876477, 666.8232918276655, 672.2302963107239, 35263.82368088159, 37938.051548711264, 37946.8102659487, 37960.43059354112, 38097.42853857191, 38002.8099859501, 38501.61498385015, 47753.76123119379, 48828.0, 48918.26622600655, 9162.496062716531, 9299.116583924517, 9299.30255230857, 9300.441973481613, 9304.488253146073, 9123.771905702344, 9294.209992150676, 9306.62773489063, 9314.208292295163, 9321.766955826091, 8733.475991440533, 11603.767924641503, 11822.822657660143, 11840.230385024988, 11844.230125041888, 11346.149038822081, 11406.657800265923, 11429.828552571676, 11433.59982400612, 11435.828462573068, 11108.722281943015, 11113.83329250062, 11189.216754827165, 11191.384473853936, 11207.21549491536, 44499.33251001238, 44729.10541789146, 44772.328415073796, 44861.10277794426, 44882.77558612219, 3783.9432408513894, 3804.239152169577, 3824.158685089276, 3895.0, 3914.178022615226, 34670.306593868234, 34676.653233467594, 34680.13299667528, 34709.0, 34801.0, 4532.750698711569, 4603.746793926332, 4615.515370886076, 4642.234031384817, 4683.929741053887, 348784.0, 348871.0451581934, 351485.0, 352761.99999999744, 353093.0, 60729.39270607326, 61072.38927610756, 61145.0, 61414.0, 61784.0, 39461.80269098636, 40056.79971600181, 40268.0, 42163.0, 64996.67501662461, 14982.0, 15127.773083403757, 15132.546023619308, 15133.848661513244, 15145.242737863284, 11172.385518796382, 11238.438078096062, 11242.55029798799, 11244.887551124548, 11254.099672026217, 18341.81658183428, 19800.019998000083, 20124.39626811198, 20303.8984805075, 20572.279970200972, 7152.4278057755255, 7542.358899493604, 7571.772846814604, 7606.80982975419, 7742.0, 1012.4431562640666, 1080.6109800471825, 1083.4041277297456, 1135.2620796482188, 1166.0904494494307, 34607.0, 34973.47539786905, 34998.47502287468, 35197.82401087978, 35233.47149792755, 1100.944952752359, 1152.0610702277618, 1159.2117360195148, 1206.7586482703493, 1228.5700004998357, 16333.42833000863, 17199.91400043017, 17310.394136205174, 17423.47729568115, 17578.91210543939, 647129.0, 647489.0, 647755.0, 649347.0, 650559.0, 60716.39283607196, 61138.69430652818, 61230.38769612336, 61292.69353653203, 61308.0, 1325.4896864707157, 1394.9511767088104, 1428.4429072661671, 1442.3004842651305, 1446.8914831387688, 32383.838080809444, 34212.48681269733, 34710.826445867606, 34739.478907816905, 35027.82486087553, 52098.218526722885, 52687.736561316946, 52795.0, 52844.73577632087, 52923.20615190698, 455486.0, 455608.0, 455957.0, 463208.0, 530476.0, 29803.701962980093, 29866.253343666584, 29961.250968725955, 31233.06300810979, 31548.369032619677, 49604.0, 49976.0, 50044.49955500398, 50073.0, 50402.243966341266, 41904.79047604743, 42034.579654203066, 42110.36834447424, 42267.7886610565, 43617.127657446035, 41874.79062604668, 42350.0, 43157.56842431661, 43774.12451750883, 44670.55329446664, 42192.578074218865, 43142.0, 43583.78208109066, 43931.0, 44307.33538996982, 36093.819530902176, 38245.42631860469, 38525.80737096297, 38929.0, 39003.21993560169, 28937.276568085967, 29188.27029324284, 29274.268143296587, 29274.41451170922, 29393.85303073471], "value": 21064.967973202416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.25948163564755, 410.4438485851669, 418.5082528029565, 423.67165446778745, 440.35268155810945, 19311.0, 20214.191432342726, 20621.484462888435, 20832.791672083276, 21114.788852111473, 16655.25051372688, 18612.255509779592, 20227.494312642193, 20745.481362965897, 20755.96220188992, 5755.338136114358, 6004.129401236818, 6037.607555508878, 6056.0, 6062.424069713392, 4320.524742278347, 4549.499555048942, 5938.109283607472, 6021.036634138539, 6662.067310576524, 6669.032990216416, 6670.499712521556, 6677.766278180265, 6683.598984060972, 6685.565438246523, 6488.545801793876, 6663.300353462865, 6669.0, 6704.765333213339, 6705.0, 5933.851653708649, 5939.4357536034, 5951.523878089785, 5960.582759206857, 5960.821175364746, 24260.39349016271, 24375.87812060946, 24451.0, 24592.754072459404, 24910.0, 2589.37854914821, 2642.5111354399305, 2747.6565429321336, 2871.7128287171327, 3078.0, 15267.923660381626, 15327.846721532753, 15661.29524171415, 15706.712461733765, 15987.0, 2049.5081180516568, 2049.610573990937, 2051.393857710566, 2089.2165437960766, 2129.7976692214215, 61710.0, 71788.0, 80333.0, 84810.57594712048, 86301.13698862994, 11022.283551569122, 11131.721706957229, 11307.49116289769, 11361.602343917923, 11416.0, 15848.44530441429, 15876.603084922739, 15906.681866362607, 15907.204639767966, 15931.761023584424, 7399.741009064657, 7849.3328067114935, 7879.606019698992, 7897.3287270581495, 7968.362530997622, 7386.224446433154, 7431.962840185764, 7598.354139898171, 7602.46782725204, 7608.087029556488, 6838.829029274308, 6903.930960690429, 6945.0971373721095, 6989.5107342485535, 7013.964930175419, 2009.6033256886617, 2015.1737787507052, 2021.6866385710239, 2022.150696707357, 2082.6875968604527, 911.3666002128457, 975.1077763846143, 1001.5793366785967, 1035.6427032673716, 1159.2349049627205, 20728.37814865526, 21269.36191914245, 21527.354179374644, 21560.13759449636, 21594.352169434944, 994.6469003503797, 1030.948452577383, 1039.1271332081087, 1054.0408228512017, 1066.1364294921138, 11093.389863557582, 11968.10239232061, 12043.518259269706, 12085.214461060003, 12105.152639315163, 516088.0, 524542.0, 535064.0, 544670.0, 551680.0, 82172.58913705393, 84758.57620711857, 85754.57122714471, 86599.0, 86903.56548217345, 940.8823897012874, 1000.5347513406156, 1057.836035414512, 1096.2107282756403, 1125.994370028161, 17803.554911127325, 19040.904795475933, 19082.236710531422, 19168.329108481135, 19255.77603690292, 41510.0, 42447.57552424436, 42937.35593966155, 43585.12829743324, 43993.560064398946, 54488.0, 54870.72564637151, 55504.44495554993, 56553.43446565482, 58047.12929306148, 8782.692605758768, 8927.866082008644, 9141.26869850397, 9224.631014759603, 9359.438433694, 9257.444553326823, 9739.561719722771, 13551.186928784304, 13733.176009439465, 14197.503087391891, 11956.940215298868, 12235.26588404663, 12408.379581021094, 12426.192297500995, 12450.81323780126, 11981.400929953641, 12129.757404851676, 12146.514139434325, 12341.444634991267, 12477.5008999639, 12254.01744433045, 12540.498380064695, 12616.432260548114, 12629.684257893627, 12637.74724505523, 8675.86986195195, 8966.910330896608, 9136.588853501742, 9151.313651476034, 9230.076992300985, 11143.665690029313, 11894.643160705193, 12084.093692972887, 12139.939300303442, 12245.632631021084], "value": 9790.663704902861, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1546.3891762753708, 1572.7090488259673, 1663.8502534771876, 1772.4151030160056, 1798.5054110119706, 8905.599248033837, 9634.807303853928, 9636.0, 9647.710568682933, 9649.179819715315, 7939.206079392058, 8940.73177804667, 9252.491112988782, 9260.907390926088, 9375.76560585984, 2431.064040344467, 2480.2435257246534, 2482.6772519572432, 2489.26566662834, 2491.813114016447, 2386.200622791367, 2523.3313172009434, 2524.886380112899, 2527.8736063196766, 2528.9241322760345, 2506.410993416546, 2520.4707011527603, 2584.883680234394, 2589.2102908612865, 2591.883365248568, 2484.4906794107164, 2487.45276039272, 2493.8652912924576, 2528.329992551975, 2546.4652422991194, 2456.914008009711, 2460.2865169100937, 2496.500699860017, 2504.4114633061217, 2505.8747062646794, 9570.56932438042, 9680.419174849532, 9691.176250018756, 9702.660406885725, 9720.07659272368, 5495.945040549624, 5628.437156284339, 5642.802501912413, 5651.039323315046, 5653.745581448845, 3567.678908898212, 3665.138692407282, 3727.7576957497677, 3747.925041499182, 3754.3617585010616, 1195.8983486403665, 1276.5978716704208, 1291.6577107066635, 1300.8504022037491, 1303.9282839443827, 13083.934580327134, 13503.12229705066, 13586.592402227949, 13590.728185436235, 13616.863831361758, 4831.661783675109, 4862.0, 4895.167821470359, 5037.420696619863, 5040.991801639687, 10621.309614875163, 10621.946890265499, 10631.52158152885, 10636.30863993838, 10644.201684873666, 5486.533644640251, 5488.25908502355, 5489.780408783684, 5492.69790161537, 5495.477929596682, 4518.43519560055, 5084.872878178001, 5142.51146141116, 5150.124478838607, 5198.454162312978, 4076.449679293317, 4083.5508094109623, 4152.875413737593, 4198.874033778991, 4259.148170365939, 1235.6910772306924, 1239.9318037507844, 1242.0, 1254.5609036837216, 1257.5221415861913, 908.5502676175204, 927.3137877970254, 931.1479995803763, 966.2028826218398, 1043.1550444140119, 6100.755969761249, 6195.349488303754, 6252.093446450239, 6409.487241020707, 6553.5084868634085, 458.9288660257665, 463.3119817071646, 468.6508551129362, 469.63368572513497, 474.90976714424147, 4147.854825081107, 4357.302831546937, 4372.715773474714, 4398.560143985575, 4406.669499787528, 11526.827097593543, 11938.88061119395, 12001.099917506222, 12022.398880055962, 12072.215306005257, 11957.342346171044, 11997.560292764749, 12015.339156346485, 12028.45871935765, 12031.819522707166, 8522.678984757373, 8839.381243313035, 8844.955775221082, 8896.555172241491, 9043.909560904438, 5987.401259874152, 6102.786402476069, 6247.750089996349, 6287.779927702509, 6317.5893566918, 11164.832527511931, 11276.32342059479, 11372.601958931398, 11498.655040348804, 11542.653720388402, 11219.270747401393, 11268.661940141808, 11516.884831151916, 11568.884311157117, 11627.47676354583, 8276.710315138942, 8443.155684431507, 8500.957495212731, 8506.106858779738, 8545.444546104483, 8757.956210219163, 8895.154960278764, 8942.821143577223, 8965.910340896768, 9005.459672419676, 7977.082635496992, 8045.798855028437, 8111.269985701315, 8184.672613095409, 8233.300169485661, 7893.723719669784, 7952.880706789519, 7980.201979801974, 8116.147804480444, 8407.663693452194, 7444.143923448656, 7468.290512401313, 7544.4341674373545, 7592.734254301073, 7613.847723045397, 5205.089109405763, 5255.816046438356, 5279.709615971158, 5341.599380046589, 5372.409035006224, 5497.0, 5599.689789912103, 5638.464345887134, 5667.85830354228, 5681.119426488901], "value": 4852.579252903385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.1607445683618, 349.49497975425504, 349.64161734222404, 353.9752217344785, 355.39050528343887, 5378.870437208181, 5457.690154362954, 5542.722863856806, 5578.972105139474, 5580.246666699994, 5482.972585137069, 5504.697241651707, 5512.228288039668, 5515.393306736256, 5523.0, 4369.388285640013, 4373.103513779683, 4375.518692943773, 4380.605745482906, 4389.23188442023, 4145.606167414091, 4301.763403012833, 4366.541513141106, 4371.8688439346715, 4394.736315821045, 4255.616994470497, 4271.6155546000855, 4272.607339889656, 4276.572342765714, 4289.485261768592, 4279.529251782302, 4327.117703798857, 4331.155424692186, 4340.370646256275, 4347.956520434786, 3728.459373390843, 3728.5152930119184, 3842.4620553122586, 3849.5573009104032, 3855.9807200964096, 5324.387695415038, 5337.879045400432, 5358.946410535884, 5405.729713514348, 5425.728713564306, 4885.340479035321, 4936.407631084293, 4956.430010548797, 4959.429665588467, 4960.851174464772, 5427.83716488506, 5729.627574207705, 5762.74067666956, 5763.942360576382, 5778.682172480512, 4191.287481128185, 4206.242876282268, 4207.64235040022, 4209.137126888981, 4212.726172798758, 5992.760289588411, 5999.820005399845, 6005.1892994445625, 6007.399260074001, 6009.849753756191, 4223.13425747721, 4225.936610950838, 4263.4031235627035, 4323.092150648336, 4328.891777705582, 4308.009157893702, 4316.374125751749, 4330.891727706769, 4333.956660433419, 4348.543402942709, 4842.346283251716, 4906.533879281463, 4918.5819205367225, 4920.827771028068, 4934.5805606523845, 5679.659220446786, 5778.27771528559, 5806.21616081832, 5810.240135935397, 5818.8254352369495, 1473.7126260379123, 1489.7988771515707, 1490.6571488557693, 1494.1184701026446, 1494.7384207763816, 929.702495201529, 946.990530094704, 959.4387283439266, 973.4986481961795, 977.941323520591, 4890.361728820813, 4919.434265059493, 4943.901121977612, 5001.7499125043605, 5031.9744204636145, 5176.886969301619, 5182.170852663556, 5210.296609957683, 5217.373915130132, 5252.9737351313, 4699.812007519729, 4935.457099719028, 4967.677100988424, 4987.800487980513, 4990.401151861727, 2772.625695531118, 2778.722127787205, 2790.9860450697615, 2819.3656427304104, 2826.4064546445475, 5363.417791751443, 5365.302510673588, 5384.623076384693, 5417.805788655601, 5448.700321482356, 5125.769340379692, 5133.589312854962, 5292.232626269247, 5294.788208471618, 5324.547413469821, 5320.813771518056, 5371.57027437804, 5396.4333744957, 5398.703071331036, 5406.756695948692, 5227.4249832517635, 5285.550728187994, 5292.533390629144, 5298.920516192337, 5311.814086506954, 4995.8751031223055, 5097.706032285412, 5179.860430705239, 5204.583633309402, 5239.842804715865, 5333.43998880112, 5342.706151161723, 5370.892582148257, 5406.756695948613, 5413.702246376487, 4057.5130984282073, 4156.501219853637, 4157.501099868035, 4199.370094485852, 4201.096764195712, 5834.533237341087, 5865.853353666193, 5866.354700982974, 5903.439173278532, 5918.0, 5594.8810237953385, 5722.656640601577, 5809.535237180931, 5813.79651712188, 5813.854653633693, 5737.225474560881, 5739.942600573941, 5755.971220143872, 5757.395473475225, 5757.683327417032, 5689.118186681071, 5705.971470142623, 5719.942800571941, 5724.771009159587, 5734.426557344233, 5535.72678284005, 5721.656700597978, 5760.222369979999, 5774.0, 5774.007967251292, 5607.906458240604, 5611.3266408031295, 5616.9047035827625, 5634.126710359901, 5639.4642508963225], "value": 4263.585904830598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.1568894815969, 808.7371604228623, 816.3836303590788, 851.987220191697, 905.4431524612365, 15685.607859803511, 17477.0, 17607.29570817169, 17611.55971100723, 17706.468805935827, 15672.608184795386, 15868.206589670528, 16149.677006459893, 16182.19089045546, 16189.35242590298, 862.1809281182885, 889.3596610440505, 890.8574628059512, 890.9153630405101, 891.3404080980093, 874.654511467973, 875.6059773102098, 877.2630989968425, 878.3544095090103, 878.4553576782404, 918.8805455290803, 921.2123634292684, 925.4586067150725, 925.7037747920668, 926.2219735422244, 910.7677542226722, 917.3991035871514, 918.1369512658114, 918.7611221082533, 919.4115765909823, 685.4208194075998, 685.4379408884716, 685.4961603221628, 685.9348361905611, 685.9519833611649, 15327.386904523806, 15362.385504579803, 15397.692046159125, 15413.075215487106, 15419.922900385427, 14175.387514770213, 14215.715685686228, 14238.715225695429, 14296.714065718732, 14466.638334041629, 13732.244726540037, 13827.930860345634, 13859.930700346535, 13945.16329020262, 14325.212113333762, 4707.270373092176, 4778.522147785228, 4813.3983252093485, 5012.523810238022, 5021.046001259785, 51996.74001629968, 65513.344866551204, 67996.0, 68690.3130968689, 83736.16263837405, 12168.513259469699, 12431.440585173688, 12516.561920332744, 12778.872211277954, 12820.871791282154, 22053.558928821654, 22106.557868842534, 22299.219527316465, 22463.88768056182, 22484.0, 8448.366372522085, 8617.741467755977, 8706.782330441789, 8724.389292749447, 8970.955145224363, 4584.587387135174, 4593.908121837545, 4727.834525791581, 4849.442314133849, 4899.461059283476, 3744.64425879541, 3893.7469064510715, 3922.588128246493, 3924.7056470764805, 3925.2738243425056, 654.8722999015241, 660.3858411677186, 662.4038365471115, 662.8243515468405, 673.0308355967392, 632.34552238433, 644.3878315600199, 709.9183593886665, 712.8895021271711, 748.8951546783454, 9495.0, 10010.899891000996, 10084.445355505371, 10135.239857010754, 10475.423851688078, 671.1107782188619, 700.9859802803916, 717.153758564888, 738.966746496409, 744.5941961630924, 4478.41780568524, 4676.906461870743, 4696.2955556666775, 4732.361131247307, 4823.782929768169, 72473.6376318115, 76265.0, 88721.55639221762, 133261.0, 133501.3324933369, 106490.46754766177, 106548.46725766321, 107924.46037769761, 110376.0, 114129.42935285436, 62933.68533157305, 94954.0, 98777.50611246898, 99196.0, 102556.48721756194, 8894.28845692356, 9160.541972901461, 9184.908150918673, 9206.723798286062, 9228.261739060994, 39286.41070384004, 47838.52161478341, 48660.0, 52086.0, 58355.416445836694, 47722.52277477181, 49240.753796232224, 57357.0, 64179.0, 76947.0, 13900.374483148486, 14317.212553309668, 14394.20831854258, 14405.639859003608, 14527.418903243752, 13696.452141914211, 13982.510612128526, 14098.718025639635, 14149.292535372984, 14424.639384015063, 13949.58151255464, 14225.786613201017, 14528.055676381002, 14812.703745924806, 15025.098494090389, 13718.245496497788, 13914.234717090658, 14292.14247145133, 14353.85646143567, 15004.099754014795, 13077.869221307665, 13210.933945330213, 13221.933890330487, 13250.204987700768, 13323.33383330849, 11845.822312665488, 11882.940585297018, 11980.28118312904, 12005.219660722025, 12154.817677735018, 11948.522059117538, 12030.09774266916, 12061.336626485627, 12133.150679452354, 12303.753924921273], "value": 7836.849318973993, "unit": "score"}], "name": "dromaeo_css", "value": 7557.7234909012905, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 120.0, 109.0, 111.0, 110.0, 111.0, 110.0, 111.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 142.0, 164.0, 118.0, 146.0, 150.0, 116.0, 176.0, 121.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 162.0, 161.0, 162.0, 160.0, 159.0, 164.0, 161.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 169.0, 76.0, 74.0, 74.0, 75.0, 170.0, 76.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 151.0, 84.0, 84.0, 85.0, 140.0, 84.0, 84.0, 149.0], "value": 102.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 118.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 53.0, 52.0, 54.0, 52.0, 50.0, 51.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 74.0, 78.0, 74.0, 72.0, 72.0, 73.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 119.0, 118.0, 118.0, 117.0, 117.0, 127.0, 116.0, 150.0, 125.0], "value": 123.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 156.0, 155.0, 153.0, 154.0, 161.0, 153.0, 150.0, 151.0, 152.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 68.0, 61.0, 63.0, 62.0, 63.0, 62.0, 60.0, 60.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1492.7999999999997, "alertThreshold": 2.0}]} 14:48:39 INFO - Return code: 0 14:48:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:48:39 INFO - # TBPL SUCCESS # 14:48:39 INFO - Running post-action listener: _resource_record_post_action 14:48:39 INFO - [mozharness: 2016-09-20 21:48:39.116746Z] Finished run-tests step (success) 14:48:39 INFO - Running post-run listener: _resource_record_post_run 14:48:39 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 471453696; Read time: 12; Write time: 482864 14:48:39 INFO - TinderboxPrint: CPU usage
13.0% 14:48:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:48:39 INFO - TinderboxPrint: I/O write bytes / time
471,453,696 / 482,864 14:48:39 INFO - TinderboxPrint: CPU idle
8,239.7 (87.0%) 14:48:39 INFO - TinderboxPrint: CPU user
1,187.4 (12.5%) 14:48:39 INFO - TinderboxPrint: Swap in / out
0 / 0 14:48:39 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 239284224; Read time: 0; Write time: 337916 14:48:39 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 223240192; Read time: 12; Write time: 141900 14:48:39 INFO - Running post-run listener: _upload_blobber_files 14:48:39 INFO - Blob upload gear active. 14:48:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:48:39 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 14:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:48:40 INFO - (blobuploader) - INFO - Open directory for files ... 14:48:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:48:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:48:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:44 INFO - (blobuploader) - INFO - Done attempting. 14:48:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:48:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:48:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:45 INFO - (blobuploader) - INFO - Done attempting. 14:48:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:48:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:48:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:46 INFO - (blobuploader) - INFO - Done attempting. 14:48:46 INFO - (blobuploader) - INFO - Iteration through files over. 14:48:46 INFO - Return code: 0 14:48:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:48:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:48:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4f29f43c12ae25e97d8f1891f1190fbcfe753150dd112a5fa1c067c93554bf9247753087fb6e6f0a0d2a4c40587da1c14afb52d875218c29f38f879c4e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86b45b5674343e7726d1b3af4d6cf94cdfcf6e557d777c8d22c73a1e91091bd68c5aec0a12a1c6918e8e2f67243034f27fc56eecffa378d0d14fa0bf3082e025", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad187524e835ddcc6121f390510c8fcc6071e56110e763459154648810683628615d25a22a15d87dbc8e2c1878a2c35373835ffbae7df39c3eb53a52bd3d956"} 14:48:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:48:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:48:46 INFO - Contents: 14:48:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4f29f43c12ae25e97d8f1891f1190fbcfe753150dd112a5fa1c067c93554bf9247753087fb6e6f0a0d2a4c40587da1c14afb52d875218c29f38f879c4e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86b45b5674343e7726d1b3af4d6cf94cdfcf6e557d777c8d22c73a1e91091bd68c5aec0a12a1c6918e8e2f67243034f27fc56eecffa378d0d14fa0bf3082e025", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad187524e835ddcc6121f390510c8fcc6071e56110e763459154648810683628615d25a22a15d87dbc8e2c1878a2c35373835ffbae7df39c3eb53a52bd3d956"} 14:48:46 INFO - Running post-run listener: copy_logs_to_upload_dir 14:48:46 INFO - Copying logs to upload dir... 14:48:46 INFO - mkdir: /builds/slave/test/build/upload/logs 14:48:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1222.012895 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 22 secs) (at 2016-09-20 14:48:45.806783) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:48:45.810158) ========= 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=52faae308a556c440401f8e5000001e8-1474406902.611537-297648870 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4f29f43c12ae25e97d8f1891f1190fbcfe753150dd112a5fa1c067c93554bf9247753087fb6e6f0a0d2a4c40587da1c14afb52d875218c29f38f879c4e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86b45b5674343e7726d1b3af4d6cf94cdfcf6e557d777c8d22c73a1e91091bd68c5aec0a12a1c6918e8e2f67243034f27fc56eecffa378d0d14fa0bf3082e025", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad187524e835ddcc6121f390510c8fcc6071e56110e763459154648810683628615d25a22a15d87dbc8e2c1878a2c35373835ffbae7df39c3eb53a52bd3d956"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4f29f43c12ae25e97d8f1891f1190fbcfe753150dd112a5fa1c067c93554bf9247753087fb6e6f0a0d2a4c40587da1c14afb52d875218c29f38f879c4e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86b45b5674343e7726d1b3af4d6cf94cdfcf6e557d777c8d22c73a1e91091bd68c5aec0a12a1c6918e8e2f67243034f27fc56eecffa378d0d14fa0bf3082e025", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad187524e835ddcc6121f390510c8fcc6071e56110e763459154648810683628615d25a22a15d87dbc8e2c1878a2c35373835ffbae7df39c3eb53a52bd3d956"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:48:45.840900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:48:45.841668) ========= 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=52faae308a556c440401f8e5000001e8-1474406902.611537-297648870 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004511 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:48:45.926065) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:48:45.926841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:48:45.927573) ========= ========= Total master_lag: 0.16 =========