builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-066 starttime: 1469598258.7 results: success (0) buildid: 20160726221437 builduid: 55afeffe6e494658bc890842afac59e5 revision: c1ac662342a28384bedc60ed979fa23ddef0af1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:18.699107) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:18.699687) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:18.699959) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:18.714287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:18.714591) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 22:44:19-- 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% 37.9M=0s 2016-07-26 22:44:20 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.320226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.048619) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.048924) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016031 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.098132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.098510) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c1ac662342a28384bedc60ed979fa23ddef0af1e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c1ac662342a28384bedc60ed979fa23ddef0af1e --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=82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 22:44:20,231 truncating revision to first 12 chars 2016-07-26 22:44:20,231 Setting DEBUG logging. 2016-07-26 22:44:20,231 attempt 1/10 2016-07-26 22:44:20,231 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c1ac662342a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 22:44:20,549 unpacking tar archive at: mozilla-inbound-c1ac662342a2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.684838 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.796571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.796886) ========= script_repo_revision: c1ac662342a28384bedc60ed979fa23ddef0af1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.797309) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.797571) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:44:19.806685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-07-26 22:44:19.806967) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:44:21 INFO - MultiFileLogger online at 20160726 22:44:21 in /builds/slave/test 22:44:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:44:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:44:21 INFO - {'append_to_log': False, 22:44:21 INFO - 'base_work_dir': '/builds/slave/test', 22:44:21 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:44:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:44:21 INFO - 'branch': 'Mozilla-Inbound', 22:44:21 INFO - 'buildbot_json_path': 'buildprops.json', 22:44:21 INFO - 'config_files': ('talos/linux_config.py',), 22:44:21 INFO - 'default_actions': ('clobber', 22:44:21 INFO - 'read-buildbot-config', 22:44:21 INFO - 'download-and-extract', 22:44:21 INFO - 'populate-webroot', 22:44:21 INFO - 'create-virtualenv', 22:44:21 INFO - 'install', 22:44:21 INFO - 'run-tests'), 22:44:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:44:21 INFO - 'download_minidump_stackwalk': True, 22:44:21 INFO - 'download_symbols': 'ondemand', 22:44:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:44:21 INFO - 'tooltool.py': '/tools/tooltool.py', 22:44:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:44:21 INFO - '/tools/misc-python/virtualenv.py')}, 22:44:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:44:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:44:21 INFO - 'installer_path': 'installer.exe', 22:44:21 INFO - 'log_level': 'info', 22:44:21 INFO - 'log_name': 'talos', 22:44:21 INFO - 'log_to_console': True, 22:44:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:44:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:44:21 INFO - 'opt_config_files': (), 22:44:21 INFO - 'pip_index': False, 22:44:21 INFO - 'sps_profile': False, 22:44:21 INFO - 'sps_profile_interval': 0, 22:44:21 INFO - 'suite': 'dromaeojs-e10s', 22:44:21 INFO - 'system_bits': '32', 22:44:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:44:21 INFO - 'title': 'talos-linux64-ix-066', 22:44:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:44:21 INFO - 'use_talos_json': True, 22:44:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:44:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:44:21 INFO - 'work_dir': 'build'} 22:44:21 INFO - [mozharness: 2016-07-27 05:44:21.027752Z] Running clobber step. 22:44:21 INFO - Running pre-action listener: _resource_record_pre_action 22:44:21 INFO - Running main action method: clobber 22:44:21 INFO - rmtree: /builds/slave/test/build 22:44:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:44:22 INFO - Running post-action listener: _resource_record_post_action 22:44:22 INFO - [mozharness: 2016-07-27 05:44:22.023088Z] Finished clobber step (success) 22:44:22 INFO - [mozharness: 2016-07-27 05:44:22.023190Z] Running read-buildbot-config step. 22:44:22 INFO - Running pre-action listener: _resource_record_pre_action 22:44:22 INFO - Running main action method: read_buildbot_config 22:44:22 INFO - Using buildbot properties: 22:44:22 INFO - { 22:44:22 INFO - "project": "", 22:44:22 INFO - "product": "firefox", 22:44:22 INFO - "script_repo_revision": "production", 22:44:22 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:44:22 INFO - "repository": "", 22:44:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:44:22 INFO - "buildid": "20160726221437", 22:44:22 INFO - "stage_platform": "linux64-pgo", 22:44:22 INFO - "basedir": "/builds/slave/test", 22:44:22 INFO - "buildnumber": 913, 22:44:22 INFO - "platform": "ubuntu64_hw", 22:44:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:44:22 INFO - "slavebuilddir": "test", 22:44:22 INFO - "branch": "mozilla-inbound", 22:44:22 INFO - "revision": "c1ac662342a28384bedc60ed979fa23ddef0af1e", 22:44:22 INFO - "slavename": "talos-linux64-ix-066", 22:44:22 INFO - "builduid": "55afeffe6e494658bc890842afac59e5", 22:44:22 INFO - "repo_path": "integration/mozilla-inbound" 22:44:22 INFO - } 22:44:22 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:44:22 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.test_packages.json. 22:44:22 INFO - Running post-action listener: _resource_record_post_action 22:44:22 INFO - [mozharness: 2016-07-27 05:44:22.025550Z] Finished read-buildbot-config step (success) 22:44:22 INFO - [mozharness: 2016-07-27 05:44:22.025638Z] Running download-and-extract step. 22:44:22 INFO - Running pre-action listener: _resource_record_pre_action 22:44:22 INFO - Running main action method: download_and_extract 22:44:22 INFO - mkdir: /builds/slave/test/build/tests 22:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:44:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:44:22 INFO - Downloaded 1361 bytes. 22:44:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:44:22 INFO - Using the following test package requirements: 22:44:22 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:44:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:44:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:44:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'jsshell-linux-x86_64.zip'], 22:44:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:44:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:44:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:44:22 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:44:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:44:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:44:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:44:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:44:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:44:22 INFO - Downloaded 24315477 bytes. 22:44:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:44:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:44:23 INFO - Return code: 0 22:44:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:44:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:44:24 INFO - Downloaded 24315477 bytes. 22:44:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:44:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:44:25 INFO - Return code: 0 22:44: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')]} 22:44:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:44:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:44:25 INFO - Downloaded 14413725 bytes. 22:44:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:44:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:44:26 INFO - Return code: 0 22:44: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')]} 22:44:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:44:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:44:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:44:26 INFO - Downloaded 61001987 bytes. 22:44:26 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:44:26 INFO - mkdir: /builds/slave/test/properties 22:44:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:44:26 INFO - Writing to file /builds/slave/test/properties/build_url 22:44:26 INFO - Contents: 22:44:26 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:44:26 INFO - Running post-action listener: _resource_record_post_action 22:44:26 INFO - Running post-action listener: set_extra_try_arguments 22:44:26 INFO - [mozharness: 2016-07-27 05:44:26.956418Z] Finished download-and-extract step (success) 22:44:26 INFO - [mozharness: 2016-07-27 05:44:26.956653Z] Running populate-webroot step. 22:44:26 INFO - Running pre-action listener: _resource_record_pre_action 22:44:26 INFO - Running main action method: populate_webroot 22:44:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:44:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:44:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:44:26 INFO - '--tppagecycles', 22:44:26 INFO - '7'], 22:44:26 INFO - 'tests': ['tsvgm']}}, 22:44:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:44:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:44:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:44:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:44:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:44:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:44:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['damp', 'tps']}, 22:44:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'tests': ['damp', 'tps']}, 22:44:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['dromaeo_dom']}, 22:44:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:44:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['basic_compositor_video']}, 22:44:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:44:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['a11yr', 22:44:26 INFO - 'ts_paint', 22:44:26 INFO - 'tpaint', 22:44:26 INFO - 'sessionrestore', 22:44:26 INFO - 'sessionrestore_no_auto_restore', 22:44:26 INFO - 'tabpaint']}, 22:44:26 INFO - 'other-e10s': {'tests': ['a11yr', 22:44:26 INFO - 'ts_paint', 22:44:26 INFO - 'tpaint', 22:44:26 INFO - 'sessionrestore', 22:44:26 INFO - 'sessionrestore_no_auto_restore', 22:44:26 INFO - 'tabpaint']}, 22:44:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:44:26 INFO - 'ts_paint', 22:44:26 INFO - 'tpaint', 22:44:26 INFO - 'sessionrestore', 22:44:26 INFO - 'sessionrestore_no_auto_restore', 22:44:26 INFO - 'tabpaint']}, 22:44:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:44:26 INFO - 'ts_paint', 22:44:26 INFO - 'tpaint', 22:44:26 INFO - 'sessionrestore', 22:44:26 INFO - 'sessionrestore_no_auto_restore', 22:44:26 INFO - 'tabpaint']}, 22:44:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['a11yr', 22:44:26 INFO - 'ts_paint', 22:44:26 INFO - 'tpaint', 22:44:26 INFO - 'sessionrestore', 22:44:26 INFO - 'sessionrestore_no_auto_restore', 22:44:26 INFO - 'tabpaint']}, 22:44:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['a11yr', 22:44:26 INFO - 'ts_paint', 22:44:26 INFO - 'tpaint', 22:44:26 INFO - 'sessionrestore', 22:44:26 INFO - 'sessionrestore_no_auto_restore', 22:44:26 INFO - 'tabpaint']}, 22:44:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['tsvgx', 22:44:26 INFO - 'tsvgr_opacity', 22:44:26 INFO - 'tart', 22:44:26 INFO - 'tscrollx', 22:44:26 INFO - 'cart']}, 22:44:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:44:26 INFO - 'tsvgr_opacity', 22:44:26 INFO - 'tart', 22:44:26 INFO - 'tscrollx', 22:44:26 INFO - 'cart']}, 22:44:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'talos_options': ['--disable-e10s'], 22:44:26 INFO - 'tests': ['tp5o']}, 22:44:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'tests': ['tp5o']}, 22:44:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'talos_options': ['--disable-e10s', 22:44:26 INFO - '--xperf_path', 22:44:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:26 INFO - 'tests': ['tp5n']}, 22:44:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:26 INFO - 'talos_options': ['--xperf_path', 22:44:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:26 INFO - 'tests': ['tp5n']}}, 22:44:26 INFO - 'talos.zip': {'path': '', 22:44:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:44:26 INFO - Running post-action listener: _resource_record_post_action 22:44:26 INFO - [mozharness: 2016-07-27 05:44:26.975814Z] Finished populate-webroot step (success) 22:44:26 INFO - [mozharness: 2016-07-27 05:44:26.975903Z] Running create-virtualenv step. 22:44:26 INFO - Running pre-action listener: _resource_record_pre_action 22:44:26 INFO - Running main action method: create_virtualenv 22:44:26 INFO - Creating virtualenv /builds/slave/test/build/venv 22:44:26 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 22:44:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:44:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:44:27 INFO - Using real prefix '/usr' 22:44:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:44:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:44:29 INFO - Installing pip.................done. 22:44:29 INFO - Return code: 0 22:44:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:44:29 INFO - Using env: {'DISPLAY': ':0', 22:44:29 INFO - 'HOME': '/home/cltbld', 22:44:29 INFO - 'LANG': 'en_US.UTF-8', 22:44:29 INFO - 'LANGUAGE': 'en_US:en', 22:44:29 INFO - 'LOGNAME': 'cltbld', 22:44:29 INFO - 'MAIL': '/var/mail/cltbld', 22:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:29 INFO - 'MOZ_NO_REMOTE': '1', 22:44:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:29 INFO - 'NO_EM_RESTART': '1', 22:44:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:29 INFO - 'PWD': '/builds/slave/test', 22:44:29 INFO - 'SHELL': '/bin/bash', 22:44:29 INFO - 'SHLVL': '1', 22:44:29 INFO - 'TERM': 'linux', 22:44:29 INFO - 'TMOUT': '86400', 22:44:29 INFO - 'USER': 'cltbld', 22:44:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:29 INFO - '_': '/tools/buildbot/bin/python'} 22:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:29 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 22:44:29 INFO - Cleaning up... 22:44:29 INFO - Return code: 0 22:44:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:44:29 INFO - Using env: {'DISPLAY': ':0', 22:44:29 INFO - 'HOME': '/home/cltbld', 22:44:29 INFO - 'LANG': 'en_US.UTF-8', 22:44:29 INFO - 'LANGUAGE': 'en_US:en', 22:44:29 INFO - 'LOGNAME': 'cltbld', 22:44:29 INFO - 'MAIL': '/var/mail/cltbld', 22:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:29 INFO - 'MOZ_NO_REMOTE': '1', 22:44:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:29 INFO - 'NO_EM_RESTART': '1', 22:44:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:29 INFO - 'PWD': '/builds/slave/test', 22:44:29 INFO - 'SHELL': '/bin/bash', 22:44:29 INFO - 'SHLVL': '1', 22:44:29 INFO - 'TERM': 'linux', 22:44:29 INFO - 'TMOUT': '86400', 22:44:29 INFO - 'USER': 'cltbld', 22:44:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:29 INFO - '_': '/tools/buildbot/bin/python'} 22:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:29 INFO - Downloading/unpacking psutil>=3.1.1 22:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:44:31 INFO - Installing collected packages: psutil 22:44:31 INFO - Running setup.py install for psutil 22:44:32 INFO - building 'psutil._psutil_linux' extension 22:44:32 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 22:44:32 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 22:44:32 INFO - building 'psutil._psutil_posix' extension 22:44:32 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 22:44:32 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 22:44:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:44:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:44:32 INFO - Successfully installed psutil 22:44:32 INFO - Cleaning up... 22:44:32 INFO - Return code: 0 22:44:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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')]} 22:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:44: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 mozsystemmonitor==0.3 22:44:32 INFO - Using env: {'DISPLAY': ':0', 22:44:32 INFO - 'HOME': '/home/cltbld', 22:44:32 INFO - 'LANG': 'en_US.UTF-8', 22:44:32 INFO - 'LANGUAGE': 'en_US:en', 22:44:32 INFO - 'LOGNAME': 'cltbld', 22:44:32 INFO - 'MAIL': '/var/mail/cltbld', 22:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:32 INFO - 'MOZ_NO_REMOTE': '1', 22:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:32 INFO - 'NO_EM_RESTART': '1', 22:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:32 INFO - 'PWD': '/builds/slave/test', 22:44:32 INFO - 'SHELL': '/bin/bash', 22:44:32 INFO - 'SHLVL': '1', 22:44:32 INFO - 'TERM': 'linux', 22:44:32 INFO - 'TMOUT': '86400', 22:44:32 INFO - 'USER': 'cltbld', 22:44:32 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:32 INFO - '_': '/tools/buildbot/bin/python'} 22:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:44:34 INFO - Installing collected packages: mozsystemmonitor 22:44:34 INFO - Running setup.py install for mozsystemmonitor 22:44:34 INFO - Successfully installed mozsystemmonitor 22:44:34 INFO - Cleaning up... 22:44:34 INFO - Return code: 0 22:44:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:44:34 INFO - Using env: {'DISPLAY': ':0', 22:44:34 INFO - 'HOME': '/home/cltbld', 22:44:34 INFO - 'LANG': 'en_US.UTF-8', 22:44:34 INFO - 'LANGUAGE': 'en_US:en', 22:44:34 INFO - 'LOGNAME': 'cltbld', 22:44:34 INFO - 'MAIL': '/var/mail/cltbld', 22:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:34 INFO - 'MOZ_NO_REMOTE': '1', 22:44:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:34 INFO - 'NO_EM_RESTART': '1', 22:44:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:34 INFO - 'PWD': '/builds/slave/test', 22:44:34 INFO - 'SHELL': '/bin/bash', 22:44:34 INFO - 'SHLVL': '1', 22:44:34 INFO - 'TERM': 'linux', 22:44:34 INFO - 'TMOUT': '86400', 22:44:34 INFO - 'USER': 'cltbld', 22:44:34 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:34 INFO - '_': '/tools/buildbot/bin/python'} 22:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:35 INFO - Downloading/unpacking jsonschema==2.5.1 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:44:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:37 INFO - Downloading functools32-3.2.3-2.tar.gz 22:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:44:37 INFO - warning: no files found matching '*.txt' 22:44:37 INFO - no previously-included directories found matching 'build' 22:44:37 INFO - no previously-included directories found matching 'dist' 22:44:37 INFO - no previously-included directories found matching '.git*' 22:44:37 INFO - Installing collected packages: jsonschema, functools32 22:44:37 INFO - Running setup.py install for functools32 22:44:37 INFO - warning: no files found matching '*.txt' 22:44:37 INFO - no previously-included directories found matching 'build' 22:44:37 INFO - no previously-included directories found matching 'dist' 22:44:37 INFO - no previously-included directories found matching '.git*' 22:44:37 INFO - Successfully installed jsonschema functools32 22:44:37 INFO - Cleaning up... 22:44:37 INFO - Return code: 0 22:44:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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')]} 22:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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', '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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:44: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 functools32==3.2.3-2 22:44:37 INFO - Using env: {'DISPLAY': ':0', 22:44:37 INFO - 'HOME': '/home/cltbld', 22:44:37 INFO - 'LANG': 'en_US.UTF-8', 22:44:37 INFO - 'LANGUAGE': 'en_US:en', 22:44:37 INFO - 'LOGNAME': 'cltbld', 22:44:37 INFO - 'MAIL': '/var/mail/cltbld', 22:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:37 INFO - 'MOZ_NO_REMOTE': '1', 22:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:37 INFO - 'NO_EM_RESTART': '1', 22:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:37 INFO - 'PWD': '/builds/slave/test', 22:44:37 INFO - 'SHELL': '/bin/bash', 22:44:37 INFO - 'SHLVL': '1', 22:44:37 INFO - 'TERM': 'linux', 22:44:37 INFO - 'TMOUT': '86400', 22:44:37 INFO - 'USER': 'cltbld', 22:44:37 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:37 INFO - '_': '/tools/buildbot/bin/python'} 22:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:44:37 INFO - Cleaning up... 22:44:37 INFO - Return code: 0 22:44:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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')]} 22:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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', '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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:44: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 blobuploader==1.2.4 22:44:37 INFO - Using env: {'DISPLAY': ':0', 22:44:37 INFO - 'HOME': '/home/cltbld', 22:44:37 INFO - 'LANG': 'en_US.UTF-8', 22:44:37 INFO - 'LANGUAGE': 'en_US:en', 22:44:37 INFO - 'LOGNAME': 'cltbld', 22:44:37 INFO - 'MAIL': '/var/mail/cltbld', 22:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:37 INFO - 'MOZ_NO_REMOTE': '1', 22:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:37 INFO - 'NO_EM_RESTART': '1', 22:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:37 INFO - 'PWD': '/builds/slave/test', 22:44:37 INFO - 'SHELL': '/bin/bash', 22:44:37 INFO - 'SHLVL': '1', 22:44:37 INFO - 'TERM': 'linux', 22:44:37 INFO - 'TMOUT': '86400', 22:44:37 INFO - 'USER': 'cltbld', 22:44:37 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:37 INFO - '_': '/tools/buildbot/bin/python'} 22:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:37 INFO - Downloading/unpacking blobuploader==1.2.4 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44:39 INFO - Downloading blobuploader-1.2.4.tar.gz 22:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:44:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:44:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44: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 22:44:40 INFO - Downloading docopt-0.6.1.tar.gz 22:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:44:40 INFO - Installing collected packages: blobuploader, requests, docopt 22:44:40 INFO - Running setup.py install for blobuploader 22:44:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:44:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:44:40 INFO - Running setup.py install for requests 22:44:41 INFO - Running setup.py install for docopt 22:44:41 INFO - Successfully installed blobuploader requests docopt 22:44:41 INFO - Cleaning up... 22:44:41 INFO - Return code: 0 22:44:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:41 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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:41 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 22:44:41 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 22:44:41 INFO - Using env: {'DISPLAY': ':0', 22:44:41 INFO - 'HOME': '/home/cltbld', 22:44:41 INFO - 'LANG': 'en_US.UTF-8', 22:44:41 INFO - 'LANGUAGE': 'en_US:en', 22:44:41 INFO - 'LOGNAME': 'cltbld', 22:44:41 INFO - 'MAIL': '/var/mail/cltbld', 22:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:41 INFO - 'MOZ_NO_REMOTE': '1', 22:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:41 INFO - 'NO_EM_RESTART': '1', 22:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:41 INFO - 'PWD': '/builds/slave/test', 22:44:41 INFO - 'SHELL': '/bin/bash', 22:44:41 INFO - 'SHLVL': '1', 22:44:41 INFO - 'TERM': 'linux', 22:44:41 INFO - 'TMOUT': '86400', 22:44:41 INFO - 'USER': 'cltbld', 22:44:41 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:41 INFO - '_': '/tools/buildbot/bin/python'} 22:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:44:41 INFO - Running setup.py (path:/tmp/pip-lnvCBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:44:41 INFO - Running setup.py (path:/tmp/pip-XOcdlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:44:41 INFO - Running setup.py (path:/tmp/pip-S9S5aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:44:41 INFO - Running setup.py (path:/tmp/pip-cZqqwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:44:41 INFO - Running setup.py (path:/tmp/pip-6hIw7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:44:41 INFO - Running setup.py (path:/tmp/pip-iSU3Vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:44:41 INFO - Running setup.py (path:/tmp/pip-4aeReA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:44:41 INFO - Running setup.py (path:/tmp/pip-zt6xIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:44:41 INFO - Running setup.py (path:/tmp/pip-1URAFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:44:42 INFO - Running setup.py (path:/tmp/pip-OTevor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:44:42 INFO - Running setup.py (path:/tmp/pip-M9zgPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:44:42 INFO - Running setup.py (path:/tmp/pip-iYddIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:44:42 INFO - Running setup.py (path:/tmp/pip-XaOA5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:44:42 INFO - Running setup.py (path:/tmp/pip-NKXCTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:42 INFO - Running setup.py (path:/tmp/pip-S_8BC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:44:42 INFO - Running setup.py (path:/tmp/pip-KQMYMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:44:42 INFO - Running setup.py (path:/tmp/pip-MxvBr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:44:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:44:42 INFO - Running setup.py install for manifestparser 22:44:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:44:42 INFO - Running setup.py install for mozcrash 22:44:42 INFO - Running setup.py install for mozdebug 22:44:42 INFO - Running setup.py install for mozdevice 22:44:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for mozfile 22:44:43 INFO - Running setup.py install for mozhttpd 22:44:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for mozinfo 22:44:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for mozInstall 22:44:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for mozleak 22:44:43 INFO - Running setup.py install for mozlog 22:44:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for moznetwork 22:44:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for mozprocess 22:44:43 INFO - Running setup.py install for mozprofile 22:44:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:44:43 INFO - Running setup.py install for mozrunner 22:44:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:44:44 INFO - Running setup.py install for mozscreenshot 22:44:44 INFO - Running setup.py install for moztest 22:44:44 INFO - Running setup.py install for mozversion 22:44:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:44:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:44:44 INFO - Cleaning up... 22:44:44 INFO - Return code: 0 22:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:44 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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:44 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 22:44:44 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 22:44:44 INFO - Using env: {'DISPLAY': ':0', 22:44:44 INFO - 'HOME': '/home/cltbld', 22:44:44 INFO - 'LANG': 'en_US.UTF-8', 22:44:44 INFO - 'LANGUAGE': 'en_US:en', 22:44:44 INFO - 'LOGNAME': 'cltbld', 22:44:44 INFO - 'MAIL': '/var/mail/cltbld', 22:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:44 INFO - 'MOZ_NO_REMOTE': '1', 22:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:44 INFO - 'NO_EM_RESTART': '1', 22:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:44 INFO - 'PWD': '/builds/slave/test', 22:44:44 INFO - 'SHELL': '/bin/bash', 22:44:44 INFO - 'SHLVL': '1', 22:44:44 INFO - 'TERM': 'linux', 22:44:44 INFO - 'TMOUT': '86400', 22:44:44 INFO - 'USER': 'cltbld', 22:44:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:44 INFO - '_': '/tools/buildbot/bin/python'} 22:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:44:44 INFO - Running setup.py (path:/tmp/pip-O1Up4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:44:44 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)) 22:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:44:44 INFO - Running setup.py (path:/tmp/pip-Nif2v_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:44:44 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)) 22:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:44:44 INFO - Running setup.py (path:/tmp/pip-mj6HzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:44:44 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)) 22:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:44:44 INFO - Running setup.py (path:/tmp/pip-aYXO2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:44:44 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)) 22:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:44:44 INFO - Running setup.py (path:/tmp/pip-NbwY1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:44:45 INFO - Running setup.py (path:/tmp/pip-6q9UqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:44:45 INFO - Running setup.py (path:/tmp/pip-MP0HQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:44:45 INFO - Running setup.py (path:/tmp/pip-2l7zks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:44:45 INFO - Running setup.py (path:/tmp/pip-n9c2Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:44:45 INFO - Running setup.py (path:/tmp/pip-NgaNNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:44:45 INFO - Running setup.py (path:/tmp/pip-TbYqRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:44:45 INFO - Running setup.py (path:/tmp/pip-8th5r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:44:45 INFO - Running setup.py (path:/tmp/pip-smizYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:44:45 INFO - Running setup.py (path:/tmp/pip-GIJ7TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:45 INFO - Running setup.py (path:/tmp/pip-KErusy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:44:45 INFO - Running setup.py (path:/tmp/pip-_sR4tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:44:45 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)) 22:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:44:45 INFO - Running setup.py (path:/tmp/pip-FtnaXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:44:45 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)) 22:44:45 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)) 22:44:45 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)) 22:44:45 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)) 22:44:45 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)) 22:44:45 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)) 22:44:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:44:45 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 22:44:45 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 22:44:45 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 22:44:45 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 22:44:45 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 22:44:45 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 22:44:47 INFO - Downloading blessings-1.6.tar.gz 22:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:44:47 INFO - Installing collected packages: blessings 22:44:47 INFO - Running setup.py install for blessings 22:44:47 INFO - Successfully installed blessings 22:44:47 INFO - Cleaning up... 22:44:48 INFO - Return code: 0 22:44:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:48 INFO - Reading from file tmpfile_stdout 22:44:48 INFO - Current package versions: 22:44:48 INFO - argparse == 1.2.1 22:44:48 INFO - blessings == 1.6 22:44:48 INFO - blobuploader == 1.2.4 22:44:48 INFO - docopt == 0.6.1 22:44:48 INFO - functools32 == 3.2.3-2 22:44:48 INFO - jsonschema == 2.5.1 22:44:48 INFO - manifestparser == 1.1 22:44:48 INFO - mozInstall == 1.12 22:44:48 INFO - mozcrash == 0.17 22:44:48 INFO - mozdebug == 0.1 22:44:48 INFO - mozdevice == 0.48 22:44:48 INFO - mozfile == 1.2 22:44:48 INFO - mozhttpd == 0.7 22:44:48 INFO - mozinfo == 0.9 22:44:48 INFO - mozleak == 0.1 22:44:48 INFO - mozlog == 3.3 22:44:48 INFO - moznetwork == 0.27 22:44:48 INFO - mozprocess == 0.23 22:44:48 INFO - mozprofile == 0.28 22:44:48 INFO - mozrunner == 6.12 22:44:48 INFO - mozscreenshot == 0.1 22:44:48 INFO - mozsystemmonitor == 0.3 22:44:48 INFO - moztest == 0.7 22:44:48 INFO - mozversion == 1.4 22:44:48 INFO - psutil == 3.1.1 22:44:48 INFO - requests == 1.2.3 22:44:48 INFO - wsgiref == 0.1.2 22:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:48 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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:48 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 22:44:48 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 22:44:48 INFO - Using env: {'DISPLAY': ':0', 22:44:48 INFO - 'HOME': '/home/cltbld', 22:44:48 INFO - 'LANG': 'en_US.UTF-8', 22:44:48 INFO - 'LANGUAGE': 'en_US:en', 22:44:48 INFO - 'LOGNAME': 'cltbld', 22:44:48 INFO - 'MAIL': '/var/mail/cltbld', 22:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:48 INFO - 'MOZ_NO_REMOTE': '1', 22:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:48 INFO - 'NO_EM_RESTART': '1', 22:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:48 INFO - 'PWD': '/builds/slave/test', 22:44:48 INFO - 'SHELL': '/bin/bash', 22:44:48 INFO - 'SHLVL': '1', 22:44:48 INFO - 'TERM': 'linux', 22:44:48 INFO - 'TMOUT': '86400', 22:44:48 INFO - 'USER': 'cltbld', 22:44:48 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:48 INFO - '_': '/tools/buildbot/bin/python'} 22:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 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)) 22:44:48 INFO - Cleaning up... 22:44:48 INFO - Return code: 0 22:44:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:48 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 0x1129030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1358400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, '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': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', '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 22:44:48 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 22:44:48 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 22:44:48 INFO - Using env: {'DISPLAY': ':0', 22:44:48 INFO - 'HOME': '/home/cltbld', 22:44:48 INFO - 'LANG': 'en_US.UTF-8', 22:44:48 INFO - 'LANGUAGE': 'en_US:en', 22:44:48 INFO - 'LOGNAME': 'cltbld', 22:44:48 INFO - 'MAIL': '/var/mail/cltbld', 22:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:48 INFO - 'MOZ_NO_REMOTE': '1', 22:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:48 INFO - 'NO_EM_RESTART': '1', 22:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:48 INFO - 'PWD': '/builds/slave/test', 22:44:48 INFO - 'SHELL': '/bin/bash', 22:44:48 INFO - 'SHLVL': '1', 22:44:48 INFO - 'TERM': 'linux', 22:44:48 INFO - 'TMOUT': '86400', 22:44:48 INFO - 'USER': 'cltbld', 22:44:48 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:48 INFO - '_': '/tools/buildbot/bin/python'} 22:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:44:48 INFO - Cleaning up... 22:44:48 INFO - Return code: 0 22:44:48 INFO - Running post-action listener: _resource_record_post_action 22:44:48 INFO - Running post-action listener: _start_resource_monitoring 22:44:48 INFO - Starting resource monitoring. 22:44:48 INFO - [mozharness: 2016-07-27 05:44:48.726905Z] Finished create-virtualenv step (success) 22:44:48 INFO - [mozharness: 2016-07-27 05:44:48.727339Z] Running install step. 22:44:48 INFO - Running pre-action listener: _resource_record_pre_action 22:44:48 INFO - Running main action method: install 22:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:48 INFO - Reading from file tmpfile_stdout 22:44:48 INFO - Detecting whether we're running mozinstall >=1.0... 22:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:44:49 INFO - Reading from file tmpfile_stdout 22:44:49 INFO - Output received: 22:44:49 INFO - Usage: mozinstall [options] installer 22:44:49 INFO - Options: 22:44:49 INFO - -h, --help show this help message and exit 22:44:49 INFO - -d DEST, --destination=DEST 22:44:49 INFO - Directory to install application into. [default: 22:44:49 INFO - "/builds/slave/test"] 22:44:49 INFO - --app=APP Application being installed. [default: firefox] 22:44:49 INFO - mkdir: /builds/slave/test/build/application 22:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:45:06 INFO - Reading from file tmpfile_stdout 22:45:06 INFO - Output received: 22:45:06 INFO - /builds/slave/test/build/application/firefox/firefox 22:45:06 INFO - Running post-action listener: _resource_record_post_action 22:45:06 INFO - [mozharness: 2016-07-27 05:45:06.332391Z] Finished install step (success) 22:45:06 INFO - [mozharness: 2016-07-27 05:45:06.332666Z] Running run-tests step. 22:45:06 INFO - Running pre-action listener: _resource_record_pre_action 22:45:06 INFO - Running main action method: run_tests 22:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:45:06 INFO - Python 2.7.3 22:45:06 INFO - Return code: 0 22:45:06 INFO - grabbing minidump binary from tooltool 22:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:06 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 0x13e7570>, '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 0x13e8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e8900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:45:06 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 22:45:06 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 22:45:06 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 22:45:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:45:06 INFO - Return code: 0 22:45:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:45:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:45:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:45:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:45:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:45:06 INFO - Using env: {'DISPLAY': ':0', 22:45:06 INFO - 'HOME': '/home/cltbld', 22:45:06 INFO - 'LANG': 'en_US.UTF-8', 22:45:06 INFO - 'LANGUAGE': 'en_US:en', 22:45:06 INFO - 'LOGNAME': 'cltbld', 22:45:06 INFO - 'MAIL': '/var/mail/cltbld', 22:45:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:45:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:06 INFO - 'MOZ_NO_REMOTE': '1', 22:45:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:45:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:45:06 INFO - 'NO_EM_RESTART': '1', 22:45:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:06 INFO - 'PWD': '/builds/slave/test', 22:45:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:45:06 INFO - 'SHELL': '/bin/bash', 22:45:06 INFO - 'SHLVL': '1', 22:45:06 INFO - 'TERM': 'linux', 22:45:06 INFO - 'TMOUT': '86400', 22:45:06 INFO - 'USER': 'cltbld', 22:45:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215', 22:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:06 INFO - '_': '/tools/buildbot/bin/python'} 22:45:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:45:06 INFO - mozversion application_buildid: 20160726193100 22:45:06 INFO - mozversion application_changeset: c1ac662342a28384bedc60ed979fa23ddef0af1e 22:45:06 INFO - mozversion application_display_name: Nightly 22:45:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:45:06 INFO - mozversion application_name: Firefox 22:45:06 INFO - mozversion application_remotingname: firefox 22:45:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:45:06 INFO - mozversion application_vendor: Mozilla 22:45:06 INFO - mozversion application_version: 50.0a1 22:45:06 INFO - mozversion platform_buildid: 20160726193100 22:45:06 INFO - mozversion platform_changeset: c1ac662342a28384bedc60ed979fa23ddef0af1e 22:45:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:45:06 INFO - mozversion platform_version: 50.0a1 22:45:06 INFO - using testdate: 1469598306 22:45:06 INFO - actual date: 1469598306 22:45:06 INFO - starting webserver on 'localhost:39194' 22:45:06 INFO - SUITE-START | Running 2 tests 22:45:06 INFO - TEST-START | dromaeo_css 22:45:06 INFO - operating with platform_type : linux_ 22:45:06 INFO - Initialising browser for dromaeo_css test... 22:45:06 INFO - TEST-INFO | started process 19795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK28Jl_/profile http://localhost:39194/getInfo.html) 22:45:09 INFO - PROCESS | 19795 | JavaScript error: , line 0: uncaught exception: undefined 22:45:11 INFO - PROCESS | 19795 | __metrics Screen width/height:1600/1200 22:45:11 INFO - PROCESS | 19795 | colorDepth:24 22:45:11 INFO - PROCESS | 19795 | Browser inner width/height: 1024/697 22:45:11 INFO - PROCESS | 19795 | __metrics 22:45:12 ERROR - PROCESS | 19795 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 22:45:12 INFO - TEST-INFO | 19795: exit 0 22:45:12 INFO - Browser initialized. 22:45:12 INFO - Running cycle 1/1 for dromaeo_css test... 22:45:12 INFO - TEST-INFO | started process 19913 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK28Jl_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:45:24 INFO - PROCESS | 19913 | RSS: Main: 164827136 22:45:24 INFO - PROCESS | 19913 | 22:46:14 INFO - PROCESS | 19913 | 1469598374352 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] 22:48:22 INFO - PROCESS | 19913 | Cycle 1(1): loaded http://localhost:39194/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39194/tests/dromaeo/cssquery-ext.html) 22:48:23 INFO - PROCESS | 19913 | RSS: Main: 170582016 22:48:23 INFO - PROCESS | 19913 | 22:51:25 INFO - PROCESS | 19913 | Cycle 1(1): loaded http://localhost:39194/tests/dromaeo/cssquery-ext.html (next: http://localhost:39194/tests/dromaeo/cssquery-jquery.html) 22:51:26 INFO - PROCESS | 19913 | RSS: Main: 167694336 22:51:26 INFO - PROCESS | 19913 | 22:54:28 INFO - PROCESS | 19913 | Cycle 1(1): loaded http://localhost:39194/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39194/tests/dromaeo/cssquery-mootools.html) 22:54:28 INFO - PROCESS | 19913 | RSS: Main: 171454464 22:54:28 INFO - PROCESS | 19913 | 22:57:30 INFO - PROCESS | 19913 | Cycle 1(1): loaded http://localhost:39194/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39194/tests/dromaeo/cssquery-prototype.html) 22:57:31 INFO - PROCESS | 19913 | RSS: Main: 166076416 22:57:31 INFO - PROCESS | 19913 | 23:00:33 INFO - PROCESS | 19913 | Cycle 1(1): loaded http://localhost:39194/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39194/tests/dromaeo/cssquery-yui.html) 23:00:34 INFO - PROCESS | 19913 | RSS: Main: 167538688 23:00:34 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | Cycle 1(1): loaded http://localhost:39194/tests/dromaeo/cssquery-yui.html (next: http://localhost:39194/tests/dromaeo/cssquery-dojo.html) 23:03:37 INFO - PROCESS | 19913 | RSS: Main: 170917888 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | __start_tp_report 23:03:37 INFO - PROCESS | 19913 | _x_x_mozilla_page_load 23:03:37 INFO - PROCESS | 19913 | _x_x_mozilla_page_load_details 23:03:37 INFO - PROCESS | 19913 | |i|pagename|runs| 23:03:37 INFO - PROCESS | 19913 | |0;dojo.html;3147.1974646465155;3153.6530981592005;3179.1893067267843;3186.665400132986;3188.7767856250057;5405.486478784519;5556.416576259495;5581.609287349887;5589.357223919251;5654.802081927133;5440.183972404141;5512.173174023898;5523.143912693539;5525.613207075507;5552.833414997546;4961.255811628258;5042.47054059324;5054.7472626368535;5066.164082926312;5117.692938423706;5077.847664570068;5086;5265.236540701596;5270.183121616155;5270.525652691257;5085.593152547787;5103.923441148386;5165.199394093903;5170.53465188133;5171.146760784485;4910.705357678551;4939.3578834751615;4939.604831613462;4976.278439626234;5005.424376196747;4882.048000639877;4905.705657660516;4908.9754551227015;4917.483664215278;4987.6758010729545;5408.891822163574;5445.972770136124;5454.318210223722;5471.261379713767;5504.009278329899;5842.649441033509;5893.14549390336;5907.852303692399;5923.614965027303;5934.851628709273;5937.762489500458;5941;5955.523558115384;5961;5970.402959704038;5473.917891231635;5557.666540007613;5558.360788509332;5562.527185189264;5570.303712036036;6092.969535152296;6120.751898419022;6122.794777235106;6125.479334256549;6127.80043271483;5875.3243377012195;5879.441453061952;5885.47030767233;5888.293404791452;5927.022041363213;5706.914396284059;5950.256217972754;5959.225300710967;5969.73136208863;5972.522198224132;5734.139879018181;5738.024535828919;5817.563682723812;5827.24245848037;5875.50058245054;5899.764009439575;5963.4334738199805;5996.37038110992;5998.610090344114;6022.969885150546;4521;4529.048899731093;4540.296254080623;4541.795619197144;4580.1068791585985;3979.46277252573;4003.3794761811964;4052.0882801370044;4052.878413647595;4073.857414990461;5645.745941432645;5649.237352957381;5694.743736531866;5695.601307908407;5738.110592858114;6003.669798161056;6026.337102918676;6029.065494848305;6035.517158627299;6046.153538504613;5426.403095659474;5557.861053473695;5631.042722737145;5646;5675.091985282335;5121.923171152435;5130.717810520383;5142.922856157161;5144;5152.227165925066;6119.571629985859;6122.326544080148;6156.815295541141;6157.9384206158265;6160.229971253593;5817.825465236049;5858.9414105860105;5878.265216847894;5972.134040564007;5992.640441573432;6123.969380153071;6153.4154255345675;6157;6159.5688301818445;6191.380861913773;5861.736221870111;5879.5002424794375;5880.970595147168;5883.499902508334;5912.172295878667;5839.766409343749;5935.0503919372695;5967.582269241285;5968.313643931004;6006.849828754316;5859.00396932514;5884.382139875252;5892.558058145571;5906.468417842415;5921.259842519685;5806.216160818404;5833.620814647034;5888.823335299948;5891.675957822361;5892.970535147297;5517.586181036519;5757.424257574209;5772.336181339032;5855.414458554111;5855.970720146372;5932.228810254639;5933.614315069507;5939.46544810969;5963.821085367446;5974.402559744165;5842.328132264844;5846.56150788684;5850.0932355484965;5855.2095467113095;5863.296404431325 23:03:37 INFO - PROCESS | 19913 | |1;ext.html;555.9733818223361;572.1617829879227;586.5190543754123;613.9938600613993;617.657200253859;40247;45299.54700452999;45317.32024019642;45421;45465.044772089874;44306.77846610762;44598.55401445985;44814.32778508325;44853.7757311213;44952.3257151143;8753.080926502706;8824.73525794224;8848.070952549986;8870.822583548324;8888.511131887744;8803.867941980876;8881.689140880071;8883.11168883113;8883.822323553524;8892.822143557125;8853.981792093895;8901;8938.195562399384;8946.686865959693;8954.462732236087;8825.293976481866;8876.068012858654;8901.287896968228;8919.821603567925;8927.419717718327;8870.73387798367;8898.955505222497;8927.910720892773;8932.106789321017;8943.373963822534;41848.37227441591;41911.580884191375;42026.78986605078;42253.36619950703;42368.36447453291;3662.6520480554304;3683.7053035757076;3867.9226415471535;4153.501579810412;4223.450951376332;32707;32876.50685239724;33075.173120672174;33410.99767006969;34746.305073898635;4459.665525085632;4493.213687604656;4524.343970124346;4525.660575456821;4653.232216684243;357016;357834;358528.9999999974;358562;358634;57987.42012579904;58584.41415585875;58629.4137058624;58802.705986470646;58879.70560147172;34945.47581786275;62024.37975620186;62093.37906620876;62245.37754622396;62269.68865155736;14826.777598336033;15311.310991005432;15337.386504539916;15364.308606112752;15377.692446151239;11323.943380283046;11397.088232941345;11398.430078496041;11407;11423.485943132579;19813.801861981196;19970.90014549918;20009.499762506053;20054.29809956674;20087.698684519743;7295.52579082358;7332.743353982585;7484.438667099989;7529.661165247577;7569.3566046886635;953.4612943686933;957.7031120352713;1002.8946960569181;1047.7694907120422;1078.703356576932;33701;33800.1549961248;34042.659573404446;34103.488447673306;34431;1064;1106.247751528952;1130.0846314485293;1156.5662876421343;1205.0419916166668;17644.28494135286;17664.205110769788;17709.557261068578;17728.29086836538;17759.822401776077;621118;622421;622741;625136;626348;61774;62091.689541552005;62655.37344626495;62696.373036269055;62877.68561157165;1297.772889744291;1340.919544827294;1356.8507464178933;1365.4538184726189;1371.5953793630904;31426.371472569965;32068.03795886127;32424.513632296006;33166.834165829016;33350.16624584405;47600.28599571079;47610.28584571165;47785.76107119442;53477;53592.46407536031;488320;489818;491287;492805;492986;28671.56992645154;28693;28767.280817979718;28774.856125720074;29091.85454072716;49669.75165124151;49698.503014969385;50929.490705092474;51490.48509514857;51629.48370516247;42030;43378.56621433745;43913.34129987989;44365.33451998158;44421.77789111163;42299.57700423079;42401.36397954095;43004.56995430006;43036.35445468383;43993.340099899164;42152.36771448369;42398.576014239465;43143.56856431396;43464.13071738484;44900.77549612231;33266.33467330688;38295.80852095722;39144;39196.608033920435;39576.40635390529;29330;29504.409911801213;29519;29675.406491870475;29888.70111298859 23:03:37 INFO - PROCESS | 19913 | |2;jquery.html;367.8638903605668;378.52873172899746;385.3199103582178;391.18047690089253;395.42268288299084;15421.92289038556;17849.19678614462;18777.718334225;19707.408777736655;20745.481362965915;17757.28970841168;18491.90754046231;19812.603747925034;19986.60026799467;20036.69944950827;5719.571032172583;5881.059030555112;5925.31858836235;5929.762809487637;5949.988501954678;5430.266913966623;5685.118806584986;5807.680577568232;5814.186013958049;5851.970740146315;5669.6314739541795;5699.743511541969;5751.539876809845;5760.193572899798;5778.971105144468;5370.597205209606;5589.552835773128;5632.0707083331;5741.397153298885;5746;5520.420355862617;5541.5843811714285;5550.750216240279;5551.833444996657;5567.192757050205;19205.231790728354;22695.319140425814;22796;23000.4249893754;23306;2348.894299756498;2401;2663.3474798674256;2829.122971878724;2844.7581955533806;9773.755656108584;9966.501674916226;9967;10042.89957100427;10175.13511351536;1842.3367587668563;1907.83783378413;1922.0966145911539;1928.7492625958675;1947.1627200303863;81521.59239203825;82885.58557207236;84726;85120.57439712762;86156.13843861544;10525.579046828734;10639.734006649896;11115.666530004113;11157.218994670295;11634.592789252336;11695.473703683354;12384.504619815107;14770;14840.480583179537;14931.253437328307;7113.395361394236;7132.857342853217;7184.976599523115;7195.208527062019;7409.518381305198;7097.290270972861;7340.669669864869;7425.294597013275;7463.79817953705;7661.387089032864;3799.5787119767506;3867.8839634811;3897.979066223699;3899.532056153223;4075.816588253536;1924.7594050743658;1927.8264956154014;1943.5238366600274;1969.7734760502726;1970.1232951336517;957.200737384278;972.1687956796978;975.5219942228399;999.4453078541479;1033.7674023344687;18923.81076189248;19535.902320488305;19667.311644092388;19667.803321966596;19903.701444478345;958.1855422890487;972.7179118055826;977.2475194100502;986.7730422002836;1043.3531210649446;10238.488075596191;10904;10952.561897524169;10987.61543345979;11044.171687123498;464168;467252;497077;501806;504436;80969;99178;100204.49897750464;101398;101820;993.2649839119144;1014.1481155829139;1050.1808589300206;1057.9153667706564;1062.2139616683598;16921.238544265292;17560.28496079086;17873.73189402134;18111.275548977894;18148.364807231683;41090.383644244765;41094.794526028374;41206.587934120274;41377.793111034254;42390.78804605957;54313;55318.44681553294;59030.40969590249;59108.704456479165;59472.40527594669;8049.2755651989255;8336.29141522953;8404;8405.579721013803;8599.699010534601;12873;12989.155704879153;13253.337333133111;13601.251931143883;13781.655458613615;9503.904960950578;9583.760405989906;11093.667189984313;11612.883871161519;11798.410079496163;11303.265287756269;11431.885681143081;11475.196736228718;11609.30344179352;11871.940640296743;11346.602868899548;11726;11781.175317727677;11790.292582445081;12140.75718485643;8334.83330333402;8391.790205244917;8396.748097557083;8425.915740842758;8663.826723465621;8670;11211.439428028729;11316.49075791575;11624.593139240085;12106.515739370327 23:03:37 INFO - PROCESS | 19913 | |3;mootools.html;1448.7899254608083;1472.2417954753303;1596.162014942155;1736.930522779089;1739.913004349782;7504.737334193291;8623.180797824205;8665.263452606536;9160.450372977617;9164.770880727976;9042.32182586307;9100.590473428694;9103.408278461895;9104.226140778026;9124.543772811367;2388.904443822245;2418.2503423938633;2424.9515009699794;2427.9514409711796;2434.0628857889665;2324.779145981129;2379.976200237993;2387.248016874679;2387.319613910036;2387.5344307859973;2334.8015418689433;2348.6007378745653;2365.9053637854463;2366.7278262999803;2367.3726462487452;2265.592193405186;2274.999999999992;2275.84069115162;2289.198780426854;2333.7899589036897;2276.1919518570835;2283.5204607032542;2301.0795681727277;2304.055337311711;2334.0197117210814;8884.37809353339;8954.149355811187;8996.685116020974;9069.410488318239;9075.092490750938;5022.146235140035;5227.424983251839;5230.189320655304;5286.550643195333;5304.92042619361;3286.7863588866885;3376.1390845334527;3400.3199360128074;3428.708559772422;3437.759356845022;1140.0822338017892;1181.078758568318;1186.0926391310654;1203.6990752311922;1204.7289359894041;12210.450529726184;12253.081018923616;12292.8156077658;12336.629901102891;12348.75302493954;4679.812807487662;4702.600278976325;4713.257487810506;4795.568398844051;4905.264210368473;10460.058594726512;10487.632932847313;10491.318064325795;10494.317869338469;10526.789464210673;5353.063213937546;5366.758495867695;5390.272313237741;5397.109476936262;5398.1902714593125;4225.767582782915;4723.976380118077;4785.736784476887;4835.6131509479155;4949.554540091409;4026.59734026595;4110.445089912823;4120.938185927213;4149.2738770715005;4169.291220492463;1260.7352455984162;1260.9117361784588;1291.6770807298176;1298.337847697662;1300.4213125159213;903.7559858838208;984.4585477987079;993.2749093162032;1025.2823023883311;1031.3090229546222;6112.847178820476;6268.1851359323;6404.519661025442;6454.4513716333695;6581.04574836646;450.6417398168464;452.2741000693905;452.86414075777134;456.97943592538417;459.4876712465577;4421;4473.038296766211;4532.0482698632995;4571.085782843445;4616.446026476844;10148.528463372917;10289.897101029044;10343.534540945675;10411.375317480975;10418.114460270961;10523.10553602937;10900.236983411243;10969.506372213269;11333.659990200307;11387.487563059682;8280.668773249003;8426.609609414501;8436.36727245459;8439.113893041265;8467.364947628952;6126.295476020315;6344.555881088464;6454.870902581828;6505.2518960318275;6616.106825578485;9718.12536871685;9951.601935922483;9968.750781230527;10014.499275036073;10151.593936242467;10331.173506119341;10467.685969420929;10487.10859576914;10516.947415263181;10521.579136834442;7081.256468070985;7723.806904827425;7812.3750099993;7826.334761545332;7967.2431119043595;8813.515256660945;8815.294776417742;8865.335099867407;8881.866771998297;8902.643894244417;7871.960640196762;7891.408144389308;7910;8104.392170587347;8189.877151842847;7800.453968222397;7888.723894663659;7966.681332746625;7974.720884769005;7984.640691169027;7306.817329566803;7323.780286591411;7407.296306850841;7413.962930185315;7425.7029718810645;5488.286522752017;5604.075327570905;5621.74702138413;5689;5719.999000175036;5324.148136298174;5549.445055494418;5555.3611334697025;5660.037793575019;5796.681182535001 23:03:37 INFO - PROCESS | 19913 | |4;prototype.html;323.2952164281866;327.9721223695986;330.8560776062413;336.9511420843976;337.3910092283429;5518.862028449291;5647.491725744682;5681.755706495932;5752.539796816261;5762.106873434619;5621.465960733734;5657.57568182387;5661.8584535386635;5664.490195882369;5674.2339784129235;4394.516603173648;4396.274614688573;4400.317950717628;4402.515723270438;4404.326355984721;4161.500619925598;4337.219300525904;4348.934765978513;4398;4416.7191514460765;4279.1441711657635;4321.351797230409;4340.544242854487;4351.75794033444;4352.499462561815;4326;4333.155034768221;4340.088581397895;4354.3438502513445;4389.692721509496;3749.7562658427114;3775.6601905828334;3825.024618722208;3830.2339532093692;3830.7893065881367;5453.473027552292;5455.972720136414;5458.153986132156;5550.16747487876;5571.387147413781;5017.7491125443585;5027.019731152427;5027.245913113025;5030.195168772979;5043.420006699203;5753.539716822645;5921.437463440966;5947.851303717442;5952.7023648817385;5968.597379615787;4150.8547200847825;4152.584741525853;4159.396887451333;4180.101278225196;4208.116295577933;6180.783672571439;6204.6587437690905;6244;6249.937500624981;6251.718672659741;4135.441715368447;4208.305629571087;4231.809568569361;4253.574642535722;4271.466066741657;4274.4443222381515;4298.20483210607;4299;4311.8706438806885;4330.783460826946;4976.90046199074;4980.103581355391;4987.501249875056;5001.974990125026;5024.32171656829;5871.4715675589405;5875.441833025856;5896.498797602252;5985.551083668743;6020.277566692025;1464.7509923313062;1475.8671719545293;1482.2070192446988;1482.5700546841294;1485.2944851195596;896.3814967672286;925.4447331600985;932.4451951089092;935.0229010683852;943.9528023598792;5056.898862022739;5063.151949538392;5074.264231686385;5098.4646612105935;5129.9743501282255;5383.757730902119;5425.945740542623;5446.537044351196;5450.501112194178;5484.862878428071;5011.917670632608;5084.313617661643;5113.565346945476;5357.383900851374;5788.80025590273;3266.2977459846247;3290.5393244945726;3300.3234336960863;3302.702756751904;3306.2560923791953;6082.452579267887;6358.872822543523;6376.855044221201;6389.424951754271;6401.64790936503;6198.876022479525;6227.629921417281;6229.53278504114;6240.469560087353;6289;6309.526785491106;6316.778442994014;6350.936490635035;6373.490120790326;6376.003278459117;5897.941020589739;6073.908891366545;6084.361142080018;6107.84730381744;6127.540434467343;6047.667378294236;6053.727582258721;6082.756689732538;6092.086187071975;6118.60229085108;5999.940000599938;6259.561830671992;6329.588576742497;6332.556721029484;6370.93629063722;4231.534531201627;4256.87229383119;4268.530461649154;4295.377170310225;4309.439772829513;5981.192539007178;6004.519638429003;6192.938070619416;6214.689265536796;6259.749610015549;5894.646321220741;5909.43399998992;5917.289925209003;5924.259467566554;5930.584859059824;5968.134620479919;5976.223090998316;5984.551158663205;5991.760329586942;6026.819195424145;5730.598858079894;5861.472467477947;5869;5878.823635290948;5941.197938278277;6036.486898613492;6063;6068.302145253176;6071.2714474263375;6071.392860713893;5707.771689132388;5838;5841.4450627190345;5872.882542349043;5952.76188952437 23:03:37 INFO - PROCESS | 19913 | |5;yui.html;783.2950344689779;823.4112609484217;838.1518751153707;840.4915026409022;840.5083026429536;15812.20938953052;16551.668966620662;16596.33614655414;16624.168791560434;16671.333146674133;14195.574132776008;14248.216348100876;14277.214753188571;14280.857191428082;14295.857041429583;630.7350912616712;760.8668483015477;773.489496932025;787.6337503061076;788.5781107107698;782.4131901074194;783.2285199078902;788.2826627768732;789.9526028438283;793.0245797668871;841.4824882697159;842.1410161635159;842.9578521073953;845.0450990380891;847.737201467547;837.9329653627725;838.0613712641849;839.6683310092541;840.9327253819741;842.806154584449;612.9816105516842;616.1374076293181;616.1804799616499;616.374380004296;616.6300219868089;15390.461333853373;15602.453914112952;15611.921940390339;15626.687466250725;15651.217439127999;15557;15632.374705011785;15779.842201577952;15784.131872746995;15907.363705451768;12254.20347677398;12425.689357766129;12442.626721198289;12498.81251781215;12616.936915315457;3964.5638979712207;3972.543157536891;3982.741121827101;4015.0765323975356;4091.4476545666253;75225.24774752237;76301;76764.61617691931;77758;78305.82541261942;11793.351365674971;11817.940910295394;12006.219595726425;12031.819522707166;12100.636980890587;22182.223622173467;22613.095476181097;22794;22844.771552284597;22939;8393.118722534167;8481.787955301166;8518.957405212934;8544.401891867634;8739.563021848882;4838.225883858636;4945.604351651859;4960.305557221992;4977.576905963034;5280.287161233185;3465.4801779733243;3552.1474846036767;3556.608773034964;3560.7151427886224;3566.144125409883;591.8905002574537;608.4402349838159;608.6104892868568;619.2012304127625;627.0374974414343;678.7352932356386;712.568895818033;721.2030706069822;724.4648500387611;795.4034474144391;9820.165285950632;10005.49972501372;10030.849537256947;10240.231982601335;10467.057964783206;610.716017052073;738.8152961759561;744.5607091815855;746.3842330077707;760.7869796457001;4964.7836280111605;5003.149464591028;5021.221710634857;5081.323163356084;5103.974480127576;99535.50232248793;108297.4585127085;109236;112557;115272;97968;98438;99458.502707486;100044;100215;53484.73257633687;54224;83554;85818;89930.10069899217;9072.95463522678;9156.908430915606;9197.310201734763;9225.313420908155;9367.297452690938;53896.73051634717;54045;55925.72037139788;55942;56197;44909.32636010397;61151;72127.27872721206;72505;72663;11464.484098215433;11606.071514278668;11799.76400472003;12078.698032549257;12097.03223742081;12626.684332891384;12823.294718790183;12870.09909306377;13102.148360356547;13186.868131318564;13102.868971310165;13227.272500012592;13328.400221990227;13463.192208467523;13863.237521935987;12959.546415875777;13448.596542103753;13588.59234222975;13844.584662460142;14112.50606228777;12905.483780648668;12981.740365192833;13272.933635331761;13313.267770272729;13361.13152645075;9868.407895526292;10152.289339746147;10323.56171327205;10424.42665653366;10467.16262698967;9446.763830904283;10746.24776265654;10746.83879741789;10823.242373034127;10850.131989440668 23:03:37 INFO - PROCESS | 19913 | __end_tp_report 23:03:37 INFO - PROCESS | 19913 | __start_cc_report 23:03:37 INFO - PROCESS | 19913 | _x_x_mozilla_cycle_collect,325 23:03:37 INFO - PROCESS | 19913 | __end_cc_report 23:03:37 INFO - PROCESS | 19913 | __startTimestamp1469599417461__endTimestamp 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | ------- Summary: start ------- 23:03:37 INFO - PROCESS | 19913 | Number of tests: 6 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | [#0] dojo.html Cycles:165 Average:5527.22 Median:5817.56 stddev:641.14 (11.0%) stddev-sans-first:615.32 23:03:37 INFO - PROCESS | 19913 | Values: 3147.2 3153.7 3179.2 3186.7 3188.8 5405.5 5556.4 5581.6 5589.4 5654.8 5440.2 5512.2 5523.1 5525.6 5552.8 4961.3 5042.5 5054.7 5066.2 5117.7 5077.8 5086.0 5265.2 5270.2 5270.5 5085.6 5103.9 5165.2 5170.5 5171.1 4910.7 4939.4 4939.6 4976.3 5005.4 4882.0 4905.7 4909.0 4917.5 4987.7 5408.9 5446.0 5454.3 5471.3 5504.0 5842.6 5893.1 5907.9 5923.6 5934.9 5937.8 5941.0 5955.5 5961.0 5970.4 5473.9 5557.7 5558.4 5562.5 5570.3 6093.0 6120.8 6122.8 6125.5 6127.8 5875.3 5879.4 5885.5 5888.3 5927.0 5706.9 5950.3 5959.2 5969.7 5972.5 5734.1 5738.0 5817.6 5827.2 5875.5 5899.8 5963.4 5996.4 5998.6 6023.0 4521.0 4529.0 4540.3 4541.8 4580.1 3979.5 4003.4 4052.1 4052.9 4073.9 5645.7 5649.2 5694.7 5695.6 5738.1 6003.7 6026.3 6029.1 6035.5 6046.2 5426.4 5557.9 5631.0 5646.0 5675.1 5121.9 5130.7 5142.9 5144.0 5152.2 6119.6 6122.3 6156.8 6157.9 6160.2 5817.8 5858.9 5878.3 5972.1 5992.6 6124.0 6153.4 6157.0 6159.6 6191.4 5861.7 5879.5 5881.0 5883.5 5912.2 5839.8 5935.1 5967.6 5968.3 6006.8 5859.0 5884.4 5892.6 5906.5 5921.3 5806.2 5833.6 5888.8 5891.7 5893.0 5517.6 5757.4 5772.3 5855.4 5856.0 5932.2 5933.6 5939.5 5963.8 5974.4 5842.3 5846.6 5850.1 5855.2 5863.3 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | [#1] ext.html Cycles:180 Average:64913.93 Median:43953.34 stddev:133875.98 (304.6%) stddev-sans-first:134164.33 23:03:37 INFO - PROCESS | 19913 | Values: 556.0 572.2 586.5 614.0 617.7 40247.0 45299.5 45317.3 45421.0 45465.0 44306.8 44598.6 44814.3 44853.8 44952.3 8753.1 8824.7 8848.1 8870.8 8888.5 8803.9 8881.7 8883.1 8883.8 8892.8 8854.0 8901.0 8938.2 8946.7 8954.5 8825.3 8876.1 8901.3 8919.8 8927.4 8870.7 8899.0 8927.9 8932.1 8943.4 41848.4 41911.6 42026.8 42253.4 42368.4 3662.7 3683.7 3867.9 4153.5 4223.5 32707.0 32876.5 33075.2 33411.0 34746.3 4459.7 4493.2 4524.3 4525.7 4653.2 357016.0 357834.0 358529.0 358562.0 358634.0 57987.4 58584.4 58629.4 58802.7 58879.7 34945.5 62024.4 62093.4 62245.4 62269.7 14826.8 15311.3 15337.4 15364.3 15377.7 11323.9 11397.1 11398.4 11407.0 11423.5 19813.8 19970.9 20009.5 20054.3 20087.7 7295.5 7332.7 7484.4 7529.7 7569.4 953.5 957.7 1002.9 1047.8 1078.7 33701.0 33800.2 34042.7 34103.5 34431.0 1064.0 1106.2 1130.1 1156.6 1205.0 17644.3 17664.2 17709.6 17728.3 17759.8 621118.0 622421.0 622741.0 625136.0 626348.0 61774.0 62091.7 62655.4 62696.4 62877.7 1297.8 1340.9 1356.9 1365.5 1371.6 31426.4 32068.0 32424.5 33166.8 33350.2 47600.3 47610.3 47785.8 53477.0 53592.5 488320.0 489818.0 491287.0 492805.0 492986.0 28671.6 28693.0 28767.3 28774.9 29091.9 49669.8 49698.5 50929.5 51490.5 51629.5 42030.0 43378.6 43913.3 44365.3 44421.8 42299.6 42401.4 43004.6 43036.4 43993.3 42152.4 42398.6 43143.6 43464.1 44900.8 33266.3 38295.8 39144.0 39196.6 39576.4 29330.0 29504.4 29519.0 29675.4 29888.7 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | [#2] jquery.html Cycles:180 Average:28925.84 Median:2092.45 stddev:80567.19 (3850.4%) stddev-sans-first:80764.67 23:03:37 INFO - PROCESS | 19913 | Values: 367.9 378.5 385.3 391.2 395.4 15421.9 17849.2 18777.7 19707.4 20745.5 17757.3 18491.9 19812.6 19986.6 20036.7 5719.6 5881.1 5925.3 5929.8 5950.0 5430.3 5685.1 5807.7 5814.2 5852.0 5669.6 5699.7 5751.5 5760.2 5779.0 5370.6 5589.6 5632.1 5741.4 5746.0 5520.4 5541.6 5550.8 5551.8 5567.2 19205.2 22695.3 22796.0 23000.4 23306.0 2348.9 2401.0 2663.3 2829.1 2844.8 9773.8 9966.5 9967.0 10042.9 10175.1 1842.3 1907.8 1922.1 1928.7 1947.2 81521.6 82885.6 84726.0 85120.6 86156.1 10525.6 10639.7 11115.7 11157.2 11634.6 11695.5 12384.5 14770.0 14840.5 14931.3 7113.4 7132.9 7185.0 7195.2 7409.5 7097.3 7340.7 7425.3 7463.8 7661.4 3799.6 3867.9 3898.0 3899.5 4075.8 1924.8 1927.8 1943.5 1969.8 1970.1 957.2 972.2 975.5 999.4 1033.8 18923.8 19535.9 19667.3 19667.8 19903.7 958.2 972.7 977.2 986.8 1043.4 10238.5 10904.0 10952.6 10987.6 11044.2 464168.0 467252.0 497077.0 501806.0 504436.0 80969.0 99178.0 100204.5 101398.0 101820.0 993.3 1014.1 1050.2 1057.9 1062.2 16921.2 17560.3 17873.7 18111.3 18148.4 41090.4 41094.8 41206.6 41377.8 42390.8 54313.0 55318.4 59030.4 59108.7 59472.4 8049.3 8336.3 8404.0 8405.6 8599.7 12873.0 12989.2 13253.3 13601.3 13781.7 9503.9 9583.8 11093.7 11612.9 11798.4 11303.3 11431.9 11475.2 11609.3 11871.9 11346.6 11726.0 11781.2 11790.3 12140.8 8334.8 8391.8 8396.7 8425.9 8663.8 8670.0 11211.4 11316.5 11624.6 12106.5 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | [#3] mootools.html Cycles:180 Average:5911.31 Median:4691.21 stddev:3327.70 (70.9%) stddev-sans-first:3320.13 23:03:37 INFO - PROCESS | 19913 | Values: 1448.8 1472.2 1596.2 1736.9 1739.9 7504.7 8623.2 8665.3 9160.5 9164.8 9042.3 9100.6 9103.4 9104.2 9124.5 2388.9 2418.3 2425.0 2428.0 2434.1 2324.8 2380.0 2387.2 2387.3 2387.5 2334.8 2348.6 2365.9 2366.7 2367.4 2265.6 2275.0 2275.8 2289.2 2333.8 2276.2 2283.5 2301.1 2304.1 2334.0 8884.4 8954.1 8996.7 9069.4 9075.1 5022.1 5227.4 5230.2 5286.6 5304.9 3286.8 3376.1 3400.3 3428.7 3437.8 1140.1 1181.1 1186.1 1203.7 1204.7 12210.5 12253.1 12292.8 12336.6 12348.8 4679.8 4702.6 4713.3 4795.6 4905.3 10460.1 10487.6 10491.3 10494.3 10526.8 5353.1 5366.8 5390.3 5397.1 5398.2 4225.8 4724.0 4785.7 4835.6 4949.6 4026.6 4110.4 4120.9 4149.3 4169.3 1260.7 1260.9 1291.7 1298.3 1300.4 903.8 984.5 993.3 1025.3 1031.3 6112.8 6268.2 6404.5 6454.5 6581.0 450.6 452.3 452.9 457.0 459.5 4421.0 4473.0 4532.0 4571.1 4616.4 10148.5 10289.9 10343.5 10411.4 10418.1 10523.1 10900.2 10969.5 11333.7 11387.5 8280.7 8426.6 8436.4 8439.1 8467.4 6126.3 6344.6 6454.9 6505.3 6616.1 9718.1 9951.6 9968.8 10014.5 10151.6 10331.2 10467.7 10487.1 10516.9 10521.6 7081.3 7723.8 7812.4 7826.3 7967.2 8813.5 8815.3 8865.3 8881.9 8902.6 7872.0 7891.4 7910.0 8104.4 8189.9 7800.5 7888.7 7966.7 7974.7 7984.6 7306.8 7323.8 7407.3 7414.0 7425.7 5488.3 5604.1 5621.7 5689.0 5720.0 5324.1 5549.4 5555.4 5660.0 5796.7 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | [#4] prototype.html Cycles:180 Average:4944.77 Median:5634.48 stddev:1480.12 (26.3%) stddev-sans-first:1443.05 23:03:37 INFO - PROCESS | 19913 | Values: 323.3 328.0 330.9 337.0 337.4 5518.9 5647.5 5681.8 5752.5 5762.1 5621.5 5657.6 5661.9 5664.5 5674.2 4394.5 4396.3 4400.3 4402.5 4404.3 4161.5 4337.2 4348.9 4398.0 4416.7 4279.1 4321.4 4340.5 4351.8 4352.5 4326.0 4333.2 4340.1 4354.3 4389.7 3749.8 3775.7 3825.0 3830.2 3830.8 5453.5 5456.0 5458.2 5550.2 5571.4 5017.7 5027.0 5027.2 5030.2 5043.4 5753.5 5921.4 5947.9 5952.7 5968.6 4150.9 4152.6 4159.4 4180.1 4208.1 6180.8 6204.7 6244.0 6249.9 6251.7 4135.4 4208.3 4231.8 4253.6 4271.5 4274.4 4298.2 4299.0 4311.9 4330.8 4976.9 4980.1 4987.5 5002.0 5024.3 5871.5 5875.4 5896.5 5985.6 6020.3 1464.8 1475.9 1482.2 1482.6 1485.3 896.4 925.4 932.4 935.0 944.0 5056.9 5063.2 5074.3 5098.5 5130.0 5383.8 5425.9 5446.5 5450.5 5484.9 5011.9 5084.3 5113.6 5357.4 5788.8 3266.3 3290.5 3300.3 3302.7 3306.3 6082.5 6358.9 6376.9 6389.4 6401.6 6198.9 6227.6 6229.5 6240.5 6289.0 6309.5 6316.8 6350.9 6373.5 6376.0 5897.9 6073.9 6084.4 6107.8 6127.5 6047.7 6053.7 6082.8 6092.1 6118.6 5999.9 6259.6 6329.6 6332.6 6370.9 4231.5 4256.9 4268.5 4295.4 4309.4 5981.2 6004.5 6192.9 6214.7 6259.7 5894.6 5909.4 5917.3 5924.3 5930.6 5968.1 5976.2 5984.6 5991.8 6026.8 5730.6 5861.5 5869.0 5878.8 5941.2 6036.5 6063.0 6068.3 6071.3 6071.4 5707.8 5838.0 5841.4 5872.9 5952.8 23:03:37 INFO - PROCESS | 19913 | 23:03:37 INFO - PROCESS | 19913 | [#5] yui.html Cycles:180 Average:20145.78 Median:4952.95 stddev:28572.01 (576.9%) stddev-sans-first:28615.17 23:03:37 INFO - PROCESS | 19913 | Values: 783.3 823.4 838.2 840.5 840.5 15812.2 16551.7 16596.3 16624.2 16671.3 14195.6 14248.2 14277.2 14280.9 14295.9 630.7 760.9 773.5 787.6 788.6 782.4 783.2 788.3 790.0 793.0 841.5 842.1 843.0 845.0 847.7 837.9 838.1 839.7 840.9 842.8 613.0 616.1 616.2 616.4 616.6 15390.5 15602.5 15611.9 15626.7 15651.2 15557.0 15632.4 15779.8 15784.1 15907.4 12254.2 12425.7 12442.6 12498.8 12616.9 3964.6 3972.5 3982.7 4015.1 4091.4 75225.2 76301.0 76764.6 77758.0 78305.8 11793.4 11817.9 12006.2 12031.8 12100.6 22182.2 22613.1 22794.0 22844.8 22939.0 8393.1 8481.8 8519.0 8544.4 8739.6 4838.2 4945.6 4960.3 4977.6 5280.3 3465.5 3552.1 3556.6 3560.7 3566.1 591.9 608.4 608.6 619.2 627.0 678.7 712.6 721.2 724.5 795.4 9820.2 10005.5 10030.8 10240.2 10467.1 610.7 738.8 744.6 746.4 760.8 4964.8 5003.1 5021.2 5081.3 5104.0 99535.5 108297.5 109236.0 112557.0 115272.0 97968.0 98438.0 99458.5 100044.0 100215.0 53484.7 54224.0 83554.0 85818.0 89930.1 9073.0 9156.9 9197.3 9225.3 9367.3 53896.7 54045.0 55925.7 55942.0 56197.0 44909.3 61151.0 72127.3 72505.0 72663.0 11464.5 11606.1 11799.8 12078.7 12097.0 12626.7 12823.3 12870.1 13102.1 13186.9 13102.9 13227.3 13328.4 13463.2 13863.2 12959.5 13448.6 13588.6 13844.6 14112.5 12905.5 12981.7 13272.9 13313.3 13361.1 9868.4 10152.3 10323.6 10424.4 10467.2 9446.8 10746.2 10746.8 10823.2 10850.1 23:03:37 INFO - PROCESS | 19913 | -------- Summary: end -------- 23:03:37 INFO - PROCESS | 19913 | 23:03:38 INFO - TEST-INFO | 19913: exit 0 23:03:38 INFO - TEST-OK | dromaeo_css | took 1111581ms 23:03:38 INFO - TEST-START | kraken 23:03:38 INFO - operating with platform_type : linux_ 23:03:38 INFO - Initialising browser for kraken test... 23:03:38 INFO - TEST-INFO | started process 20175 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRIfNkR/profile http://localhost:39194/getInfo.html) 23:03:41 INFO - PROCESS | 20175 | JavaScript error: , line 0: uncaught exception: undefined 23:03:42 INFO - PROCESS | 20175 | __metrics Screen width/height:1600/1200 23:03:42 INFO - PROCESS | 20175 | colorDepth:24 23:03:42 INFO - PROCESS | 20175 | Browser inner width/height: 1024/697 23:03:42 INFO - PROCESS | 20175 | __metrics 23:03:43 ERROR - PROCESS | 20175 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 23:03:44 INFO - TEST-INFO | 20175: exit 0 23:03:44 INFO - Browser initialized. 23:03:44 INFO - Running cycle 1/1 for kraken test... 23:03:44 INFO - TEST-INFO | started process 20296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRIfNkR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:56 INFO - PROCESS | 20296 | RSS: Main: 164868096 23:03:56 INFO - PROCESS | 20296 | 23:03:58 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39194/tests/kraken/driver.html?testName=audio-beat-detection) 23:03:59 INFO - PROCESS | 20296 | RSS: Main: 170381312 23:03:59 INFO - PROCESS | 20296 | 23:04:02 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39194/tests/kraken/driver.html?testName=audio-dft) 23:04:03 INFO - PROCESS | 20296 | RSS: Main: 168992768 23:04:03 INFO - PROCESS | 20296 | 23:04:05 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39194/tests/kraken/driver.html?testName=audio-fft) 23:04:06 INFO - PROCESS | 20296 | RSS: Main: 164749312 23:04:06 INFO - PROCESS | 20296 | 23:04:08 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39194/tests/kraken/driver.html?testName=audio-oscillator) 23:04:09 INFO - PROCESS | 20296 | RSS: Main: 166346752 23:04:09 INFO - PROCESS | 20296 | 23:04:11 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39194/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:04:12 INFO - PROCESS | 20296 | RSS: Main: 166309888 23:04:12 INFO - PROCESS | 20296 | 23:04:15 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39194/tests/kraken/driver.html?testName=imaging-darkroom) 23:04:15 INFO - PROCESS | 20296 | RSS: Main: 166977536 23:04:15 INFO - PROCESS | 20296 | 23:04:19 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39194/tests/kraken/driver.html?testName=imaging-desaturate) 23:04:20 INFO - PROCESS | 20296 | RSS: Main: 163614720 23:04:20 INFO - PROCESS | 20296 | 23:04:23 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39194/tests/kraken/driver.html?testName=json-parse-financial) 23:04:23 INFO - PROCESS | 20296 | RSS: Main: 165703680 23:04:23 INFO - PROCESS | 20296 | 23:04:25 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39194/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:04:25 INFO - PROCESS | 20296 | RSS: Main: 165482496 23:04:25 INFO - PROCESS | 20296 | 23:04:27 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:04:28 INFO - PROCESS | 20296 | RSS: Main: 165826560 23:04:28 INFO - PROCESS | 20296 | 23:04:30 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:04:31 INFO - PROCESS | 20296 | RSS: Main: 167309312 23:04:31 INFO - PROCESS | 20296 | 23:04:33 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:04:34 INFO - PROCESS | 20296 | RSS: Main: 167165952 23:04:34 INFO - PROCESS | 20296 | 23:04:36 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:04:37 INFO - PROCESS | 20296 | RSS: Main: 167374848 23:04:37 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | Cycle 1(1): loaded http://localhost:39194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39194/tests/kraken/driver.html?testName=ai-astar) 23:04:39 INFO - PROCESS | 20296 | RSS: Main: 167792640 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | __start_tp_report 23:04:39 INFO - PROCESS | 20296 | _x_x_mozilla_page_load 23:04:39 INFO - PROCESS | 20296 | _x_x_mozilla_page_load_details 23:04:39 INFO - PROCESS | 20296 | |i|pagename|runs| 23:04:39 INFO - PROCESS | 20296 | |0;ai-astar;104;111;108;112;113;112;113;112;111;111 23:04:39 INFO - PROCESS | 20296 | |1;audio-beat-detection;161;118;144;172;119;157;145;120;183;119 23:04:39 INFO - PROCESS | 20296 | |2;audio-dft;163;165;164;162;163;170;169;162;163;164 23:04:39 INFO - PROCESS | 20296 | |3;audio-fft;75;74;76;186;76;76;75;76;169;75 23:04:39 INFO - PROCESS | 20296 | |4;audio-oscillator;85;83;161;86;86;84;140;84;84;149 23:04:39 INFO - PROCESS | 20296 | |5;imaging-gaussian-blur;117;118;117;118;117;117;118;118;118;118 23:04:39 INFO - PROCESS | 20296 | |6;imaging-darkroom;147;147;147;147;146;147;146;148;146;148 23:04:39 INFO - PROCESS | 20296 | |7;imaging-desaturate;96;95;95;97;95;95;96;96;96;96 23:04:39 INFO - PROCESS | 20296 | |8;json-parse-financial;54;52;55;53;54;54;53;55;53;52 23:04:39 INFO - PROCESS | 20296 | |9;json-stringify-tinderbox;62;63;63;62;63;63;62;62;63;63 23:04:39 INFO - PROCESS | 20296 | |10;stanford-crypto-aes;77;78;77;87;77;84;79;82;85;77 23:04:39 INFO - PROCESS | 20296 | |11;stanford-crypto-ccm;124;129;145;128;130;133;120;125;158;127 23:04:39 INFO - PROCESS | 20296 | |12;stanford-crypto-pbkdf2;158;160;161;154;156;160;156;159;155;157 23:04:39 INFO - PROCESS | 20296 | |13;stanford-crypto-sha256-iterative;65;64;64;64;65;64;64;63;65;67 23:04:39 INFO - PROCESS | 20296 | __end_tp_report 23:04:39 INFO - PROCESS | 20296 | __start_cc_report 23:04:39 INFO - PROCESS | 20296 | _x_x_mozilla_cycle_collect,876 23:04:39 INFO - PROCESS | 20296 | __end_cc_report 23:04:39 INFO - PROCESS | 20296 | __startTimestamp1469599479499__endTimestamp 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | ------- Summary: start ------- 23:04:39 INFO - PROCESS | 20296 | Number of tests: 14 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.75 (2.5%) stddev-sans-first:1.51 23:04:39 INFO - PROCESS | 20296 | Values: 104.0 111.0 108.0 112.0 113.0 112.0 113.0 112.0 111.0 111.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:151.00 stddev:24.19 (16.0%) stddev-sans-first:24.84 23:04:39 INFO - PROCESS | 20296 | Values: 161.0 118.0 144.0 172.0 119.0 157.0 145.0 120.0 183.0 119.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.00 stddev:2.80 (1.7%) stddev-sans-first:2.92 23:04:39 INFO - PROCESS | 20296 | Values: 163.0 165.0 164.0 162.0 163.0 170.0 169.0 162.0 163.0 164.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#3] audio-fft Cycles:10 Average:95.80 Median:75.50 stddev:43.25 (57.3%) stddev-sans-first:45.21 23:04:39 INFO - PROCESS | 20296 | Values: 75.0 74.0 76.0 186.0 76.0 76.0 75.0 76.0 169.0 75.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#4] audio-oscillator Cycles:10 Average:104.20 Median:84.00 stddev:32.01 (38.1%) stddev-sans-first:33.19 23:04:39 INFO - PROCESS | 20296 | Values: 85.0 83.0 161.0 86.0 86.0 84.0 140.0 84.0 84.0 149.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 23:04:39 INFO - PROCESS | 20296 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 118.0 118.0 118.0 118.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 23:04:39 INFO - PROCESS | 20296 | Values: 147.0 147.0 147.0 147.0 146.0 147.0 146.0 148.0 146.0 148.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 23:04:39 INFO - PROCESS | 20296 | Values: 96.0 95.0 95.0 97.0 95.0 95.0 96.0 96.0 96.0 96.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.08 (2.0%) stddev-sans-first:1.13 23:04:39 INFO - PROCESS | 20296 | Values: 54.0 52.0 55.0 53.0 54.0 54.0 53.0 55.0 53.0 52.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 23:04:39 INFO - PROCESS | 20296 | Values: 62.0 63.0 63.0 62.0 63.0 63.0 62.0 62.0 63.0 63.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.50 stddev:3.86 (4.8%) stddev-sans-first:3.91 23:04:39 INFO - PROCESS | 20296 | Values: 77.0 78.0 77.0 87.0 77.0 84.0 79.0 82.0 85.0 77.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:129.50 stddev:11.34 (8.8%) stddev-sans-first:11.66 23:04:39 INFO - PROCESS | 20296 | Values: 124.0 129.0 145.0 128.0 130.0 133.0 120.0 125.0 158.0 127.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.60 Median:158.50 stddev:2.37 (1.5%) stddev-sans-first:2.51 23:04:39 INFO - PROCESS | 20296 | Values: 158.0 160.0 161.0 154.0 156.0 160.0 156.0 159.0 155.0 157.0 23:04:39 INFO - PROCESS | 20296 | 23:04:39 INFO - PROCESS | 20296 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.50 stddev:1.08 (1.7%) stddev-sans-first:1.13 23:04:39 INFO - PROCESS | 20296 | Values: 65.0 64.0 64.0 64.0 65.0 64.0 64.0 63.0 65.0 67.0 23:04:39 INFO - PROCESS | 20296 | -------- Summary: end -------- 23:04:39 INFO - PROCESS | 20296 | 23:04:40 INFO - TEST-INFO | 20296: exit 0 23:04:40 INFO - TEST-OK | kraken | took 62336ms 23:04:40 INFO - SUITE-END | took 1173s 23:04:40 INFO - Completed test suite (00:19:34) 23:04:40 INFO - javascript benchmark 23:04:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7364.998081840728, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.1974646465155, 3153.6530981592005, 3179.1893067267843, 3186.665400132986, 3188.7767856250057, 5405.486478784519, 5556.416576259495, 5581.609287349887, 5589.357223919251, 5654.802081927133, 5440.183972404141, 5512.173174023898, 5523.143912693539, 5525.613207075507, 5552.833414997546, 4961.255811628258, 5042.47054059324, 5054.7472626368535, 5066.164082926312, 5117.692938423706, 5077.847664570068, 5086.0, 5265.236540701596, 5270.183121616155, 5270.525652691257, 5085.593152547787, 5103.923441148386, 5165.199394093903, 5170.53465188133, 5171.146760784485, 4910.705357678551, 4939.3578834751615, 4939.604831613462, 4976.278439626234, 5005.424376196747, 4882.048000639877, 4905.705657660516, 4908.9754551227015, 4917.483664215278, 4987.6758010729545, 5408.891822163574, 5445.972770136124, 5454.318210223722, 5471.261379713767, 5504.009278329899, 5842.649441033509, 5893.14549390336, 5907.852303692399, 5923.614965027303, 5934.851628709273, 5937.762489500458, 5941.0, 5955.523558115384, 5961.0, 5970.402959704038, 5473.917891231635, 5557.666540007613, 5558.360788509332, 5562.527185189264, 5570.303712036036, 6092.969535152296, 6120.751898419022, 6122.794777235106, 6125.479334256549, 6127.80043271483, 5875.3243377012195, 5879.441453061952, 5885.47030767233, 5888.293404791452, 5927.022041363213, 5706.914396284059, 5950.256217972754, 5959.225300710967, 5969.73136208863, 5972.522198224132, 5734.139879018181, 5738.024535828919, 5817.563682723812, 5827.24245848037, 5875.50058245054, 5899.764009439575, 5963.4334738199805, 5996.37038110992, 5998.610090344114, 6022.969885150546, 4521.0, 4529.048899731093, 4540.296254080623, 4541.795619197144, 4580.1068791585985, 3979.46277252573, 4003.3794761811964, 4052.0882801370044, 4052.878413647595, 4073.857414990461, 5645.745941432645, 5649.237352957381, 5694.743736531866, 5695.601307908407, 5738.110592858114, 6003.669798161056, 6026.337102918676, 6029.065494848305, 6035.517158627299, 6046.153538504613, 5426.403095659474, 5557.861053473695, 5631.042722737145, 5646.0, 5675.091985282335, 5121.923171152435, 5130.717810520383, 5142.922856157161, 5144.0, 5152.227165925066, 6119.571629985859, 6122.326544080148, 6156.815295541141, 6157.9384206158265, 6160.229971253593, 5817.825465236049, 5858.9414105860105, 5878.265216847894, 5972.134040564007, 5992.640441573432, 6123.969380153071, 6153.4154255345675, 6157.0, 6159.5688301818445, 6191.380861913773, 5861.736221870111, 5879.5002424794375, 5880.970595147168, 5883.499902508334, 5912.172295878667, 5839.766409343749, 5935.0503919372695, 5967.582269241285, 5968.313643931004, 6006.849828754316, 5859.00396932514, 5884.382139875252, 5892.558058145571, 5906.468417842415, 5921.259842519685, 5806.216160818404, 5833.620814647034, 5888.823335299948, 5891.675957822361, 5892.970535147297, 5517.586181036519, 5757.424257574209, 5772.336181339032, 5855.414458554111, 5855.970720146372, 5932.228810254639, 5933.614315069507, 5939.46544810969, 5963.821085367446, 5974.402559744165, 5842.328132264844, 5846.56150788684, 5850.0932355484965, 5855.2095467113095, 5863.296404431325], "value": 5482.710052895921, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.9733818223361, 572.1617829879227, 586.5190543754123, 613.9938600613993, 617.657200253859, 40247.0, 45299.54700452999, 45317.32024019642, 45421.0, 45465.044772089874, 44306.77846610762, 44598.55401445985, 44814.32778508325, 44853.7757311213, 44952.3257151143, 8753.080926502706, 8824.73525794224, 8848.070952549986, 8870.822583548324, 8888.511131887744, 8803.867941980876, 8881.689140880071, 8883.11168883113, 8883.822323553524, 8892.822143557125, 8853.981792093895, 8901.0, 8938.195562399384, 8946.686865959693, 8954.462732236087, 8825.293976481866, 8876.068012858654, 8901.287896968228, 8919.821603567925, 8927.419717718327, 8870.73387798367, 8898.955505222497, 8927.910720892773, 8932.106789321017, 8943.373963822534, 41848.37227441591, 41911.580884191375, 42026.78986605078, 42253.36619950703, 42368.36447453291, 3662.6520480554304, 3683.7053035757076, 3867.9226415471535, 4153.501579810412, 4223.450951376332, 32707.0, 32876.50685239724, 33075.173120672174, 33410.99767006969, 34746.305073898635, 4459.665525085632, 4493.213687604656, 4524.343970124346, 4525.660575456821, 4653.232216684243, 357016.0, 357834.0, 358528.9999999974, 358562.0, 358634.0, 57987.42012579904, 58584.41415585875, 58629.4137058624, 58802.705986470646, 58879.70560147172, 34945.47581786275, 62024.37975620186, 62093.37906620876, 62245.37754622396, 62269.68865155736, 14826.777598336033, 15311.310991005432, 15337.386504539916, 15364.308606112752, 15377.692446151239, 11323.943380283046, 11397.088232941345, 11398.430078496041, 11407.0, 11423.485943132579, 19813.801861981196, 19970.90014549918, 20009.499762506053, 20054.29809956674, 20087.698684519743, 7295.52579082358, 7332.743353982585, 7484.438667099989, 7529.661165247577, 7569.3566046886635, 953.4612943686933, 957.7031120352713, 1002.8946960569181, 1047.7694907120422, 1078.703356576932, 33701.0, 33800.1549961248, 34042.659573404446, 34103.488447673306, 34431.0, 1064.0, 1106.247751528952, 1130.0846314485293, 1156.5662876421343, 1205.0419916166668, 17644.28494135286, 17664.205110769788, 17709.557261068578, 17728.29086836538, 17759.822401776077, 621118.0, 622421.0, 622741.0, 625136.0, 626348.0, 61774.0, 62091.689541552005, 62655.37344626495, 62696.373036269055, 62877.68561157165, 1297.772889744291, 1340.919544827294, 1356.8507464178933, 1365.4538184726189, 1371.5953793630904, 31426.371472569965, 32068.03795886127, 32424.513632296006, 33166.834165829016, 33350.16624584405, 47600.28599571079, 47610.28584571165, 47785.76107119442, 53477.0, 53592.46407536031, 488320.0, 489818.0, 491287.0, 492805.0, 492986.0, 28671.56992645154, 28693.0, 28767.280817979718, 28774.856125720074, 29091.85454072716, 49669.75165124151, 49698.503014969385, 50929.490705092474, 51490.48509514857, 51629.48370516247, 42030.0, 43378.56621433745, 43913.34129987989, 44365.33451998158, 44421.77789111163, 42299.57700423079, 42401.36397954095, 43004.56995430006, 43036.35445468383, 43993.340099899164, 42152.36771448369, 42398.576014239465, 43143.56856431396, 43464.13071738484, 44900.77549612231, 33266.33467330688, 38295.80852095722, 39144.0, 39196.608033920435, 39576.40635390529, 29330.0, 29504.409911801213, 29519.0, 29675.406491870475, 29888.70111298859], "value": 20571.799287614078, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.8638903605668, 378.52873172899746, 385.3199103582178, 391.18047690089253, 395.42268288299084, 15421.92289038556, 17849.19678614462, 18777.718334225, 19707.408777736655, 20745.481362965915, 17757.28970841168, 18491.90754046231, 19812.603747925034, 19986.60026799467, 20036.69944950827, 5719.571032172583, 5881.059030555112, 5925.31858836235, 5929.762809487637, 5949.988501954678, 5430.266913966623, 5685.118806584986, 5807.680577568232, 5814.186013958049, 5851.970740146315, 5669.6314739541795, 5699.743511541969, 5751.539876809845, 5760.193572899798, 5778.971105144468, 5370.597205209606, 5589.552835773128, 5632.0707083331, 5741.397153298885, 5746.0, 5520.420355862617, 5541.5843811714285, 5550.750216240279, 5551.833444996657, 5567.192757050205, 19205.231790728354, 22695.319140425814, 22796.0, 23000.4249893754, 23306.0, 2348.894299756498, 2401.0, 2663.3474798674256, 2829.122971878724, 2844.7581955533806, 9773.755656108584, 9966.501674916226, 9967.0, 10042.89957100427, 10175.13511351536, 1842.3367587668563, 1907.83783378413, 1922.0966145911539, 1928.7492625958675, 1947.1627200303863, 81521.59239203825, 82885.58557207236, 84726.0, 85120.57439712762, 86156.13843861544, 10525.579046828734, 10639.734006649896, 11115.666530004113, 11157.218994670295, 11634.592789252336, 11695.473703683354, 12384.504619815107, 14770.0, 14840.480583179537, 14931.253437328307, 7113.395361394236, 7132.857342853217, 7184.976599523115, 7195.208527062019, 7409.518381305198, 7097.290270972861, 7340.669669864869, 7425.294597013275, 7463.79817953705, 7661.387089032864, 3799.5787119767506, 3867.8839634811, 3897.979066223699, 3899.532056153223, 4075.816588253536, 1924.7594050743658, 1927.8264956154014, 1943.5238366600274, 1969.7734760502726, 1970.1232951336517, 957.200737384278, 972.1687956796978, 975.5219942228399, 999.4453078541479, 1033.7674023344687, 18923.81076189248, 19535.902320488305, 19667.311644092388, 19667.803321966596, 19903.701444478345, 958.1855422890487, 972.7179118055826, 977.2475194100502, 986.7730422002836, 1043.3531210649446, 10238.488075596191, 10904.0, 10952.561897524169, 10987.61543345979, 11044.171687123498, 464168.0, 467252.0, 497077.0, 501806.0, 504436.0, 80969.0, 99178.0, 100204.49897750464, 101398.0, 101820.0, 993.2649839119144, 1014.1481155829139, 1050.1808589300206, 1057.9153667706564, 1062.2139616683598, 16921.238544265292, 17560.28496079086, 17873.73189402134, 18111.275548977894, 18148.364807231683, 41090.383644244765, 41094.794526028374, 41206.587934120274, 41377.793111034254, 42390.78804605957, 54313.0, 55318.44681553294, 59030.40969590249, 59108.704456479165, 59472.40527594669, 8049.2755651989255, 8336.29141522953, 8404.0, 8405.579721013803, 8599.699010534601, 12873.0, 12989.155704879153, 13253.337333133111, 13601.251931143883, 13781.655458613615, 9503.904960950578, 9583.760405989906, 11093.667189984313, 11612.883871161519, 11798.410079496163, 11303.265287756269, 11431.885681143081, 11475.196736228718, 11609.30344179352, 11871.940640296743, 11346.602868899548, 11726.0, 11781.175317727677, 11790.292582445081, 12140.75718485643, 8334.83330333402, 8391.790205244917, 8396.748097557083, 8425.915740842758, 8663.826723465621, 8670.0, 11211.439428028729, 11316.49075791575, 11624.593139240085, 12106.515739370327], "value": 9071.49393426041, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.7899254608083, 1472.2417954753303, 1596.162014942155, 1736.930522779089, 1739.913004349782, 7504.737334193291, 8623.180797824205, 8665.263452606536, 9160.450372977617, 9164.770880727976, 9042.32182586307, 9100.590473428694, 9103.408278461895, 9104.226140778026, 9124.543772811367, 2388.904443822245, 2418.2503423938633, 2424.9515009699794, 2427.9514409711796, 2434.0628857889665, 2324.779145981129, 2379.976200237993, 2387.248016874679, 2387.319613910036, 2387.5344307859973, 2334.8015418689433, 2348.6007378745653, 2365.9053637854463, 2366.7278262999803, 2367.3726462487452, 2265.592193405186, 2274.999999999992, 2275.84069115162, 2289.198780426854, 2333.7899589036897, 2276.1919518570835, 2283.5204607032542, 2301.0795681727277, 2304.055337311711, 2334.0197117210814, 8884.37809353339, 8954.149355811187, 8996.685116020974, 9069.410488318239, 9075.092490750938, 5022.146235140035, 5227.424983251839, 5230.189320655304, 5286.550643195333, 5304.92042619361, 3286.7863588866885, 3376.1390845334527, 3400.3199360128074, 3428.708559772422, 3437.759356845022, 1140.0822338017892, 1181.078758568318, 1186.0926391310654, 1203.6990752311922, 1204.7289359894041, 12210.450529726184, 12253.081018923616, 12292.8156077658, 12336.629901102891, 12348.75302493954, 4679.812807487662, 4702.600278976325, 4713.257487810506, 4795.568398844051, 4905.264210368473, 10460.058594726512, 10487.632932847313, 10491.318064325795, 10494.317869338469, 10526.789464210673, 5353.063213937546, 5366.758495867695, 5390.272313237741, 5397.109476936262, 5398.1902714593125, 4225.767582782915, 4723.976380118077, 4785.736784476887, 4835.6131509479155, 4949.554540091409, 4026.59734026595, 4110.445089912823, 4120.938185927213, 4149.2738770715005, 4169.291220492463, 1260.7352455984162, 1260.9117361784588, 1291.6770807298176, 1298.337847697662, 1300.4213125159213, 903.7559858838208, 984.4585477987079, 993.2749093162032, 1025.2823023883311, 1031.3090229546222, 6112.847178820476, 6268.1851359323, 6404.519661025442, 6454.4513716333695, 6581.04574836646, 450.6417398168464, 452.2741000693905, 452.86414075777134, 456.97943592538417, 459.4876712465577, 4421.0, 4473.038296766211, 4532.0482698632995, 4571.085782843445, 4616.446026476844, 10148.528463372917, 10289.897101029044, 10343.534540945675, 10411.375317480975, 10418.114460270961, 10523.10553602937, 10900.236983411243, 10969.506372213269, 11333.659990200307, 11387.487563059682, 8280.668773249003, 8426.609609414501, 8436.36727245459, 8439.113893041265, 8467.364947628952, 6126.295476020315, 6344.555881088464, 6454.870902581828, 6505.2518960318275, 6616.106825578485, 9718.12536871685, 9951.601935922483, 9968.750781230527, 10014.499275036073, 10151.593936242467, 10331.173506119341, 10467.685969420929, 10487.10859576914, 10516.947415263181, 10521.579136834442, 7081.256468070985, 7723.806904827425, 7812.3750099993, 7826.334761545332, 7967.2431119043595, 8813.515256660945, 8815.294776417742, 8865.335099867407, 8881.866771998297, 8902.643894244417, 7871.960640196762, 7891.408144389308, 7910.0, 8104.392170587347, 8189.877151842847, 7800.453968222397, 7888.723894663659, 7966.681332746625, 7974.720884769005, 7984.640691169027, 7306.817329566803, 7323.780286591411, 7407.296306850841, 7413.962930185315, 7425.7029718810645, 5488.286522752017, 5604.075327570905, 5621.74702138413, 5689.0, 5719.999000175036, 5324.148136298174, 5549.445055494418, 5555.3611334697025, 5660.037793575019, 5796.681182535001], "value": 4658.097393217287, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.2952164281866, 327.9721223695986, 330.8560776062413, 336.9511420843976, 337.3910092283429, 5518.862028449291, 5647.491725744682, 5681.755706495932, 5752.539796816261, 5762.106873434619, 5621.465960733734, 5657.57568182387, 5661.8584535386635, 5664.490195882369, 5674.2339784129235, 4394.516603173648, 4396.274614688573, 4400.317950717628, 4402.515723270438, 4404.326355984721, 4161.500619925598, 4337.219300525904, 4348.934765978513, 4398.0, 4416.7191514460765, 4279.1441711657635, 4321.351797230409, 4340.544242854487, 4351.75794033444, 4352.499462561815, 4326.0, 4333.155034768221, 4340.088581397895, 4354.3438502513445, 4389.692721509496, 3749.7562658427114, 3775.6601905828334, 3825.024618722208, 3830.2339532093692, 3830.7893065881367, 5453.473027552292, 5455.972720136414, 5458.153986132156, 5550.16747487876, 5571.387147413781, 5017.7491125443585, 5027.019731152427, 5027.245913113025, 5030.195168772979, 5043.420006699203, 5753.539716822645, 5921.437463440966, 5947.851303717442, 5952.7023648817385, 5968.597379615787, 4150.8547200847825, 4152.584741525853, 4159.396887451333, 4180.101278225196, 4208.116295577933, 6180.783672571439, 6204.6587437690905, 6244.0, 6249.937500624981, 6251.718672659741, 4135.441715368447, 4208.305629571087, 4231.809568569361, 4253.574642535722, 4271.466066741657, 4274.4443222381515, 4298.20483210607, 4299.0, 4311.8706438806885, 4330.783460826946, 4976.90046199074, 4980.103581355391, 4987.501249875056, 5001.974990125026, 5024.32171656829, 5871.4715675589405, 5875.441833025856, 5896.498797602252, 5985.551083668743, 6020.277566692025, 1464.7509923313062, 1475.8671719545293, 1482.2070192446988, 1482.5700546841294, 1485.2944851195596, 896.3814967672286, 925.4447331600985, 932.4451951089092, 935.0229010683852, 943.9528023598792, 5056.898862022739, 5063.151949538392, 5074.264231686385, 5098.4646612105935, 5129.9743501282255, 5383.757730902119, 5425.945740542623, 5446.537044351196, 5450.501112194178, 5484.862878428071, 5011.917670632608, 5084.313617661643, 5113.565346945476, 5357.383900851374, 5788.80025590273, 3266.2977459846247, 3290.5393244945726, 3300.3234336960863, 3302.702756751904, 3306.2560923791953, 6082.452579267887, 6358.872822543523, 6376.855044221201, 6389.424951754271, 6401.64790936503, 6198.876022479525, 6227.629921417281, 6229.53278504114, 6240.469560087353, 6289.0, 6309.526785491106, 6316.778442994014, 6350.936490635035, 6373.490120790326, 6376.003278459117, 5897.941020589739, 6073.908891366545, 6084.361142080018, 6107.84730381744, 6127.540434467343, 6047.667378294236, 6053.727582258721, 6082.756689732538, 6092.086187071975, 6118.60229085108, 5999.940000599938, 6259.561830671992, 6329.588576742497, 6332.556721029484, 6370.93629063722, 4231.534531201627, 4256.87229383119, 4268.530461649154, 4295.377170310225, 4309.439772829513, 5981.192539007178, 6004.519638429003, 6192.938070619416, 6214.689265536796, 6259.749610015549, 5894.646321220741, 5909.43399998992, 5917.289925209003, 5924.259467566554, 5930.584859059824, 5968.134620479919, 5976.223090998316, 5984.551158663205, 5991.760329586942, 6026.819195424145, 5730.598858079894, 5861.472467477947, 5869.0, 5878.823635290948, 5941.197938278277, 6036.486898613492, 6063.0, 6068.302145253176, 6071.2714474263375, 6071.392860713893, 5707.771689132388, 5838.0, 5841.4450627190345, 5872.882542349043, 5952.76188952437], "value": 4462.304986574682, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.2950344689779, 823.4112609484217, 838.1518751153707, 840.4915026409022, 840.5083026429536, 15812.20938953052, 16551.668966620662, 16596.33614655414, 16624.168791560434, 16671.333146674133, 14195.574132776008, 14248.216348100876, 14277.214753188571, 14280.857191428082, 14295.857041429583, 630.7350912616712, 760.8668483015477, 773.489496932025, 787.6337503061076, 788.5781107107698, 782.4131901074194, 783.2285199078902, 788.2826627768732, 789.9526028438283, 793.0245797668871, 841.4824882697159, 842.1410161635159, 842.9578521073953, 845.0450990380891, 847.737201467547, 837.9329653627725, 838.0613712641849, 839.6683310092541, 840.9327253819741, 842.806154584449, 612.9816105516842, 616.1374076293181, 616.1804799616499, 616.374380004296, 616.6300219868089, 15390.461333853373, 15602.453914112952, 15611.921940390339, 15626.687466250725, 15651.217439127999, 15557.0, 15632.374705011785, 15779.842201577952, 15784.131872746995, 15907.363705451768, 12254.20347677398, 12425.689357766129, 12442.626721198289, 12498.81251781215, 12616.936915315457, 3964.5638979712207, 3972.543157536891, 3982.741121827101, 4015.0765323975356, 4091.4476545666253, 75225.24774752237, 76301.0, 76764.61617691931, 77758.0, 78305.82541261942, 11793.351365674971, 11817.940910295394, 12006.219595726425, 12031.819522707166, 12100.636980890587, 22182.223622173467, 22613.095476181097, 22794.0, 22844.771552284597, 22939.0, 8393.118722534167, 8481.787955301166, 8518.957405212934, 8544.401891867634, 8739.563021848882, 4838.225883858636, 4945.604351651859, 4960.305557221992, 4977.576905963034, 5280.287161233185, 3465.4801779733243, 3552.1474846036767, 3556.608773034964, 3560.7151427886224, 3566.144125409883, 591.8905002574537, 608.4402349838159, 608.6104892868568, 619.2012304127625, 627.0374974414343, 678.7352932356386, 712.568895818033, 721.2030706069822, 724.4648500387611, 795.4034474144391, 9820.165285950632, 10005.49972501372, 10030.849537256947, 10240.231982601335, 10467.057964783206, 610.716017052073, 738.8152961759561, 744.5607091815855, 746.3842330077707, 760.7869796457001, 4964.7836280111605, 5003.149464591028, 5021.221710634857, 5081.323163356084, 5103.974480127576, 99535.50232248793, 108297.4585127085, 109236.0, 112557.0, 115272.0, 97968.0, 98438.0, 99458.502707486, 100044.0, 100215.0, 53484.73257633687, 54224.0, 83554.0, 85818.0, 89930.10069899217, 9072.95463522678, 9156.908430915606, 9197.310201734763, 9225.313420908155, 9367.297452690938, 53896.73051634717, 54045.0, 55925.72037139788, 55942.0, 56197.0, 44909.32636010397, 61151.0, 72127.27872721206, 72505.0, 72663.0, 11464.484098215433, 11606.071514278668, 11799.76400472003, 12078.698032549257, 12097.03223742081, 12626.684332891384, 12823.294718790183, 12870.09909306377, 13102.148360356547, 13186.868131318564, 13102.868971310165, 13227.272500012592, 13328.400221990227, 13463.192208467523, 13863.237521935987, 12959.546415875777, 13448.596542103753, 13588.59234222975, 13844.584662460142, 14112.50606228777, 12905.483780648668, 12981.740365192833, 13272.933635331761, 13313.267770272729, 13361.13152645075, 9868.407895526292, 10152.289339746147, 10323.56171327205, 10424.42665653366, 10467.16262698967, 9446.763830904283, 10746.24776265654, 10746.83879741789, 10823.242373034127, 10850.131989440668], "value": 7503.758065154809, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 108.0, 112.0, 113.0, 112.0, 113.0, 112.0, 111.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 118.0, 144.0, 172.0, 119.0, 157.0, 145.0, 120.0, 183.0, 119.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 162.0, 163.0, 170.0, 169.0, 162.0, 163.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 186.0, 76.0, 76.0, 75.0, 76.0, 169.0, 75.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 161.0, 86.0, 86.0, 84.0, 140.0, 84.0, 84.0, 149.0], "value": 104.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 148.0, 146.0, 148.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 55.0, 53.0, 54.0, 54.0, 53.0, 55.0, 53.0, 52.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 63.0, 63.0, 62.0, 62.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 87.0, 77.0, 84.0, 79.0, 82.0, 85.0, 77.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 129.0, 145.0, 128.0, 130.0, 133.0, 120.0, 125.0, 158.0, 127.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 161.0, 154.0, 156.0, 160.0, 156.0, 159.0, 155.0, 157.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 63.0, 65.0, 67.0], "value": 64.5, "unit": "score"}]}]} 23:04:40 INFO - Return code: 0 23:04:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:04:40 ERROR - # TBPL SUCCESS # 23:04:40 INFO - Running post-action listener: _resource_record_post_action 23:04:40 INFO - [mozharness: 2016-07-27 06:04:40.815077Z] Finished run-tests step (success) 23:04:40 INFO - Running post-run listener: _resource_record_post_run 23:04:40 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 556867584; Read time: 12; Write time: 590928 23:04:40 INFO - TinderboxPrint: CPU usage
13.4% 23:04:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:04:40 INFO - TinderboxPrint: I/O write bytes / time
556,867,584 / 590,928 23:04:40 INFO - TinderboxPrint: CPU idle
8,238.3 (86.6%) 23:04:40 INFO - TinderboxPrint: CPU user
1,215.2 (12.8%) 23:04:40 INFO - TinderboxPrint: Swap in / out
0 / 0 23:04:40 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 260931584; Read time: 0; Write time: 323248 23:04:40 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 295264256; Read time: 0; Write time: 267680 23:04:41 INFO - Running post-run listener: _upload_blobber_files 23:04:41 INFO - Blob upload gear active. 23:04:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:04:41 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 23:04:41 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:04:41 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:04:41 INFO - (blobuploader) - INFO - Open directory for files ... 23:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:04:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:42 INFO - (blobuploader) - INFO - Done attempting. 23:04:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:04:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:04:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:46 INFO - (blobuploader) - INFO - Done attempting. 23:04:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:04:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:04:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:47 INFO - (blobuploader) - INFO - Done attempting. 23:04:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:04:47 INFO - Return code: 0 23:04:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:04:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:04:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac037d6ca8bddc14c6d8a02ed24ebfd5cca21d85976f7e5b699417fa2a4a564544db78540acb8f6b2aa8eaecfd0eed35efd04254fc5f1bc7fbf77501424ef44f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8be22fa767a414096cb20b9616e390e8eccda65f1c4a03ac09aab31280e73de763689d820d0eb6b3b9bdb78407666fd9de8ca26e0e1eb2676d765cfe8542ee7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ddc8dd1d4fb9514f5ed1d32f50cdd3e4125eb44f7312c0dadefeaa41268d8c139e306cb55275271124e6755d9cb3977f6973021f9ea9525a4d2e1a9d1107301b"} 23:04:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:04:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:04:47 INFO - Contents: 23:04:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac037d6ca8bddc14c6d8a02ed24ebfd5cca21d85976f7e5b699417fa2a4a564544db78540acb8f6b2aa8eaecfd0eed35efd04254fc5f1bc7fbf77501424ef44f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8be22fa767a414096cb20b9616e390e8eccda65f1c4a03ac09aab31280e73de763689d820d0eb6b3b9bdb78407666fd9de8ca26e0e1eb2676d765cfe8542ee7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ddc8dd1d4fb9514f5ed1d32f50cdd3e4125eb44f7312c0dadefeaa41268d8c139e306cb55275271124e6755d9cb3977f6973021f9ea9525a4d2e1a9d1107301b"} 23:04:47 INFO - Running post-run listener: copy_logs_to_upload_dir 23:04:47 INFO - Copying logs to upload dir... 23:04:47 INFO - mkdir: /builds/slave/test/build/upload/logs 23:04:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1226.800190 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-07-26 23:04:46.607755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 23:04:46.612087) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac037d6ca8bddc14c6d8a02ed24ebfd5cca21d85976f7e5b699417fa2a4a564544db78540acb8f6b2aa8eaecfd0eed35efd04254fc5f1bc7fbf77501424ef44f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8be22fa767a414096cb20b9616e390e8eccda65f1c4a03ac09aab31280e73de763689d820d0eb6b3b9bdb78407666fd9de8ca26e0e1eb2676d765cfe8542ee7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ddc8dd1d4fb9514f5ed1d32f50cdd3e4125eb44f7312c0dadefeaa41268d8c139e306cb55275271124e6755d9cb3977f6973021f9ea9525a4d2e1a9d1107301b"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1469586660/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac037d6ca8bddc14c6d8a02ed24ebfd5cca21d85976f7e5b699417fa2a4a564544db78540acb8f6b2aa8eaecfd0eed35efd04254fc5f1bc7fbf77501424ef44f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8be22fa767a414096cb20b9616e390e8eccda65f1c4a03ac09aab31280e73de763689d820d0eb6b3b9bdb78407666fd9de8ca26e0e1eb2676d765cfe8542ee7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ddc8dd1d4fb9514f5ed1d32f50cdd3e4125eb44f7312c0dadefeaa41268d8c139e306cb55275271124e6755d9cb3977f6973021f9ea9525a4d2e1a9d1107301b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 23:04:46.640268) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:04:46.640725) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1469598259.116895-1846975215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:04:46.723112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 23:04:46.723468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 23:04:46.723790) ========= ========= Total master_lag: 0.16 =========