builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1470061250.69 results: success (0) buildid: 20160801072026 builduid: af3f5761fcb444b39370be29f9ab913f revision: c0c16cea7afa66c59d18a6b88cfc95274599a1c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.694104) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.694489) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.694753) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.757138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.757425) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 07:20:50-- 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% 115M=0s 2016-08-01 07:20:50 (115 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163100 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.950574) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:50.950925) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.134001 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:51.101212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 07:20:51.101507) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c0c16cea7afa66c59d18a6b88cfc95274599a1c7 --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 c0c16cea7afa66c59d18a6b88cfc95274599a1c7 --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=e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 07:20:51,155 truncating revision to first 12 chars 2016-08-01 07:20:51,155 Setting DEBUG logging. 2016-08-01 07:20:51,155 attempt 1/10 2016-08-01 07:20:51,155 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c0c16cea7afa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 07:20:52,410 unpacking tar archive at: mozilla-inbound-c0c16cea7afa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.610802 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 07:20:52.725971) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:52.726359) ========= script_repo_revision: c0c16cea7afa66c59d18a6b88cfc95274599a1c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:52.726746) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:52.727065) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 07:20:52.738813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 49 secs) (at 2016-08-01 07:20:52.739247) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:20:53 INFO - MultiFileLogger online at 20160801 07:20:53 in /builds/slave/test 07:20:53 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 07:20:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:20:53 INFO - {'append_to_log': False, 07:20:53 INFO - 'base_work_dir': '/builds/slave/test', 07:20:53 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 07:20:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:20:53 INFO - 'branch': 'Mozilla-Inbound', 07:20:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:20:53 INFO - 'config_files': ('talos/linux_config.py',), 07:20:53 INFO - 'default_actions': ('clobber', 07:20:53 INFO - 'read-buildbot-config', 07:20:53 INFO - 'download-and-extract', 07:20:53 INFO - 'populate-webroot', 07:20:53 INFO - 'create-virtualenv', 07:20:53 INFO - 'install', 07:20:53 INFO - 'run-tests'), 07:20:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:20:53 INFO - 'download_minidump_stackwalk': True, 07:20:53 INFO - 'download_symbols': 'ondemand', 07:20:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:20:53 INFO - 'tooltool.py': '/tools/tooltool.py', 07:20:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:20:53 INFO - '/tools/misc-python/virtualenv.py')}, 07:20:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:20:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:20:53 INFO - 'installer_path': 'installer.exe', 07:20:53 INFO - 'log_level': 'info', 07:20:53 INFO - 'log_name': 'talos', 07:20:53 INFO - 'log_to_console': True, 07:20:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:20:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:20:53 INFO - 'opt_config_files': (), 07:20:53 INFO - 'pip_index': False, 07:20:53 INFO - 'sps_profile': False, 07:20:53 INFO - 'sps_profile_interval': 0, 07:20:53 INFO - 'suite': 'dromaeojs-e10s', 07:20:53 INFO - 'system_bits': '32', 07:20:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:20:53 INFO - 'title': 'talos-linux64-ix-006', 07:20:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:20:53 INFO - 'use_talos_json': True, 07:20:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:20:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:20:53 INFO - 'work_dir': 'build'} 07:20:53 INFO - [mozharness: 2016-08-01 14:20:53.018773Z] Running clobber step. 07:20:53 INFO - Running pre-action listener: _resource_record_pre_action 07:20:53 INFO - Running main action method: clobber 07:20:53 INFO - rmtree: /builds/slave/test/build 07:20:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:20:57 INFO - Running post-action listener: _resource_record_post_action 07:20:57 INFO - [mozharness: 2016-08-01 14:20:57.235824Z] Finished clobber step (success) 07:20:57 INFO - [mozharness: 2016-08-01 14:20:57.236009Z] Running read-buildbot-config step. 07:20:57 INFO - Running pre-action listener: _resource_record_pre_action 07:20:57 INFO - Running main action method: read_buildbot_config 07:20:57 INFO - Using buildbot properties: 07:20:57 INFO - { 07:20:57 INFO - "project": "", 07:20:57 INFO - "product": "firefox", 07:20:57 INFO - "script_repo_revision": "production", 07:20:57 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 07:20:57 INFO - "repository": "", 07:20:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 07:20:57 INFO - "buildid": "20160801072026", 07:20:57 INFO - "stage_platform": "linux64-pgo", 07:20:57 INFO - "basedir": "/builds/slave/test", 07:20:57 INFO - "buildnumber": 933, 07:20:57 INFO - "platform": "ubuntu64_hw", 07:20:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:20:57 INFO - "slavebuilddir": "test", 07:20:57 INFO - "branch": "mozilla-inbound", 07:20:57 INFO - "revision": "c0c16cea7afa66c59d18a6b88cfc95274599a1c7", 07:20:57 INFO - "slavename": "talos-linux64-ix-006", 07:20:57 INFO - "builduid": "af3f5761fcb444b39370be29f9ab913f", 07:20:57 INFO - "repo_path": "integration/mozilla-inbound" 07:20:57 INFO - } 07:20:57 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:20:57 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.test_packages.json. 07:20:57 INFO - Running post-action listener: _resource_record_post_action 07:20:57 INFO - [mozharness: 2016-08-01 14:20:57.240456Z] Finished read-buildbot-config step (success) 07:20:57 INFO - [mozharness: 2016-08-01 14:20:57.240620Z] Running download-and-extract step. 07:20:57 INFO - Running pre-action listener: _resource_record_pre_action 07:20:57 INFO - Running main action method: download_and_extract 07:20:57 INFO - mkdir: /builds/slave/test/build/tests 07:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:20:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:20:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:20:57 INFO - Downloaded 1361 bytes. 07:20:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:20:57 INFO - Using the following test package requirements: 07:20:57 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:20:57 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:20:57 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:20:57 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'jsshell-linux-x86_64.zip'], 07:20:57 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:20:57 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:20:57 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:20:57 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:20:57 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:20:57 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:20:57 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:20:57 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:20:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:20:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:20:57 INFO - Downloaded 21808441 bytes. 07:20:57 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'] 07:20:57 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 07:20:58 INFO - Return code: 0 07:20:58 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 07:20:58 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')]} 07:20:58 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:20:58 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:20:59 INFO - Downloaded 21808441 bytes. 07:20:59 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'] 07:20:59 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 07:21:00 INFO - Return code: 0 07:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:21:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:21:00 INFO - Downloaded 14413723 bytes. 07:21:00 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'] 07:21:00 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 07:21:00 INFO - Return code: 0 07:21:01 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')]} 07:21:01 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:21:01 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:21:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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 07:21:01 INFO - Downloaded 59376699 bytes. 07:21:01 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:21:01 INFO - mkdir: /builds/slave/test/properties 07:21:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:21:01 INFO - Writing to file /builds/slave/test/properties/build_url 07:21:01 INFO - Contents: 07:21:01 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:21:01 INFO - Running post-action listener: _resource_record_post_action 07:21:01 INFO - Running post-action listener: set_extra_try_arguments 07:21:01 INFO - [mozharness: 2016-08-01 14:21:01.847021Z] Finished download-and-extract step (success) 07:21:01 INFO - [mozharness: 2016-08-01 14:21:01.847259Z] Running populate-webroot step. 07:21:01 INFO - Running pre-action listener: _resource_record_pre_action 07:21:01 INFO - Running main action method: populate_webroot 07:21:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:21:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:21:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:21:01 INFO - '--tppagecycles', 07:21:01 INFO - '7'], 07:21:01 INFO - 'tests': ['tsvgm']}}, 07:21:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:21:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:21:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:21:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:21:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['damp', 'tps']}, 07:21:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'tests': ['damp', 'tps']}, 07:21:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['dromaeo_dom']}, 07:21:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:21:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['basic_compositor_video']}, 07:21:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:21:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['a11yr', 07:21:01 INFO - 'ts_paint', 07:21:01 INFO - 'tpaint', 07:21:01 INFO - 'sessionrestore', 07:21:01 INFO - 'sessionrestore_no_auto_restore', 07:21:01 INFO - 'tabpaint']}, 07:21:01 INFO - 'other-e10s': {'tests': ['a11yr', 07:21:01 INFO - 'ts_paint', 07:21:01 INFO - 'tpaint', 07:21:01 INFO - 'sessionrestore', 07:21:01 INFO - 'sessionrestore_no_auto_restore', 07:21:01 INFO - 'tabpaint']}, 07:21:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:21:01 INFO - 'ts_paint', 07:21:01 INFO - 'tpaint', 07:21:01 INFO - 'sessionrestore', 07:21:01 INFO - 'sessionrestore_no_auto_restore', 07:21:01 INFO - 'tabpaint']}, 07:21:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:21:01 INFO - 'ts_paint', 07:21:01 INFO - 'tpaint', 07:21:01 INFO - 'sessionrestore', 07:21:01 INFO - 'sessionrestore_no_auto_restore', 07:21:01 INFO - 'tabpaint']}, 07:21:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['a11yr', 07:21:01 INFO - 'ts_paint', 07:21:01 INFO - 'tpaint', 07:21:01 INFO - 'sessionrestore', 07:21:01 INFO - 'sessionrestore_no_auto_restore', 07:21:01 INFO - 'tabpaint']}, 07:21:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['a11yr', 07:21:01 INFO - 'ts_paint', 07:21:01 INFO - 'tpaint', 07:21:01 INFO - 'sessionrestore', 07:21:01 INFO - 'sessionrestore_no_auto_restore', 07:21:01 INFO - 'tabpaint']}, 07:21:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['tsvgx', 07:21:01 INFO - 'tsvgr_opacity', 07:21:01 INFO - 'tart', 07:21:01 INFO - 'tscrollx', 07:21:01 INFO - 'cart']}, 07:21:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:21:01 INFO - 'tsvgr_opacity', 07:21:01 INFO - 'tart', 07:21:01 INFO - 'tscrollx', 07:21:01 INFO - 'cart']}, 07:21:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'talos_options': ['--disable-e10s'], 07:21:01 INFO - 'tests': ['tp5o']}, 07:21:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'tests': ['tp5o']}, 07:21:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'talos_options': ['--disable-e10s', 07:21:01 INFO - '--xperf_path', 07:21:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:01 INFO - 'tests': ['tp5n']}, 07:21:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:01 INFO - 'talos_options': ['--xperf_path', 07:21:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:01 INFO - 'tests': ['tp5n']}}, 07:21:01 INFO - 'talos.zip': {'path': '', 07:21:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:21:01 INFO - Running post-action listener: _resource_record_post_action 07:21:01 INFO - [mozharness: 2016-08-01 14:21:01.867394Z] Finished populate-webroot step (success) 07:21:01 INFO - [mozharness: 2016-08-01 14:21:01.867483Z] Running create-virtualenv step. 07:21:01 INFO - Running pre-action listener: _resource_record_pre_action 07:21:01 INFO - Running main action method: create_virtualenv 07:21:01 INFO - Creating virtualenv /builds/slave/test/build/venv 07:21:01 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 07:21:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:21:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:21:02 INFO - Using real prefix '/usr' 07:21:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:21:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:21:05 INFO - Installing pip.................done. 07:21:05 INFO - Return code: 0 07:21:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:05 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:05 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 07:21:05 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 07:21:05 INFO - Using env: {'DISPLAY': ':0', 07:21:05 INFO - 'HOME': '/home/cltbld', 07:21:05 INFO - 'LANG': 'en_US.UTF-8', 07:21:05 INFO - 'LANGUAGE': 'en_US:en', 07:21:05 INFO - 'LOGNAME': 'cltbld', 07:21:05 INFO - 'MAIL': '/var/mail/cltbld', 07:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:05 INFO - 'MOZ_NO_REMOTE': '1', 07:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:05 INFO - 'NO_EM_RESTART': '1', 07:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:05 INFO - 'PWD': '/builds/slave/test', 07:21:05 INFO - 'SHELL': '/bin/bash', 07:21:05 INFO - 'SHLVL': '1', 07:21:05 INFO - 'TERM': 'linux', 07:21:05 INFO - 'TMOUT': '86400', 07:21:05 INFO - 'USER': 'cltbld', 07:21:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:05 INFO - '_': '/tools/buildbot/bin/python'} 07:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:05 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 07:21:05 INFO - Cleaning up... 07:21:05 INFO - Return code: 0 07:21:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:05 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:05 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 07:21:05 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 07:21:05 INFO - Using env: {'DISPLAY': ':0', 07:21:05 INFO - 'HOME': '/home/cltbld', 07:21:05 INFO - 'LANG': 'en_US.UTF-8', 07:21:05 INFO - 'LANGUAGE': 'en_US:en', 07:21:05 INFO - 'LOGNAME': 'cltbld', 07:21:05 INFO - 'MAIL': '/var/mail/cltbld', 07:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:05 INFO - 'MOZ_NO_REMOTE': '1', 07:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:05 INFO - 'NO_EM_RESTART': '1', 07:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:05 INFO - 'PWD': '/builds/slave/test', 07:21:05 INFO - 'SHELL': '/bin/bash', 07:21:05 INFO - 'SHLVL': '1', 07:21:05 INFO - 'TERM': 'linux', 07:21:05 INFO - 'TMOUT': '86400', 07:21:05 INFO - 'USER': 'cltbld', 07:21:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:05 INFO - '_': '/tools/buildbot/bin/python'} 07:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:05 INFO - Downloading/unpacking psutil>=3.1.1 07:21:05 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 07:21:05 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 07:21:05 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 07:21:05 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 07:21:05 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 07:21:05 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 07:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:21:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:07 INFO - Installing collected packages: psutil 07:21:07 INFO - Running setup.py install for psutil 07:21:07 INFO - building 'psutil._psutil_linux' extension 07:21:07 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 07:21:09 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 07:21:09 INFO - building 'psutil._psutil_posix' extension 07:21:09 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 07:21:09 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 07:21:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:09 INFO - Successfully installed psutil 07:21:09 INFO - Cleaning up... 07:21:09 INFO - Return code: 0 07:21:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:21:09 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')]} 07:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:09 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')]} 07:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:09 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:09 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 07:21:09 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 07:21:09 INFO - Using env: {'DISPLAY': ':0', 07:21:09 INFO - 'HOME': '/home/cltbld', 07:21:09 INFO - 'LANG': 'en_US.UTF-8', 07:21:09 INFO - 'LANGUAGE': 'en_US:en', 07:21:09 INFO - 'LOGNAME': 'cltbld', 07:21:09 INFO - 'MAIL': '/var/mail/cltbld', 07:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:09 INFO - 'MOZ_NO_REMOTE': '1', 07:21:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:09 INFO - 'NO_EM_RESTART': '1', 07:21:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:09 INFO - 'PWD': '/builds/slave/test', 07:21:09 INFO - 'SHELL': '/bin/bash', 07:21:09 INFO - 'SHLVL': '1', 07:21:09 INFO - 'TERM': 'linux', 07:21:09 INFO - 'TMOUT': '86400', 07:21:09 INFO - 'USER': 'cltbld', 07:21:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:09 INFO - '_': '/tools/buildbot/bin/python'} 07:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:21:09 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 07:21:09 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 07:21:09 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 07:21:09 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 07:21:09 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 07:21:09 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 07:21:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:21:11 INFO - Installing collected packages: mozsystemmonitor 07:21:11 INFO - Running setup.py install for mozsystemmonitor 07:21:11 INFO - Successfully installed mozsystemmonitor 07:21:11 INFO - Cleaning up... 07:21:11 INFO - Return code: 0 07:21:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:21:11 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')]} 07:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:11 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')]} 07:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:11 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:11 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 07:21:11 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 07:21:11 INFO - Using env: {'DISPLAY': ':0', 07:21:11 INFO - 'HOME': '/home/cltbld', 07:21:11 INFO - 'LANG': 'en_US.UTF-8', 07:21:11 INFO - 'LANGUAGE': 'en_US:en', 07:21:11 INFO - 'LOGNAME': 'cltbld', 07:21:11 INFO - 'MAIL': '/var/mail/cltbld', 07:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:11 INFO - 'MOZ_NO_REMOTE': '1', 07:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:11 INFO - 'NO_EM_RESTART': '1', 07:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:11 INFO - 'PWD': '/builds/slave/test', 07:21:11 INFO - 'SHELL': '/bin/bash', 07:21:11 INFO - 'SHLVL': '1', 07:21:11 INFO - 'TERM': 'linux', 07:21:11 INFO - 'TMOUT': '86400', 07:21:11 INFO - 'USER': 'cltbld', 07:21:11 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:11 INFO - '_': '/tools/buildbot/bin/python'} 07:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:12 INFO - Downloading/unpacking jsonschema==2.5.1 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 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 07:21:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:21:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:14 INFO - Downloading functools32-3.2.3-2.tar.gz 07:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:21:14 INFO - warning: no files found matching '*.txt' 07:21:14 INFO - no previously-included directories found matching 'build' 07:21:14 INFO - no previously-included directories found matching 'dist' 07:21:14 INFO - no previously-included directories found matching '.git*' 07:21:14 INFO - Installing collected packages: jsonschema, functools32 07:21:14 INFO - Running setup.py install for functools32 07:21:14 INFO - warning: no files found matching '*.txt' 07:21:14 INFO - no previously-included directories found matching 'build' 07:21:14 INFO - no previously-included directories found matching 'dist' 07:21:14 INFO - no previously-included directories found matching '.git*' 07:21:14 INFO - Successfully installed jsonschema functools32 07:21:14 INFO - Cleaning up... 07:21:14 INFO - Return code: 0 07:21:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:14 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:14 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 07:21:14 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 07:21:14 INFO - Using env: {'DISPLAY': ':0', 07:21:14 INFO - 'HOME': '/home/cltbld', 07:21:14 INFO - 'LANG': 'en_US.UTF-8', 07:21:14 INFO - 'LANGUAGE': 'en_US:en', 07:21:14 INFO - 'LOGNAME': 'cltbld', 07:21:14 INFO - 'MAIL': '/var/mail/cltbld', 07:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:14 INFO - 'MOZ_NO_REMOTE': '1', 07:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:14 INFO - 'NO_EM_RESTART': '1', 07:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:14 INFO - 'PWD': '/builds/slave/test', 07:21:14 INFO - 'SHELL': '/bin/bash', 07:21:14 INFO - 'SHLVL': '1', 07:21:14 INFO - 'TERM': 'linux', 07:21:14 INFO - 'TMOUT': '86400', 07:21:14 INFO - 'USER': 'cltbld', 07:21:14 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:14 INFO - '_': '/tools/buildbot/bin/python'} 07:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:21:14 INFO - Cleaning up... 07:21:14 INFO - Return code: 0 07:21:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:14 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:14 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 07:21:14 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 07:21:14 INFO - Using env: {'DISPLAY': ':0', 07:21:14 INFO - 'HOME': '/home/cltbld', 07:21:14 INFO - 'LANG': 'en_US.UTF-8', 07:21:14 INFO - 'LANGUAGE': 'en_US:en', 07:21:14 INFO - 'LOGNAME': 'cltbld', 07:21:14 INFO - 'MAIL': '/var/mail/cltbld', 07:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:14 INFO - 'MOZ_NO_REMOTE': '1', 07:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:14 INFO - 'NO_EM_RESTART': '1', 07:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:14 INFO - 'PWD': '/builds/slave/test', 07:21:14 INFO - 'SHELL': '/bin/bash', 07:21:14 INFO - 'SHLVL': '1', 07:21:14 INFO - 'TERM': 'linux', 07:21:14 INFO - 'TMOUT': '86400', 07:21:14 INFO - 'USER': 'cltbld', 07:21:14 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:14 INFO - '_': '/tools/buildbot/bin/python'} 07:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:14 INFO - Downloading/unpacking blobuploader==1.2.4 07:21:14 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 07:21:14 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 07:21:14 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 07:21:14 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 07:21:14 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 07:21:14 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 07:21:16 INFO - Downloading blobuploader-1.2.4.tar.gz 07:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:21:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:21:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 INFO - Downloading docopt-0.6.1.tar.gz 07:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:21:17 INFO - Installing collected packages: blobuploader, requests, docopt 07:21:17 INFO - Running setup.py install for blobuploader 07:21:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:21:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:21:17 INFO - Running setup.py install for requests 07:21:17 INFO - Running setup.py install for docopt 07:21:18 INFO - Successfully installed blobuploader requests docopt 07:21:18 INFO - Cleaning up... 07:21:18 INFO - Return code: 0 07:21:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:18 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:18 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 07:21:18 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 07:21:18 INFO - Using env: {'DISPLAY': ':0', 07:21:18 INFO - 'HOME': '/home/cltbld', 07:21:18 INFO - 'LANG': 'en_US.UTF-8', 07:21:18 INFO - 'LANGUAGE': 'en_US:en', 07:21:18 INFO - 'LOGNAME': 'cltbld', 07:21:18 INFO - 'MAIL': '/var/mail/cltbld', 07:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:18 INFO - 'MOZ_NO_REMOTE': '1', 07:21:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:18 INFO - 'NO_EM_RESTART': '1', 07:21:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:18 INFO - 'PWD': '/builds/slave/test', 07:21:18 INFO - 'SHELL': '/bin/bash', 07:21:18 INFO - 'SHLVL': '1', 07:21:18 INFO - 'TERM': 'linux', 07:21:18 INFO - 'TMOUT': '86400', 07:21:18 INFO - 'USER': 'cltbld', 07:21:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:18 INFO - '_': '/tools/buildbot/bin/python'} 07:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:18 INFO - Running setup.py (path:/tmp/pip-3ZqiR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:18 INFO - Running setup.py (path:/tmp/pip-y9sS9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:18 INFO - Running setup.py (path:/tmp/pip-Cyr9aG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:18 INFO - Running setup.py (path:/tmp/pip-5dlZI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:18 INFO - Running setup.py (path:/tmp/pip-8heuDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:18 INFO - Running setup.py (path:/tmp/pip-KS5YYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:18 INFO - Running setup.py (path:/tmp/pip-gNld1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:18 INFO - Running setup.py (path:/tmp/pip-HTNNI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:18 INFO - Running setup.py (path:/tmp/pip-eVaKkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:18 INFO - Running setup.py (path:/tmp/pip-SlZJT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:19 INFO - Running setup.py (path:/tmp/pip-IfXh5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:19 INFO - Running setup.py (path:/tmp/pip-cpsdr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:19 INFO - Running setup.py (path:/tmp/pip-SVoJ8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:19 INFO - Running setup.py (path:/tmp/pip-pFJchO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:19 INFO - Running setup.py (path:/tmp/pip-8RCoWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:19 INFO - Running setup.py (path:/tmp/pip-XzQidc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:19 INFO - Running setup.py (path:/tmp/pip-ZxM3hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:21:19 INFO - Running setup.py install for manifestparser 07:21:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:21:19 INFO - Running setup.py install for mozcrash 07:21:19 INFO - Running setup.py install for mozdebug 07:21:19 INFO - Running setup.py install for mozdevice 07:21:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:21:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozfile 07:21:20 INFO - Running setup.py install for mozhttpd 07:21:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozinfo 07:21:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozInstall 07:21:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozleak 07:21:20 INFO - Running setup.py install for mozlog 07:21:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for moznetwork 07:21:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozprocess 07:21:20 INFO - Running setup.py install for mozprofile 07:21:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozrunner 07:21:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Running setup.py install for mozscreenshot 07:21:21 INFO - Running setup.py install for moztest 07:21:21 INFO - Running setup.py install for mozversion 07:21:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:21:21 INFO - Cleaning up... 07:21:21 INFO - Return code: 0 07:21:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21:21 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')]} 07:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:21 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')]} 07:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:21 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:21 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 07:21:21 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 07:21:21 INFO - Using env: {'DISPLAY': ':0', 07:21:21 INFO - 'HOME': '/home/cltbld', 07:21:21 INFO - 'LANG': 'en_US.UTF-8', 07:21:21 INFO - 'LANGUAGE': 'en_US:en', 07:21:21 INFO - 'LOGNAME': 'cltbld', 07:21:21 INFO - 'MAIL': '/var/mail/cltbld', 07:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:21 INFO - 'MOZ_NO_REMOTE': '1', 07:21:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:21 INFO - 'NO_EM_RESTART': '1', 07:21:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:21 INFO - 'PWD': '/builds/slave/test', 07:21:21 INFO - 'SHELL': '/bin/bash', 07:21:21 INFO - 'SHLVL': '1', 07:21:21 INFO - 'TERM': 'linux', 07:21:21 INFO - 'TMOUT': '86400', 07:21:21 INFO - 'USER': 'cltbld', 07:21:21 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:21 INFO - '_': '/tools/buildbot/bin/python'} 07:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:21 INFO - Running setup.py (path:/tmp/pip-dMCml0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:21 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)) 07:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:21 INFO - Running setup.py (path:/tmp/pip-NKlFrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:21 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)) 07:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:21 INFO - Running setup.py (path:/tmp/pip-gaCTh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:21 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)) 07:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:21 INFO - Running setup.py (path:/tmp/pip-Wo_DbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:21 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)) 07:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:21 INFO - Running setup.py (path:/tmp/pip-j4eWdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:21 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)) 07:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:21 INFO - Running setup.py (path:/tmp/pip-0uLXjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:22 INFO - Running setup.py (path:/tmp/pip-LtMBcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:22 INFO - Running setup.py (path:/tmp/pip-sH37Fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:22 INFO - Running setup.py (path:/tmp/pip-60GR3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:22 INFO - Running setup.py (path:/tmp/pip-Dv6q78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:22 INFO - Running setup.py (path:/tmp/pip-IL9VvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:22 INFO - Running setup.py (path:/tmp/pip-WbD27W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:22 INFO - Running setup.py (path:/tmp/pip-YxfMs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:22 INFO - Running setup.py (path:/tmp/pip-HZw98l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:22 INFO - Running setup.py (path:/tmp/pip-PtEJxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:22 INFO - Running setup.py (path:/tmp/pip-PzE6HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:22 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)) 07:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:22 INFO - Running setup.py (path:/tmp/pip-qT5AYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:22 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)) 07:21:22 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)) 07:21:22 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)) 07:21:22 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)) 07:21:22 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)) 07:21:22 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)) 07:21:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:21:22 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 07:21:22 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 07:21:22 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 07:21:22 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 07:21:22 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 07:21:22 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 07:21:24 INFO - Downloading blessings-1.6.tar.gz 07:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:21:24 INFO - Installing collected packages: blessings 07:21:24 INFO - Running setup.py install for blessings 07:21:25 INFO - Successfully installed blessings 07:21:25 INFO - Cleaning up... 07:21:25 INFO - Return code: 0 07:21:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:21:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:21:25 INFO - Reading from file tmpfile_stdout 07:21:25 INFO - Current package versions: 07:21:25 INFO - argparse == 1.2.1 07:21:25 INFO - blessings == 1.6 07:21:25 INFO - blobuploader == 1.2.4 07:21:25 INFO - docopt == 0.6.1 07:21:25 INFO - functools32 == 3.2.3-2 07:21:25 INFO - jsonschema == 2.5.1 07:21:25 INFO - manifestparser == 1.1 07:21:25 INFO - mozInstall == 1.12 07:21:25 INFO - mozcrash == 0.17 07:21:25 INFO - mozdebug == 0.1 07:21:25 INFO - mozdevice == 0.48 07:21:25 INFO - mozfile == 1.2 07:21:25 INFO - mozhttpd == 0.7 07:21:25 INFO - mozinfo == 0.9 07:21:25 INFO - mozleak == 0.1 07:21:25 INFO - mozlog == 3.3 07:21:25 INFO - moznetwork == 0.27 07:21:25 INFO - mozprocess == 0.23 07:21:25 INFO - mozprofile == 0.28 07:21:25 INFO - mozrunner == 6.12 07:21:25 INFO - mozscreenshot == 0.1 07:21:25 INFO - mozsystemmonitor == 0.3 07:21:25 INFO - moztest == 0.7 07:21:25 INFO - mozversion == 1.4 07:21:25 INFO - psutil == 3.1.1 07:21:25 INFO - requests == 1.2.3 07:21:25 INFO - wsgiref == 0.1.2 07:21:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21: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')]} 07:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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')]} 07:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:25 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 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:25 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 07:21:25 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 07:21:25 INFO - Using env: {'DISPLAY': ':0', 07:21:25 INFO - 'HOME': '/home/cltbld', 07:21:25 INFO - 'LANG': 'en_US.UTF-8', 07:21:25 INFO - 'LANGUAGE': 'en_US:en', 07:21:25 INFO - 'LOGNAME': 'cltbld', 07:21:25 INFO - 'MAIL': '/var/mail/cltbld', 07:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:25 INFO - 'MOZ_NO_REMOTE': '1', 07:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:25 INFO - 'NO_EM_RESTART': '1', 07:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:25 INFO - 'PWD': '/builds/slave/test', 07:21:25 INFO - 'SHELL': '/bin/bash', 07:21:25 INFO - 'SHLVL': '1', 07:21:25 INFO - 'TERM': 'linux', 07:21:25 INFO - 'TMOUT': '86400', 07:21:25 INFO - 'USER': 'cltbld', 07:21:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:25 INFO - '_': '/tools/buildbot/bin/python'} 07:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 INFO - Cleaning up... 07:21:25 INFO - Return code: 0 07:21:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:21: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')]} 07:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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')]} 07:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1405400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', '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 07:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:21:25 INFO - Using env: {'DISPLAY': ':0', 07:21:25 INFO - 'HOME': '/home/cltbld', 07:21:25 INFO - 'LANG': 'en_US.UTF-8', 07:21:25 INFO - 'LANGUAGE': 'en_US:en', 07:21:25 INFO - 'LOGNAME': 'cltbld', 07:21:25 INFO - 'MAIL': '/var/mail/cltbld', 07:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:25 INFO - 'MOZ_NO_REMOTE': '1', 07:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:25 INFO - 'NO_EM_RESTART': '1', 07:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:25 INFO - 'PWD': '/builds/slave/test', 07:21:25 INFO - 'SHELL': '/bin/bash', 07:21:25 INFO - 'SHLVL': '1', 07:21:25 INFO - 'TERM': 'linux', 07:21:25 INFO - 'TMOUT': '86400', 07:21:25 INFO - 'USER': 'cltbld', 07:21:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:25 INFO - '_': '/tools/buildbot/bin/python'} 07:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:21:25 INFO - Cleaning up... 07:21:25 INFO - Return code: 0 07:21:25 INFO - Running post-action listener: _resource_record_post_action 07:21:25 INFO - Running post-action listener: _start_resource_monitoring 07:21:25 INFO - Starting resource monitoring. 07:21:25 INFO - [mozharness: 2016-08-01 14:21:25.755155Z] Finished create-virtualenv step (success) 07:21:25 INFO - [mozharness: 2016-08-01 14:21:25.755627Z] Running install step. 07:21:25 INFO - Running pre-action listener: _resource_record_pre_action 07:21:25 INFO - Running main action method: install 07:21:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:21:25 INFO - Reading from file tmpfile_stdout 07:21:25 INFO - Detecting whether we're running mozinstall >=1.0... 07:21:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:21:26 INFO - Reading from file tmpfile_stdout 07:21:26 INFO - Output received: 07:21:26 INFO - Usage: mozinstall [options] installer 07:21:26 INFO - Options: 07:21:26 INFO - -h, --help show this help message and exit 07:21:26 INFO - -d DEST, --destination=DEST 07:21:26 INFO - Directory to install application into. [default: 07:21:26 INFO - "/builds/slave/test"] 07:21:26 INFO - --app=APP Application being installed. [default: firefox] 07:21:26 INFO - mkdir: /builds/slave/test/build/application 07:21:26 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'] 07:21:26 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 07:21:43 INFO - Reading from file tmpfile_stdout 07:21:43 INFO - Output received: 07:21:43 INFO - /builds/slave/test/build/application/firefox/firefox 07:21:43 INFO - Running post-action listener: _resource_record_post_action 07:21:43 INFO - [mozharness: 2016-08-01 14:21:43.157608Z] Finished install step (success) 07:21:43 INFO - [mozharness: 2016-08-01 14:21:43.157885Z] Running run-tests step. 07:21:43 INFO - Running pre-action listener: _resource_record_pre_action 07:21:43 INFO - Running main action method: run_tests 07:21:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:21:43 INFO - Python 2.7.3 07:21:43 INFO - Return code: 0 07:21:43 INFO - grabbing minidump binary from tooltool 07:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:43 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 0x1494570>, '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 0x1495470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1495900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:21:43 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 07:21:43 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 07:21:43 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 07:21:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:21:43 INFO - Return code: 0 07:21:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:21:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:21:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:21:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:21:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:21:43 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/1470051002/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 07:21:43 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/1470051002/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 07:21:43 INFO - Using env: {'DISPLAY': ':0', 07:21:43 INFO - 'HOME': '/home/cltbld', 07:21:43 INFO - 'LANG': 'en_US.UTF-8', 07:21:43 INFO - 'LANGUAGE': 'en_US:en', 07:21:43 INFO - 'LOGNAME': 'cltbld', 07:21:43 INFO - 'MAIL': '/var/mail/cltbld', 07:21:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:21:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:43 INFO - 'MOZ_NO_REMOTE': '1', 07:21:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:21:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:21:43 INFO - 'NO_EM_RESTART': '1', 07:21:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:43 INFO - 'PWD': '/builds/slave/test', 07:21:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:21:43 INFO - 'SHELL': '/bin/bash', 07:21:43 INFO - 'SHLVL': '1', 07:21:43 INFO - 'TERM': 'linux', 07:21:43 INFO - 'TMOUT': '86400', 07:21:43 INFO - 'USER': 'cltbld', 07:21:43 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464', 07:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:43 INFO - '_': '/tools/buildbot/bin/python'} 07:21:43 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/1470051002/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 07:21:43 INFO - mozversion application_buildid: 20160801043002 07:21:43 INFO - mozversion application_changeset: c0c16cea7afa66c59d18a6b88cfc95274599a1c7 07:21:43 INFO - mozversion application_display_name: Nightly 07:21:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:21:43 INFO - mozversion application_name: Firefox 07:21:43 INFO - mozversion application_remotingname: firefox 07:21:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:21:43 INFO - mozversion application_vendor: Mozilla 07:21:43 INFO - mozversion application_version: 50.0a1 07:21:43 INFO - mozversion platform_buildid: 20160801043002 07:21:43 INFO - mozversion platform_changeset: c0c16cea7afa66c59d18a6b88cfc95274599a1c7 07:21:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:21:43 INFO - mozversion platform_version: 50.0a1 07:21:43 INFO - using testdate: 1470061303 07:21:43 INFO - actual date: 1470061303 07:21:43 INFO - starting webserver on 'localhost:36117' 07:21:43 INFO - SUITE-START | Running 2 tests 07:21:43 INFO - TEST-START | dromaeo_css 07:21:43 INFO - operating with platform_type : linux_ 07:21:43 INFO - Initialising browser for dromaeo_css test... 07:21:43 INFO - TEST-INFO | started process 3350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkotlr6/profile http://localhost:36117/getInfo.html) 07:21:48 INFO - PROCESS | 3350 | __metrics Screen width/height:1600/1200 07:21:48 INFO - PROCESS | 3350 | colorDepth:24 07:21:48 INFO - PROCESS | 3350 | Browser inner width/height: 1024/697 07:21:48 INFO - PROCESS | 3350 | __metrics 07:21:49 ERROR - PROCESS | 3350 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 07:21:50 INFO - TEST-INFO | 3350: exit 0 07:21:50 INFO - Browser initialized. 07:21:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:21:50 INFO - TEST-INFO | started process 3468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkotlr6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:02 INFO - PROCESS | 3468 | RSS: Main: 162930688 07:22:02 INFO - PROCESS | 3468 | 07:22:51 INFO - PROCESS | 3468 | 1470061371758 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] 07:25:00 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:36117/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36117/tests/dromaeo/cssquery-ext.html) 07:25:01 INFO - PROCESS | 3468 | RSS: Main: 168677376 07:25:01 INFO - PROCESS | 3468 | 07:28:03 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:36117/tests/dromaeo/cssquery-ext.html (next: http://localhost:36117/tests/dromaeo/cssquery-jquery.html) 07:28:03 INFO - PROCESS | 3468 | RSS: Main: 166391808 07:28:03 INFO - PROCESS | 3468 | 07:31:05 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:36117/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36117/tests/dromaeo/cssquery-mootools.html) 07:31:06 INFO - PROCESS | 3468 | RSS: Main: 169394176 07:31:06 INFO - PROCESS | 3468 | 07:34:08 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:36117/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36117/tests/dromaeo/cssquery-prototype.html) 07:34:08 INFO - PROCESS | 3468 | RSS: Main: 163991552 07:34:08 INFO - PROCESS | 3468 | 07:37:11 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:36117/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36117/tests/dromaeo/cssquery-yui.html) 07:37:11 INFO - PROCESS | 3468 | RSS: Main: 166277120 07:37:11 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:36117/tests/dromaeo/cssquery-yui.html (next: http://localhost:36117/tests/dromaeo/cssquery-dojo.html) 07:40:14 INFO - PROCESS | 3468 | RSS: Main: 169668608 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | __start_tp_report 07:40:14 INFO - PROCESS | 3468 | _x_x_mozilla_page_load 07:40:14 INFO - PROCESS | 3468 | _x_x_mozilla_page_load_details 07:40:14 INFO - PROCESS | 3468 | |i|pagename|runs| 07:40:14 INFO - PROCESS | 3468 | |0;dojo.html;3076.2924527358705;3109.8756049758013;3125.5624212610237;3129.702678245566;3131.9060428187163;5384.0847056000475;5420.701861397617;5431.185322201672;5481.040817856874;5502.0921547944645;5412.269343638608;5449.237106805051;5472.2338872557775;5480.150576660614;5553.4724201200825;4898.28974798654;5020.447750747415;5023.3971923369245;5027.296178535008;5076.492350764931;4992.67547609406;5014.2979982802435;5076;5091.592672586185;5122.052420302238;5003.124453220672;5038.093143234217;5081.669691470042;5081.898362032757;5087.262346959707;4867.36724225852;4872.9756351218375;4892.730899800509;4975.079610272075;4983.651144419892;4865.562099411035;4872.171730805771;4886.096072226614;4905.8773530661665;4917.170915583743;5326.840194794162;5394.406615272317;5406.864828379283;5409.972950135263;5418.349798024223;5666.829995100154;5781.682007489587;5847;5877.089051197071;5886.264216972879;5922.7927022554;5925.407459254083;5928.28860536734;5929.9703501482645;5933.169356290123;5507.476789704971;5511;5513.862153446196;5518;5531.723413829292;5816.12758086282;5936.762529498858;5977.133315669291;5979.282486101609;6075.939240607538;5790.7973220937065;5793.710314484343;5795.217645617788;5796.507296879812;5797.507211887037;5855.238818953509;5858.8242352729485;5867.413258674183;5869.00226961409;5873.706314684248;5533.979613230409;5756.539476841843;5813.273340832396;5817.360090390053;5880.2943646761805;5660.405657405995;5715.142728590745;5767.192593036978;5776.104703770922;5776.306843178845;4475.843345482893;4476.7090139140855;4477.686561940698;4478.328250762346;4521.073179998092;3928.2143571285287;3940.822662980173;3980.6218409251073;3996.7002474814503;4029.5567487576345;5566.582506311962;5579.637323574036;5636.154576813511;5685.744141513559;5690.5447564194765;5876.618019828783;5901.852453688606;5929.673867937218;5931.58478906481;5935.73289201987;5450.291462109901;5527.060399732055;5623.3533143689;5626.324841019104;5645.5483561314995;4961.627877909171;5013.699178049329;5019.0965626187635;5041.420236672756;5054.848354549369;6057.061155520902;6080.969595152084;6082.847928801815;6084.574079814371;6089.634621922699;5662.660240385425;5812.82561523155;5877.089051197071;5888.735006924613;5913.083472061838;5991.700414979146;6003.429674180946;6069.544784141295;6088.939110609014;6096;5710.885782284414;5771.480566749013;5773.018586840163;5779.306483222041;5784.681842498703;5661.264035675501;5831.329397119216;5831.970840145772;5862.589618726648;5881.441263080001;5668.319801623831;5670.461306175906;5687.77248910039;5718.914216286676;5726.599138060295;5611.775528978795;5616.719164041863;5641.182028605911;5657.462541058593;5679.630823996427;5541.695206763667;5546.722663866871;5585.441455854382;5612.102063669793;5616.971915140398;5741.282339707536;5828.912566311424;5832.24180856486;5835.591508594527;5837.67892765902;5616.410276920865;5634.154876768517;5656.8020119295625;5674.602777805679;5690.601657883908 07:40:14 INFO - PROCESS | 3468 | |1;ext.html;586.5307753796961;601.440660186027;606.8136792570525;618.3013195089549;621.7357623010216;32086;35471.64528354716;42253.577464225345;42941.570584294146;43352.78323608385;45647.77176114115;46155;46273;46322.30516542255;46439.30341044887;8881.245094167003;9080.59137338818;9156.587953542074;9160.816783664322;9161.679341223025;8936.821263574724;8984.730458086267;9034.548272586377;9061.592228349708;9068.047854975232;9062.592183351764;9128.954355228248;9131.634734610641;9165.266778657691;9169.908300917006;8984.820303593924;9028;9052.094790520961;9064.18716256748;9084.046175151612;8993.505357205351;9017.053209413049;9045.728628141167;9063.637454501812;9064.365494415393;43288;43758.781206094085;43763.78118109389;43810;43815.34276985816;3561.4835848802036;3657.5976642569294;3665.9450108248398;3710.2208536207427;3806.5622453417936;32674.6732532674;32807.17982050444;32993.3401331972;33048.6695133048;33519.32961340784;4277.636400905927;4338.132373525276;4353.216421044211;4406.603405693471;4489.910201795946;373301;375942;376540;377226.11386943166;378567;59065.40934590599;59457.10814337788;59528.404715953155;59577.40422595719;59671;34927.82536087303;35910.46134307988;35981;37534.24931501355;62077.68961155256;14830.70338593222;15050.247487625575;15057.247137643075;15058.472953446577;15074.170920599254;10818.729531761612;10906.781864362827;10976;10982.231243813016;10996.12031037515;19022.52443688891;19040.238390464503;19191;19207.32774352919;19436.90281548583;7262.20115787263;7282;7286.890696639554;7394.447166095073;7588.62056897153;888.3870129610677;930.1582068228264;958.2286259560981;994.3636072913341;1072.860528131338;32463.18842028968;32710.83644581762;33022.3395532088;33116;33427.66572334294;1053.3153450257241;1062.7077553672648;1097.237419993097;1123.7246874515795;1161.6805378520974;17394.826051739572;17507.47477575675;17558.38545650896;17571.385001524886;17747;153595.23202383917;156121;156268;156308;156966;61048.69475652682;61094.38905610887;61425;61770.38229617646;61798.69100654468;1285.8135570342247;1328.5682153300215;1338.1302153600043;1349.3388239762446;1354.133354653023;30663.386732264782;31144.998835728868;31769.8411507941;32353.676463235068;32597.34805303833;53688.73155634197;53823.73088134534;53931;53949.46050539445;54013.64965875728;457556;457944;458591;459171;459467;29041.419171616028;29461.558076629295;29757.702422975493;29888.85055574795;29973.400531988802;50059;50243;50438.49561504338;51155;52339;41415.745373560734;43293.78353108215;43459.34810977901;43712.78143609261;43781.78109109562;43065;43368;44147;44587.77706111449;44600.55399445964;43134.35298470463;43211.783941081354;43289.783551082044;43391.34912976245;44043.55956440394;37196;37867.62132378641;38600.613993859704;39650.801745991084;40812.79593602013;29091.85454072716;29252.414951701274;29624.407511850073;30154.84922575373;30517.694823051486 07:40:14 INFO - PROCESS | 3468 | |2;jquery.html;390.135849094256;395.53919683568637;395.9683225341973;399.5026192390474;409.6681687832856;13713;18605.53486162845;19373.128209230585;20039.499012524695;20086.69869951952;18986.905065474653;19021.42935711931;19309.13108910099;19316.22735090598;19556.11997460114;5675.801346952847;5726.141078838166;5859.7656093756195;5877.617954832944;5895.4399332063385;5730.3123625165035;5747.540196784247;5782.826515204551;5806.477417032466;5811.476967072963;5695.77216911323;5791.247137872071;5800.187973683687;5815.069588865782;5872.647641141524;5745.655260684331;5793;5808.448197421239;5818.767249310023;5820.767169313222;5151.201563757623;5172.612054095958;5197.454267301954;5198.376194856642;5238.580913526908;22431.551368972694;22604.54790904173;23128.306150815504;23231.3030609082;23405.765942340528;2243.932682019542;2467.728549859514;2472.6291056341693;3060.6786287439945;3091.520814273791;14487.348069336906;14856.777148342784;14878.107313561113;14882.330295136744;14908.254587270701;1921.4715953112866;1925.922963081475;1932.3526618582791;1995.1919472613615;2023.7672667643262;78429;78953.60523197404;79077.60461197657;79611.60194199049;79899;10083.848742268872;10291.53688084023;10328.48357582118;10478.73803154912;10819.891801082045;14401;14511.927440362731;14594;14605;14610.26948652563;7340.339369456775;7600.2779735925;7652.502587331805;7797.961010195027;7812.804679883048;7144.9642751785905;7156.998020277166;7242.718543658731;7325.926740732524;7428.33145016951;6293.244810622663;6477.222733272038;6500.577462465019;6537.05212744159;6542.738290468328;1886.5189376709127;1894.6779047561947;1930.6042261336672;1935.4774210963242;1953.2772874036698;1023.5547536821507;1049.7952899184738;1089.2429761315968;1102.0962810495316;1170.7248796532808;19357.4192774217;19629.31297404584;19703.605927881363;19722.211111555665;20020.199192032156;999.3703966501204;1002.1131298800614;1069.7379142110233;1072.538808312433;1112.5327362507767;9764.511774411392;10996.505157267942;10998.560057597606;11047.447627618587;11094.611688590861;383574;386529;509008;528382;534020;86489.56755216309;88845;96749.5162524197;97172.51413742887;103262.48368758259;999.8300288950895;1003.5032658833924;1038.8285932821;1054.836500342448;1058.4548957287004;16789.74815377746;17018.404355847484;17193.14034298305;17729.202185901922;17932.64134717272;31454.528182077745;36950.26099478049;42602.14795704006;42766;43970;56150;56411.64089411499;57330.713346433;59693.701531492065;63181.68409157925;7572.962135189509;7699.615019249127;7989.600519973862;8013.318867896294;8348.540830254395;9584.952075239578;11505.367204803817;12027.27836329823;12441.440135193758;12543.435545400662;9951.452670103214;10399.272050956662;10589.894101058891;10631.989960953697;10813.891861081604;10658.360498370123;10781.568737250422;10825.56697732082;10885.129189664649;10948.178886583697;10445.955404459493;10452.216083793897;10747.731306717395;10754.247202695737;10857.457127143769;7519.924800751923;7581.712303308141;7608.619569021416;7733.110692270236;7884.4086693497065;7786.688532458638;9252;10444.164466842481;10485.842712359168;10549.89450105489 07:40:14 INFO - PROCESS | 3468 | |3;mootools.html;1463.8508770615063;1521.170961825805;1673.8326167383264;1789.5168304557762;1796.946091617252;8095.473794203387;8713.867197264355;8869.20177184055;9402.34183607147;9438.622455101788;8907;9245.768855778591;9284.350095493319;9287.931887832901;9299.209567186797;2419.600765873629;2472.0359059966627;2474.542209691213;2476.19523654812;2478.5910324796387;2386.7971222446026;2424.248482970276;2440.4264997725522;2451.4361696809747;2451.570975079363;2423.212455951814;2424.624183251599;2436.11081955086;2467.9136230231948;2473.492933948545;2378.4053986503372;2401.591729406005;2411.2163546847255;2416.565018296706;2421.188901717909;2374.2402431222017;2377.75033621469;2392.5214957008493;2398.4963157736897;2401.3756423329887;9245.630174793068;9392.12157568489;9398.295127865369;9401.670941517083;9403.764905877408;5469.398366179717;5590.748416321276;5605.859853503654;5615.241942337773;5619.325680918275;3499.6850283474355;3603.6756691897594;3615.6745892869767;3687.0597997510463;3697.6487233712755;1132.9263597866195;1142.1376860470375;1158.9884101158964;1159.4376727287258;1160.2052593973071;13059.347032648424;13134.934325328408;13139.934300328438;13149.342532873414;13170.999999999904;4497.662675299301;4646.140464014168;4769.785359658824;4813.133635945564;4821.566059054702;10018.448985305733;10055.592217089614;10059.748506287402;10064.647737329306;10082.74793130163;5443.564514838803;5470.070088085035;5474.753636086386;5487.835364939058;5501.064818980783;4503.41455610775;4941.308216849644;5007.39911210657;5080.77136528857;5104.3619547556555;3863.942040869389;3872.5352957645264;3993.4009898514873;4005.0788318686864;4049.230646177217;1213.7147770273978;1234.4876876096318;1238.325112813519;1241.528219276669;1249.1755441408627;893.4773157702687;1011.145581983223;1016.8068067067381;1018.7554986803113;1026.2200727447193;5839.445252700986;6029.306629737549;6051.152838602599;6060.060690592965;6156.015037593964;453.82074080737925;455.8632410276904;458.7752001519232;1057.7302787789065;1082.1451053667672;4097.323941549671;4128.587141285848;4147.3778933160265;4160.750354978711;4240.618344349024;11211.383373914514;11427.257228280136;11511.884881151249;11595.304281743123;11672.241304315192;11419.37193454368;11422.771544569228;11442.199046066862;11499;11513.827292590619;8324.45891017094;8353.16468353172;8421.70524031656;8458.36562257833;8466.872996905051;6078.331383547717;6184.412480814315;6190.907136392868;6211.844703882259;6227.844303892439;10158.796824063329;10343.069123778896;10391.168706503613;10423.270371073952;10432.947835260775;9967.112825997721;10303.227257955834;10325.586976520855;10456.895431045592;10519.474026298501;7126.2873712628325;7574.242575742602;7657.502262352929;7785.065792104983;7900.170482099296;9191.448513089235;9236.861447078434;9243.58403871814;9277.62889484413;9304.72085837426;8097.797555061171;8152.429329946846;8182.918170818454;8217.630206640595;8451.577421129043;7887.566183859943;8022.799430014296;8070.677172913018;8076.515409075474;8171.632276547451;7162.820929476805;7219.169795473378;7282.3809976152625;7303.160136584361;7448.143463501767;5318.069337865781;5340.465953404784;5378.677279363094;5403.83788486346;5443.265159203457;5136.666116702402;5230.189320655304;5260.921086183786;5491.560675145899;5633.38032816398 07:40:14 INFO - PROCESS | 3468 | |4;prototype.html;314.79223712349847;325.4759836662973;328.916221051635;341.78809138334236;343.673510165343;5375;5493.176023596463;5561.304836895388;5590.357108932474;5662;5496.862578435531;5510.283663123799;5521.129012913293;5525.585581081415;5531;4276.764777937212;4277.443932288798;4283.935740963888;4293.484781826185;4299.527052024275;4132.710710250269;4244.259853460082;4282.914341713179;4300.806463709125;4328.285832837578;4212.810423530948;4221.142697213217;4237.300845360527;4254.893627659302;4272.359146128075;4244.808983595745;4251.914961700764;4262.190183865056;4299.871003869889;4320.0711846952745;3649.2701459708164;3778.7543809652284;3800.1069748609057;3800.9809950950344;3803;5371.5165635092635;5381.623286369956;5384.273123128406;5390.380106287748;5399.7030163341;4912.242134766906;4915.950840491585;4943.06081844452;4948;4948.158813001798;5545;5767.88464230713;5779.075347944308;5791.826245212608;5820.883582328372;4053.351463765783;4057.350823868167;4064.979675101635;4073.002114481941;4090.754554726726;6025.186599809013;6049.304330002062;6077.453029227391;6096.664683442409;6104.481119104881;3963.3064213762477;4160.167966406731;4192.685548583869;4192.769397683096;4195.685323600742;4207.221663992172;4222.873313800591;4232.873013809591;4233.25917964355;4239.300515414983;4852.757362131879;4877.463479017305;4922.606191504671;4928;4928.654994150375;5636.210930469738;5739.311282646109;5874.206982057368;5884.882302354014;5885.588008839426;1467.5377256164275;1482.2588705647177;1490.4410845932775;1491.8060652115155;1491.9477818276525;905.5291248550717;934.2619330728648;944.4663764972769;950.410745337881;968.0706521739069;4839.322494850724;4871;4881.877953051202;4902.2646603009125;5046.697198168122;5211.973940130351;5228.895422091537;5231.319928409359;5292.655977361459;5335.3864305604575;4708.788104535304;4949.267756285343;4996.277029019193;4996.725834912074;5051.363018626583;2754.663987965835;2785.2758282846203;2810.1429064135637;2810.2271875234064;2864.4102523099878;5416;5422.91865622016;5438.673679579238;5442.183672449164;5442.51678925488;5165.690058596496;5267.341582302212;5340.385855626575;5371.704556249444;5384.326959130109;5388.595855310868;5390.355941437821;5408.9175667368345;5434.646747961449;5543.916841247385;5306.787728490817;5349.545288650353;5359.990003748594;5389.302369753515;5389.7930731214865;4884.876478409915;5150.433452320317;5173.3533308336455;5188.273641690242;5211.426743058185;5432.3431353436;5438.265834112344;5455.61810673265;5484.7806087757635;5511.12621708621;4025.7987100645437;4223.662107031491;4288.656907447335;4355.934660980024;4380.6057454829215;5952.345242023461;5985.610935289193;6018.729157188025;6020.909686354796;6036.215292012186;5720.3421606515785;5833.212516310244;5839.5912286141265;5861.296644402699;5898.793542226002;5705.7432415542235;5729.2265544151005;5734.483896449339;5763.6541807491685;5779.711014449176;5699.601027928005;5736.533290685045;5759.424057594207;5759.452851979056;5761.222234998221;5574.442555744393;5782.814523022686;5787.421257874179;5804.807355954293;5823.970880145572;5647.29408823897;5648.378678345461;5651.06144804691;5655.321361436654;5691.7438715257085 07:40:14 INFO - PROCESS | 3468 | |5;yui.html;769.7498313048261;796.0328201235498;805.9959700201498;844.6705784743949;868.7784614923191;16049.83950160498;17363.392281270142;17499.825001749963;17981.730274045865;17982.190801413934;16334.75497867533;16415.097169655663;16450.506484805475;16468;16511.33954641816;814.1085511365046;832.342449464925;834.1700008491559;835.1147783349638;836.7991681996338;676.5331920974536;798.5368486277945;825.529448214518;826.8470332988386;834.4826207751203;879.1120967822513;879.463527248378;880.0143838900409;880.995595022024;882.0517943211074;875.1860769484377;875.5359659380507;876.4565969099135;877.4428238068808;877.8068824858585;631.8767840271149;634.7365843175077;635.9523035772289;636.2555809702659;638.1480723234488;15313.310901009481;15397;15414.922925385414;15425.228738563026;15438.5368438947;15476.76784848228;15569.532914012598;15620;15777.605559861095;15815.288312025872;12562.560310389092;12768.872311276862;12892.548760793326;12973.351332433434;13839.100458470268;3990.5809889961715;3993.920121597581;3995.440638310639;4030.052937559671;4077.408775727503;77048.22951770523;77812.22187778106;81823.5908820452;83969.58015209946;84520.57739711323;11989.940050299692;12211.389430528438;12269.141160118706;12309.56916507918;12477.438515266835;21108.261210857545;21308.36074917755;21338.359849204237;21369.145234190768;21389.78610213878;8747.12528747133;8820.426672266281;8872.334574906909;9082.31882608807;9088.681896133736;4942.728149951788;5054.671446355975;5101.464346243665;5113.8721531961255;5205.947940520622;3841.961580384216;3941.1132495188926;3946.9013274668364;3951.150502641888;3954.9209015819524;732.6300218389786;753.4876284126847;767.781182363035;769.6075001749036;781.9491733037335;728.0135416510658;746.4625469661835;781.5701364249753;794.6503538443067;816.7672213419147;10347.689569312932;10671.345941379099;10847.349159050482;11206.831897521544;11433.828492572618;688.5180373738303;706.2972342519226;715.6493318274113;767.9270469305407;790.6165509727778;5042.85132625599;5288.576913846883;5370.2213179089595;5423.105187643994;5443.101888188405;71006;83057;124544;130420.3478982599;131628;66546.66726666433;67603.32396675971;70106.64946675234;113185.43407282911;115715;96253.5187324059;100964.49517752364;102547.4872625647;102594;103508.48245758873;9700.175485083846;10035.548400321857;10095.545700443645;10278.640247591298;10311.948440257751;54412.72793636007;55354.16968745391;57173.42826571681;57589.12054397347;58343.124853126385;80498;82396;82714;84336;86483;12429.813552796535;12599.685007874876;12816.807747883602;12846.871531284567;12949.223046617233;14504.927475362556;15415.691686166438;15547;15708.214589270263;15790.289436975134;14814.703705926036;15015.699686006;15558.611034724223;15990.840091598935;15994.600134996717;14599.781003285172;14837.851621484078;15054.096754194783;15540.84459155394;15864.206789660702;13654.522091726742;13847.584572462842;14181.716365672422;14541.636459088608;14549.563513094625;12155.513779448724;12371.814422783485;13061.281629510087;13106.60680179596;13287.800682989957;12568.120231584067;12645.304508252135;12962.870371296167;13049.151805132637;13080.476780928657 07:40:14 INFO - PROCESS | 3468 | __end_tp_report 07:40:14 INFO - PROCESS | 3468 | __start_cc_report 07:40:14 INFO - PROCESS | 3468 | _x_x_mozilla_cycle_collect,316 07:40:14 INFO - PROCESS | 3468 | __end_cc_report 07:40:14 INFO - PROCESS | 3468 | __startTimestamp1470062414963__endTimestamp 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | ------- Summary: start ------- 07:40:14 INFO - PROCESS | 3468 | Number of tests: 6 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | [#0] dojo.html Cycles:165 Average:5431.94 Median:5641.18 stddev:622.01 (11.0%) stddev-sans-first:595.83 07:40:14 INFO - PROCESS | 3468 | Values: 3076.3 3109.9 3125.6 3129.7 3131.9 5384.1 5420.7 5431.2 5481.0 5502.1 5412.3 5449.2 5472.2 5480.2 5553.5 4898.3 5020.4 5023.4 5027.3 5076.5 4992.7 5014.3 5076.0 5091.6 5122.1 5003.1 5038.1 5081.7 5081.9 5087.3 4867.4 4873.0 4892.7 4975.1 4983.7 4865.6 4872.2 4886.1 4905.9 4917.2 5326.8 5394.4 5406.9 5410.0 5418.3 5666.8 5781.7 5847.0 5877.1 5886.3 5922.8 5925.4 5928.3 5930.0 5933.2 5507.5 5511.0 5513.9 5518.0 5531.7 5816.1 5936.8 5977.1 5979.3 6075.9 5790.8 5793.7 5795.2 5796.5 5797.5 5855.2 5858.8 5867.4 5869.0 5873.7 5534.0 5756.5 5813.3 5817.4 5880.3 5660.4 5715.1 5767.2 5776.1 5776.3 4475.8 4476.7 4477.7 4478.3 4521.1 3928.2 3940.8 3980.6 3996.7 4029.6 5566.6 5579.6 5636.2 5685.7 5690.5 5876.6 5901.9 5929.7 5931.6 5935.7 5450.3 5527.1 5623.4 5626.3 5645.5 4961.6 5013.7 5019.1 5041.4 5054.8 6057.1 6081.0 6082.8 6084.6 6089.6 5662.7 5812.8 5877.1 5888.7 5913.1 5991.7 6003.4 6069.5 6088.9 6096.0 5710.9 5771.5 5773.0 5779.3 5784.7 5661.3 5831.3 5832.0 5862.6 5881.4 5668.3 5670.5 5687.8 5718.9 5726.6 5611.8 5616.7 5641.2 5657.5 5679.6 5541.7 5546.7 5585.4 5612.1 5617.0 5741.3 5828.9 5832.2 5835.6 5837.7 5616.4 5634.2 5656.8 5674.6 5690.6 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | [#1] ext.html Cycles:180 Average:51123.80 Median:43291.78 stddev:93943.02 (217.0%) stddev-sans-first:94129.92 07:40:14 INFO - PROCESS | 3468 | Values: 586.5 601.4 606.8 618.3 621.7 32086.0 35471.6 42253.6 42941.6 43352.8 45647.8 46155.0 46273.0 46322.3 46439.3 8881.2 9080.6 9156.6 9160.8 9161.7 8936.8 8984.7 9034.5 9061.6 9068.0 9062.6 9129.0 9131.6 9165.3 9169.9 8984.8 9028.0 9052.1 9064.2 9084.0 8993.5 9017.1 9045.7 9063.6 9064.4 43288.0 43758.8 43763.8 43810.0 43815.3 3561.5 3657.6 3665.9 3710.2 3806.6 32674.7 32807.2 32993.3 33048.7 33519.3 4277.6 4338.1 4353.2 4406.6 4489.9 373301.0 375942.0 376540.0 377226.1 378567.0 59065.4 59457.1 59528.4 59577.4 59671.0 34927.8 35910.5 35981.0 37534.2 62077.7 14830.7 15050.2 15057.2 15058.5 15074.2 10818.7 10906.8 10976.0 10982.2 10996.1 19022.5 19040.2 19191.0 19207.3 19436.9 7262.2 7282.0 7286.9 7394.4 7588.6 888.4 930.2 958.2 994.4 1072.9 32463.2 32710.8 33022.3 33116.0 33427.7 1053.3 1062.7 1097.2 1123.7 1161.7 17394.8 17507.5 17558.4 17571.4 17747.0 153595.2 156121.0 156268.0 156308.0 156966.0 61048.7 61094.4 61425.0 61770.4 61798.7 1285.8 1328.6 1338.1 1349.3 1354.1 30663.4 31145.0 31769.8 32353.7 32597.3 53688.7 53823.7 53931.0 53949.5 54013.6 457556.0 457944.0 458591.0 459171.0 459467.0 29041.4 29461.6 29757.7 29888.9 29973.4 50059.0 50243.0 50438.5 51155.0 52339.0 41415.7 43293.8 43459.3 43712.8 43781.8 43065.0 43368.0 44147.0 44587.8 44600.6 43134.4 43211.8 43289.8 43391.3 44043.6 37196.0 37867.6 38600.6 39650.8 40812.8 29091.9 29252.4 29624.4 30154.8 30517.7 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | [#2] jquery.html Cycles:180 Average:28247.97 Median:2470.18 stddev:78238.88 (3167.3%) stddev-sans-first:78430.40 07:40:14 INFO - PROCESS | 3468 | Values: 390.1 395.5 396.0 399.5 409.7 13713.0 18605.5 19373.1 20039.5 20086.7 18986.9 19021.4 19309.1 19316.2 19556.1 5675.8 5726.1 5859.8 5877.6 5895.4 5730.3 5747.5 5782.8 5806.5 5811.5 5695.8 5791.2 5800.2 5815.1 5872.6 5745.7 5793.0 5808.4 5818.8 5820.8 5151.2 5172.6 5197.5 5198.4 5238.6 22431.6 22604.5 23128.3 23231.3 23405.8 2243.9 2467.7 2472.6 3060.7 3091.5 14487.3 14856.8 14878.1 14882.3 14908.3 1921.5 1925.9 1932.4 1995.2 2023.8 78429.0 78953.6 79077.6 79611.6 79899.0 10083.8 10291.5 10328.5 10478.7 10819.9 14401.0 14511.9 14594.0 14605.0 14610.3 7340.3 7600.3 7652.5 7798.0 7812.8 7145.0 7157.0 7242.7 7325.9 7428.3 6293.2 6477.2 6500.6 6537.1 6542.7 1886.5 1894.7 1930.6 1935.5 1953.3 1023.6 1049.8 1089.2 1102.1 1170.7 19357.4 19629.3 19703.6 19722.2 20020.2 999.4 1002.1 1069.7 1072.5 1112.5 9764.5 10996.5 10998.6 11047.4 11094.6 383574.0 386529.0 509008.0 528382.0 534020.0 86489.6 88845.0 96749.5 97172.5 103262.5 999.8 1003.5 1038.8 1054.8 1058.5 16789.7 17018.4 17193.1 17729.2 17932.6 31454.5 36950.3 42602.1 42766.0 43970.0 56150.0 56411.6 57330.7 59693.7 63181.7 7573.0 7699.6 7989.6 8013.3 8348.5 9585.0 11505.4 12027.3 12441.4 12543.4 9951.5 10399.3 10589.9 10632.0 10813.9 10658.4 10781.6 10825.6 10885.1 10948.2 10446.0 10452.2 10747.7 10754.2 10857.5 7519.9 7581.7 7608.6 7733.1 7884.4 7786.7 9252.0 10444.2 10485.8 10549.9 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | [#3] mootools.html Cycles:180 Average:6008.58 Median:4500.54 stddev:3444.01 (76.5%) stddev-sans-first:3436.74 07:40:14 INFO - PROCESS | 3468 | Values: 1463.9 1521.2 1673.8 1789.5 1796.9 8095.5 8713.9 8869.2 9402.3 9438.6 8907.0 9245.8 9284.4 9287.9 9299.2 2419.6 2472.0 2474.5 2476.2 2478.6 2386.8 2424.2 2440.4 2451.4 2451.6 2423.2 2424.6 2436.1 2467.9 2473.5 2378.4 2401.6 2411.2 2416.6 2421.2 2374.2 2377.8 2392.5 2398.5 2401.4 9245.6 9392.1 9398.3 9401.7 9403.8 5469.4 5590.7 5605.9 5615.2 5619.3 3499.7 3603.7 3615.7 3687.1 3697.6 1132.9 1142.1 1159.0 1159.4 1160.2 13059.3 13134.9 13139.9 13149.3 13171.0 4497.7 4646.1 4769.8 4813.1 4821.6 10018.4 10055.6 10059.7 10064.6 10082.7 5443.6 5470.1 5474.8 5487.8 5501.1 4503.4 4941.3 5007.4 5080.8 5104.4 3863.9 3872.5 3993.4 4005.1 4049.2 1213.7 1234.5 1238.3 1241.5 1249.2 893.5 1011.1 1016.8 1018.8 1026.2 5839.4 6029.3 6051.2 6060.1 6156.0 453.8 455.9 458.8 1057.7 1082.1 4097.3 4128.6 4147.4 4160.8 4240.6 11211.4 11427.3 11511.9 11595.3 11672.2 11419.4 11422.8 11442.2 11499.0 11513.8 8324.5 8353.2 8421.7 8458.4 8466.9 6078.3 6184.4 6190.9 6211.8 6227.8 10158.8 10343.1 10391.2 10423.3 10432.9 9967.1 10303.2 10325.6 10456.9 10519.5 7126.3 7574.2 7657.5 7785.1 7900.2 9191.4 9236.9 9243.6 9277.6 9304.7 8097.8 8152.4 8182.9 8217.6 8451.6 7887.6 8022.8 8070.7 8076.5 8171.6 7162.8 7219.2 7282.4 7303.2 7448.1 5318.1 5340.5 5378.7 5403.8 5443.3 5136.7 5230.2 5260.9 5491.6 5633.4 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | [#4] prototype.html Cycles:180 Average:4692.39 Median:5299.72 stddev:1361.80 (25.7%) stddev-sans-first:1325.39 07:40:14 INFO - PROCESS | 3468 | Values: 314.8 325.5 328.9 341.8 343.7 5375.0 5493.2 5561.3 5590.4 5662.0 5496.9 5510.3 5521.1 5525.6 5531.0 4276.8 4277.4 4283.9 4293.5 4299.5 4132.7 4244.3 4282.9 4300.8 4328.3 4212.8 4221.1 4237.3 4254.9 4272.4 4244.8 4251.9 4262.2 4299.9 4320.1 3649.3 3778.8 3800.1 3801.0 3803.0 5371.5 5381.6 5384.3 5390.4 5399.7 4912.2 4916.0 4943.1 4948.0 4948.2 5545.0 5767.9 5779.1 5791.8 5820.9 4053.4 4057.4 4065.0 4073.0 4090.8 6025.2 6049.3 6077.5 6096.7 6104.5 3963.3 4160.2 4192.7 4192.8 4195.7 4207.2 4222.9 4232.9 4233.3 4239.3 4852.8 4877.5 4922.6 4928.0 4928.7 5636.2 5739.3 5874.2 5884.9 5885.6 1467.5 1482.3 1490.4 1491.8 1491.9 905.5 934.3 944.5 950.4 968.1 4839.3 4871.0 4881.9 4902.3 5046.7 5212.0 5228.9 5231.3 5292.7 5335.4 4708.8 4949.3 4996.3 4996.7 5051.4 2754.7 2785.3 2810.1 2810.2 2864.4 5416.0 5422.9 5438.7 5442.2 5442.5 5165.7 5267.3 5340.4 5371.7 5384.3 5388.6 5390.4 5408.9 5434.6 5543.9 5306.8 5349.5 5360.0 5389.3 5389.8 4884.9 5150.4 5173.4 5188.3 5211.4 5432.3 5438.3 5455.6 5484.8 5511.1 4025.8 4223.7 4288.7 4355.9 4380.6 5952.3 5985.6 6018.7 6020.9 6036.2 5720.3 5833.2 5839.6 5861.3 5898.8 5705.7 5729.2 5734.5 5763.7 5779.7 5699.6 5736.5 5759.4 5759.5 5761.2 5574.4 5782.8 5787.4 5804.8 5824.0 5647.3 5648.4 5651.1 5655.3 5691.7 07:40:14 INFO - PROCESS | 3468 | 07:40:14 INFO - PROCESS | 3468 | [#5] yui.html Cycles:180 Average:21727.57 Median:3949.03 stddev:30802.38 (780.0%) stddev-sans-first:30848.59 07:40:14 INFO - PROCESS | 3468 | Values: 769.7 796.0 806.0 844.7 868.8 16049.8 17363.4 17499.8 17981.7 17982.2 16334.8 16415.1 16450.5 16468.0 16511.3 814.1 832.3 834.2 835.1 836.8 676.5 798.5 825.5 826.8 834.5 879.1 879.5 880.0 881.0 882.1 875.2 875.5 876.5 877.4 877.8 631.9 634.7 636.0 636.3 638.1 15313.3 15397.0 15414.9 15425.2 15438.5 15476.8 15569.5 15620.0 15777.6 15815.3 12562.6 12768.9 12892.5 12973.4 13839.1 3990.6 3993.9 3995.4 4030.1 4077.4 77048.2 77812.2 81823.6 83969.6 84520.6 11989.9 12211.4 12269.1 12309.6 12477.4 21108.3 21308.4 21338.4 21369.1 21389.8 8747.1 8820.4 8872.3 9082.3 9088.7 4942.7 5054.7 5101.5 5113.9 5205.9 3842.0 3941.1 3946.9 3951.2 3954.9 732.6 753.5 767.8 769.6 781.9 728.0 746.5 781.6 794.7 816.8 10347.7 10671.3 10847.3 11206.8 11433.8 688.5 706.3 715.6 767.9 790.6 5042.9 5288.6 5370.2 5423.1 5443.1 71006.0 83057.0 124544.0 130420.3 131628.0 66546.7 67603.3 70106.6 113185.4 115715.0 96253.5 100964.5 102547.5 102594.0 103508.5 9700.2 10035.5 10095.5 10278.6 10311.9 54412.7 55354.2 57173.4 57589.1 58343.1 80498.0 82396.0 82714.0 84336.0 86483.0 12429.8 12599.7 12816.8 12846.9 12949.2 14504.9 15415.7 15547.0 15708.2 15790.3 14814.7 15015.7 15558.6 15990.8 15994.6 14599.8 14837.9 15054.1 15540.8 15864.2 13654.5 13847.6 14181.7 14541.6 14549.6 12155.5 12371.8 13061.3 13106.6 13287.8 12568.1 12645.3 12962.9 13049.2 13080.5 07:40:14 INFO - PROCESS | 3468 | -------- Summary: end -------- 07:40:14 INFO - PROCESS | 3468 | 07:40:15 INFO - TEST-INFO | 3468: exit 0 07:40:15 INFO - TEST-OK | dromaeo_css | took 1111943ms 07:40:15 INFO - TEST-START | kraken 07:40:15 INFO - operating with platform_type : linux_ 07:40:15 INFO - Initialising browser for kraken test... 07:40:15 INFO - TEST-INFO | started process 3711 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN9uolS/profile http://localhost:36117/getInfo.html) 07:40:19 INFO - PROCESS | 3711 | __metrics Screen width/height:1600/1200 07:40:19 INFO - PROCESS | 3711 | colorDepth:24 07:40:19 INFO - PROCESS | 3711 | Browser inner width/height: 1024/697 07:40:19 INFO - PROCESS | 3711 | __metrics 07:40:20 ERROR - PROCESS | 3711 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 07:40:21 INFO - TEST-INFO | 3711: exit 0 07:40:21 INFO - Browser initialized. 07:40:21 INFO - Running cycle 1/1 for kraken test... 07:40:21 INFO - TEST-INFO | started process 3829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN9uolS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:40:33 INFO - PROCESS | 3829 | RSS: Main: 163172352 07:40:33 INFO - PROCESS | 3829 | 07:40:35 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36117/tests/kraken/driver.html?testName=audio-beat-detection) 07:40:36 INFO - PROCESS | 3829 | RSS: Main: 169168896 07:40:36 INFO - PROCESS | 3829 | 07:40:39 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36117/tests/kraken/driver.html?testName=audio-dft) 07:40:40 INFO - PROCESS | 3829 | RSS: Main: 167661568 07:40:40 INFO - PROCESS | 3829 | 07:40:42 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36117/tests/kraken/driver.html?testName=audio-fft) 07:40:43 INFO - PROCESS | 3829 | RSS: Main: 163532800 07:40:43 INFO - PROCESS | 3829 | 07:40:45 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36117/tests/kraken/driver.html?testName=audio-oscillator) 07:40:46 INFO - PROCESS | 3829 | RSS: Main: 164630528 07:40:46 INFO - PROCESS | 3829 | 07:40:48 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36117/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:40:49 INFO - PROCESS | 3829 | RSS: Main: 165167104 07:40:49 INFO - PROCESS | 3829 | 07:40:52 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36117/tests/kraken/driver.html?testName=imaging-darkroom) 07:40:52 INFO - PROCESS | 3829 | RSS: Main: 164589568 07:40:52 INFO - PROCESS | 3829 | 07:40:56 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36117/tests/kraken/driver.html?testName=imaging-desaturate) 07:40:56 INFO - PROCESS | 3829 | RSS: Main: 162762752 07:40:56 INFO - PROCESS | 3829 | 07:40:59 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36117/tests/kraken/driver.html?testName=json-parse-financial) 07:41:00 INFO - PROCESS | 3829 | RSS: Main: 164745216 07:41:00 INFO - PROCESS | 3829 | 07:41:01 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36117/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:41:02 INFO - PROCESS | 3829 | RSS: Main: 165138432 07:41:02 INFO - PROCESS | 3829 | 07:41:04 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:41:05 INFO - PROCESS | 3829 | RSS: Main: 164933632 07:41:05 INFO - PROCESS | 3829 | 07:41:07 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:41:08 INFO - PROCESS | 3829 | RSS: Main: 165806080 07:41:08 INFO - PROCESS | 3829 | 07:41:11 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:41:11 INFO - PROCESS | 3829 | RSS: Main: 164532224 07:41:11 INFO - PROCESS | 3829 | 07:41:14 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:41:15 INFO - PROCESS | 3829 | RSS: Main: 165502976 07:41:15 INFO - PROCESS | 3829 | 07:41:16 INFO - PROCESS | 3829 | Cycle 1(1): loaded http://localhost:36117/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36117/tests/kraken/driver.html?testName=ai-astar) 07:41:16 INFO - PROCESS | 3829 | RSS: Main: 166330368 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | __start_tp_report 07:41:17 INFO - PROCESS | 3829 | _x_x_mozilla_page_load 07:41:17 INFO - PROCESS | 3829 | _x_x_mozilla_page_load_details 07:41:17 INFO - PROCESS | 3829 | |i|pagename|runs| 07:41:17 INFO - PROCESS | 3829 | |0;ai-astar;103;111;105;109;111;113;113;112;111;111 07:41:17 INFO - PROCESS | 3829 | |1;audio-beat-detection;153;118;152;173;121;148;150;119;176;120 07:41:17 INFO - PROCESS | 3829 | |2;audio-dft;162;161;162;160;161;164;178;162;160;161 07:41:17 INFO - PROCESS | 3829 | |3;audio-fft;74;75;77;186;75;76;74;74;162;77 07:41:17 INFO - PROCESS | 3829 | |4;audio-oscillator;84;83;154;86;87;84;145;84;84;150 07:41:17 INFO - PROCESS | 3829 | |5;imaging-gaussian-blur;117;117;117;118;117;118;118;117;118;117 07:41:17 INFO - PROCESS | 3829 | |6;imaging-darkroom;149;147;148;147;148;147;148;147;147;146 07:41:17 INFO - PROCESS | 3829 | |7;imaging-desaturate;96;96;95;95;96;96;96;96;95;95 07:41:17 INFO - PROCESS | 3829 | |8;json-parse-financial;54;59;54;53;55;54;63;53;53;55 07:41:17 INFO - PROCESS | 3829 | |9;json-stringify-tinderbox;60;61;61;62;65;61;60;61;61;63 07:41:17 INFO - PROCESS | 3829 | |10;stanford-crypto-aes;78;77;78;77;80;81;77;76;81;82 07:41:17 INFO - PROCESS | 3829 | |11;stanford-crypto-ccm;127;130;123;142;123;121;123;121;168;130 07:41:17 INFO - PROCESS | 3829 | |12;stanford-crypto-pbkdf2;166;161;168;162;164;158;157;163;162;160 07:41:17 INFO - PROCESS | 3829 | |13;stanford-crypto-sha256-iterative;63;72;64;63;63;63;64;63;63;64 07:41:17 INFO - PROCESS | 3829 | __end_tp_report 07:41:17 INFO - PROCESS | 3829 | __start_cc_report 07:41:17 INFO - PROCESS | 3829 | _x_x_mozilla_cycle_collect,896 07:41:17 INFO - PROCESS | 3829 | __end_cc_report 07:41:17 INFO - PROCESS | 3829 | __startTimestamp1470062477000__endTimestamp 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | ------- Summary: start ------- 07:41:17 INFO - PROCESS | 3829 | Number of tests: 14 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.35 (3.0%) stddev-sans-first:2.45 07:41:17 INFO - PROCESS | 3829 | Values: 103.0 111.0 105.0 109.0 111.0 113.0 113.0 112.0 111.0 111.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:151.00 stddev:22.26 (14.7%) stddev-sans-first:23.31 07:41:17 INFO - PROCESS | 3829 | Values: 153.0 118.0 152.0 173.0 121.0 148.0 150.0 119.0 176.0 120.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.00 stddev:5.36 (3.3%) stddev-sans-first:5.67 07:41:17 INFO - PROCESS | 3829 | Values: 162.0 161.0 162.0 160.0 161.0 164.0 178.0 162.0 160.0 161.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.00 stddev:42.03 (56.0%) stddev-sans-first:43.89 07:41:17 INFO - PROCESS | 3829 | Values: 74.0 75.0 77.0 186.0 75.0 76.0 74.0 74.0 162.0 77.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#4] audio-oscillator Cycles:10 Average:104.10 Median:84.00 stddev:31.54 (37.5%) stddev-sans-first:32.60 07:41:17 INFO - PROCESS | 3829 | Values: 84.0 83.0 154.0 86.0 87.0 84.0 145.0 84.0 84.0 150.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:41:17 INFO - PROCESS | 3829 | Values: 117.0 117.0 117.0 118.0 117.0 118.0 118.0 117.0 118.0 117.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:0.84 (0.6%) stddev-sans-first:0.67 07:41:17 INFO - PROCESS | 3829 | Values: 149.0 147.0 148.0 147.0 148.0 147.0 148.0 147.0 147.0 146.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:41:17 INFO - PROCESS | 3829 | Values: 96.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:54.50 stddev:3.23 (5.9%) stddev-sans-first:3.40 07:41:17 INFO - PROCESS | 3829 | Values: 54.0 59.0 54.0 53.0 55.0 54.0 63.0 53.0 53.0 55.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.00 stddev:1.51 (2.5%) stddev-sans-first:1.50 07:41:17 INFO - PROCESS | 3829 | Values: 60.0 61.0 61.0 62.0 65.0 61.0 60.0 61.0 61.0 63.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:2.11 (2.7%) stddev-sans-first:2.22 07:41:17 INFO - PROCESS | 3829 | Values: 78.0 77.0 78.0 77.0 80.0 81.0 77.0 76.0 81.0 82.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:128.50 stddev:14.53 (11.3%) stddev-sans-first:15.34 07:41:17 INFO - PROCESS | 3829 | Values: 127.0 130.0 123.0 142.0 123.0 121.0 123.0 121.0 168.0 130.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.50 stddev:3.38 (2.1%) stddev-sans-first:3.28 07:41:17 INFO - PROCESS | 3829 | Values: 166.0 161.0 168.0 162.0 164.0 158.0 157.0 163.0 162.0 160.0 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - PROCESS | 3829 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:63.50 stddev:2.78 (4.4%) stddev-sans-first:2.92 07:41:17 INFO - PROCESS | 3829 | Values: 63.0 72.0 64.0 63.0 63.0 63.0 64.0 63.0 63.0 64.0 07:41:17 INFO - PROCESS | 3829 | -------- Summary: end -------- 07:41:17 INFO - PROCESS | 3829 | 07:41:17 INFO - TEST-INFO | 3829: exit 0 07:41:17 INFO - TEST-OK | kraken | took 62296ms 07:41:17 INFO - SUITE-END | took 1174s 07:41:18 INFO - Completed test suite (00:19:34) 07:41:18 INFO - javascript benchmark 07:41:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7352.253083141722, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.2924527358705, 3109.8756049758013, 3125.5624212610237, 3129.702678245566, 3131.9060428187163, 5384.0847056000475, 5420.701861397617, 5431.185322201672, 5481.040817856874, 5502.0921547944645, 5412.269343638608, 5449.237106805051, 5472.2338872557775, 5480.150576660614, 5553.4724201200825, 4898.28974798654, 5020.447750747415, 5023.3971923369245, 5027.296178535008, 5076.492350764931, 4992.67547609406, 5014.2979982802435, 5076.0, 5091.592672586185, 5122.052420302238, 5003.124453220672, 5038.093143234217, 5081.669691470042, 5081.898362032757, 5087.262346959707, 4867.36724225852, 4872.9756351218375, 4892.730899800509, 4975.079610272075, 4983.651144419892, 4865.562099411035, 4872.171730805771, 4886.096072226614, 4905.8773530661665, 4917.170915583743, 5326.840194794162, 5394.406615272317, 5406.864828379283, 5409.972950135263, 5418.349798024223, 5666.829995100154, 5781.682007489587, 5847.0, 5877.089051197071, 5886.264216972879, 5922.7927022554, 5925.407459254083, 5928.28860536734, 5929.9703501482645, 5933.169356290123, 5507.476789704971, 5511.0, 5513.862153446196, 5518.0, 5531.723413829292, 5816.12758086282, 5936.762529498858, 5977.133315669291, 5979.282486101609, 6075.939240607538, 5790.7973220937065, 5793.710314484343, 5795.217645617788, 5796.507296879812, 5797.507211887037, 5855.238818953509, 5858.8242352729485, 5867.413258674183, 5869.00226961409, 5873.706314684248, 5533.979613230409, 5756.539476841843, 5813.273340832396, 5817.360090390053, 5880.2943646761805, 5660.405657405995, 5715.142728590745, 5767.192593036978, 5776.104703770922, 5776.306843178845, 4475.843345482893, 4476.7090139140855, 4477.686561940698, 4478.328250762346, 4521.073179998092, 3928.2143571285287, 3940.822662980173, 3980.6218409251073, 3996.7002474814503, 4029.5567487576345, 5566.582506311962, 5579.637323574036, 5636.154576813511, 5685.744141513559, 5690.5447564194765, 5876.618019828783, 5901.852453688606, 5929.673867937218, 5931.58478906481, 5935.73289201987, 5450.291462109901, 5527.060399732055, 5623.3533143689, 5626.324841019104, 5645.5483561314995, 4961.627877909171, 5013.699178049329, 5019.0965626187635, 5041.420236672756, 5054.848354549369, 6057.061155520902, 6080.969595152084, 6082.847928801815, 6084.574079814371, 6089.634621922699, 5662.660240385425, 5812.82561523155, 5877.089051197071, 5888.735006924613, 5913.083472061838, 5991.700414979146, 6003.429674180946, 6069.544784141295, 6088.939110609014, 6096.0, 5710.885782284414, 5771.480566749013, 5773.018586840163, 5779.306483222041, 5784.681842498703, 5661.264035675501, 5831.329397119216, 5831.970840145772, 5862.589618726648, 5881.441263080001, 5668.319801623831, 5670.461306175906, 5687.77248910039, 5718.914216286676, 5726.599138060295, 5611.775528978795, 5616.719164041863, 5641.182028605911, 5657.462541058593, 5679.630823996427, 5541.695206763667, 5546.722663866871, 5585.441455854382, 5612.102063669793, 5616.971915140398, 5741.282339707536, 5828.912566311424, 5832.24180856486, 5835.591508594527, 5837.67892765902, 5616.410276920865, 5634.154876768517, 5656.8020119295625, 5674.602777805679, 5690.601657883908], "value": 5389.174308808788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.5307753796961, 601.440660186027, 606.8136792570525, 618.3013195089549, 621.7357623010216, 32086.0, 35471.64528354716, 42253.577464225345, 42941.570584294146, 43352.78323608385, 45647.77176114115, 46155.0, 46273.0, 46322.30516542255, 46439.30341044887, 8881.245094167003, 9080.59137338818, 9156.587953542074, 9160.816783664322, 9161.679341223025, 8936.821263574724, 8984.730458086267, 9034.548272586377, 9061.592228349708, 9068.047854975232, 9062.592183351764, 9128.954355228248, 9131.634734610641, 9165.266778657691, 9169.908300917006, 8984.820303593924, 9028.0, 9052.094790520961, 9064.18716256748, 9084.046175151612, 8993.505357205351, 9017.053209413049, 9045.728628141167, 9063.637454501812, 9064.365494415393, 43288.0, 43758.781206094085, 43763.78118109389, 43810.0, 43815.34276985816, 3561.4835848802036, 3657.5976642569294, 3665.9450108248398, 3710.2208536207427, 3806.5622453417936, 32674.6732532674, 32807.17982050444, 32993.3401331972, 33048.6695133048, 33519.32961340784, 4277.636400905927, 4338.132373525276, 4353.216421044211, 4406.603405693471, 4489.910201795946, 373301.0, 375942.0, 376540.0, 377226.11386943166, 378567.0, 59065.40934590599, 59457.10814337788, 59528.404715953155, 59577.40422595719, 59671.0, 34927.82536087303, 35910.46134307988, 35981.0, 37534.24931501355, 62077.68961155256, 14830.70338593222, 15050.247487625575, 15057.247137643075, 15058.472953446577, 15074.170920599254, 10818.729531761612, 10906.781864362827, 10976.0, 10982.231243813016, 10996.12031037515, 19022.52443688891, 19040.238390464503, 19191.0, 19207.32774352919, 19436.90281548583, 7262.20115787263, 7282.0, 7286.890696639554, 7394.447166095073, 7588.62056897153, 888.3870129610677, 930.1582068228264, 958.2286259560981, 994.3636072913341, 1072.860528131338, 32463.18842028968, 32710.83644581762, 33022.3395532088, 33116.0, 33427.66572334294, 1053.3153450257241, 1062.7077553672648, 1097.237419993097, 1123.7246874515795, 1161.6805378520974, 17394.826051739572, 17507.47477575675, 17558.38545650896, 17571.385001524886, 17747.0, 153595.23202383917, 156121.0, 156268.0, 156308.0, 156966.0, 61048.69475652682, 61094.38905610887, 61425.0, 61770.38229617646, 61798.69100654468, 1285.8135570342247, 1328.5682153300215, 1338.1302153600043, 1349.3388239762446, 1354.133354653023, 30663.386732264782, 31144.998835728868, 31769.8411507941, 32353.676463235068, 32597.34805303833, 53688.73155634197, 53823.73088134534, 53931.0, 53949.46050539445, 54013.64965875728, 457556.0, 457944.0, 458591.0, 459171.0, 459467.0, 29041.419171616028, 29461.558076629295, 29757.702422975493, 29888.85055574795, 29973.400531988802, 50059.0, 50243.0, 50438.49561504338, 51155.0, 52339.0, 41415.745373560734, 43293.78353108215, 43459.34810977901, 43712.78143609261, 43781.78109109562, 43065.0, 43368.0, 44147.0, 44587.77706111449, 44600.55399445964, 43134.35298470463, 43211.783941081354, 43289.783551082044, 43391.34912976245, 44043.55956440394, 37196.0, 37867.62132378641, 38600.613993859704, 39650.801745991084, 40812.79593602013, 29091.85454072716, 29252.414951701274, 29624.407511850073, 30154.84922575373, 30517.694823051486], "value": 19536.522158057298, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.135849094256, 395.53919683568637, 395.9683225341973, 399.5026192390474, 409.6681687832856, 13713.0, 18605.53486162845, 19373.128209230585, 20039.499012524695, 20086.69869951952, 18986.905065474653, 19021.42935711931, 19309.13108910099, 19316.22735090598, 19556.11997460114, 5675.801346952847, 5726.141078838166, 5859.7656093756195, 5877.617954832944, 5895.4399332063385, 5730.3123625165035, 5747.540196784247, 5782.826515204551, 5806.477417032466, 5811.476967072963, 5695.77216911323, 5791.247137872071, 5800.187973683687, 5815.069588865782, 5872.647641141524, 5745.655260684331, 5793.0, 5808.448197421239, 5818.767249310023, 5820.767169313222, 5151.201563757623, 5172.612054095958, 5197.454267301954, 5198.376194856642, 5238.580913526908, 22431.551368972694, 22604.54790904173, 23128.306150815504, 23231.3030609082, 23405.765942340528, 2243.932682019542, 2467.728549859514, 2472.6291056341693, 3060.6786287439945, 3091.520814273791, 14487.348069336906, 14856.777148342784, 14878.107313561113, 14882.330295136744, 14908.254587270701, 1921.4715953112866, 1925.922963081475, 1932.3526618582791, 1995.1919472613615, 2023.7672667643262, 78429.0, 78953.60523197404, 79077.60461197657, 79611.60194199049, 79899.0, 10083.848742268872, 10291.53688084023, 10328.48357582118, 10478.73803154912, 10819.891801082045, 14401.0, 14511.927440362731, 14594.0, 14605.0, 14610.26948652563, 7340.339369456775, 7600.2779735925, 7652.502587331805, 7797.961010195027, 7812.804679883048, 7144.9642751785905, 7156.998020277166, 7242.718543658731, 7325.926740732524, 7428.33145016951, 6293.244810622663, 6477.222733272038, 6500.577462465019, 6537.05212744159, 6542.738290468328, 1886.5189376709127, 1894.6779047561947, 1930.6042261336672, 1935.4774210963242, 1953.2772874036698, 1023.5547536821507, 1049.7952899184738, 1089.2429761315968, 1102.0962810495316, 1170.7248796532808, 19357.4192774217, 19629.31297404584, 19703.605927881363, 19722.211111555665, 20020.199192032156, 999.3703966501204, 1002.1131298800614, 1069.7379142110233, 1072.538808312433, 1112.5327362507767, 9764.511774411392, 10996.505157267942, 10998.560057597606, 11047.447627618587, 11094.611688590861, 383574.0, 386529.0, 509008.0, 528382.0, 534020.0, 86489.56755216309, 88845.0, 96749.5162524197, 97172.51413742887, 103262.48368758259, 999.8300288950895, 1003.5032658833924, 1038.8285932821, 1054.836500342448, 1058.4548957287004, 16789.74815377746, 17018.404355847484, 17193.14034298305, 17729.202185901922, 17932.64134717272, 31454.528182077745, 36950.26099478049, 42602.14795704006, 42766.0, 43970.0, 56150.0, 56411.64089411499, 57330.713346433, 59693.701531492065, 63181.68409157925, 7572.962135189509, 7699.615019249127, 7989.600519973862, 8013.318867896294, 8348.540830254395, 9584.952075239578, 11505.367204803817, 12027.27836329823, 12441.440135193758, 12543.435545400662, 9951.452670103214, 10399.272050956662, 10589.894101058891, 10631.989960953697, 10813.891861081604, 10658.360498370123, 10781.568737250422, 10825.56697732082, 10885.129189664649, 10948.178886583697, 10445.955404459493, 10452.216083793897, 10747.731306717395, 10754.247202695737, 10857.457127143769, 7519.924800751923, 7581.712303308141, 7608.619569021416, 7733.110692270236, 7884.4086693497065, 7786.688532458638, 9252.0, 10444.164466842481, 10485.842712359168, 10549.89450105489], "value": 9172.29764634846, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.8508770615063, 1521.170961825805, 1673.8326167383264, 1789.5168304557762, 1796.946091617252, 8095.473794203387, 8713.867197264355, 8869.20177184055, 9402.34183607147, 9438.622455101788, 8907.0, 9245.768855778591, 9284.350095493319, 9287.931887832901, 9299.209567186797, 2419.600765873629, 2472.0359059966627, 2474.542209691213, 2476.19523654812, 2478.5910324796387, 2386.7971222446026, 2424.248482970276, 2440.4264997725522, 2451.4361696809747, 2451.570975079363, 2423.212455951814, 2424.624183251599, 2436.11081955086, 2467.9136230231948, 2473.492933948545, 2378.4053986503372, 2401.591729406005, 2411.2163546847255, 2416.565018296706, 2421.188901717909, 2374.2402431222017, 2377.75033621469, 2392.5214957008493, 2398.4963157736897, 2401.3756423329887, 9245.630174793068, 9392.12157568489, 9398.295127865369, 9401.670941517083, 9403.764905877408, 5469.398366179717, 5590.748416321276, 5605.859853503654, 5615.241942337773, 5619.325680918275, 3499.6850283474355, 3603.6756691897594, 3615.6745892869767, 3687.0597997510463, 3697.6487233712755, 1132.9263597866195, 1142.1376860470375, 1158.9884101158964, 1159.4376727287258, 1160.2052593973071, 13059.347032648424, 13134.934325328408, 13139.934300328438, 13149.342532873414, 13170.999999999904, 4497.662675299301, 4646.140464014168, 4769.785359658824, 4813.133635945564, 4821.566059054702, 10018.448985305733, 10055.592217089614, 10059.748506287402, 10064.647737329306, 10082.74793130163, 5443.564514838803, 5470.070088085035, 5474.753636086386, 5487.835364939058, 5501.064818980783, 4503.41455610775, 4941.308216849644, 5007.39911210657, 5080.77136528857, 5104.3619547556555, 3863.942040869389, 3872.5352957645264, 3993.4009898514873, 4005.0788318686864, 4049.230646177217, 1213.7147770273978, 1234.4876876096318, 1238.325112813519, 1241.528219276669, 1249.1755441408627, 893.4773157702687, 1011.145581983223, 1016.8068067067381, 1018.7554986803113, 1026.2200727447193, 5839.445252700986, 6029.306629737549, 6051.152838602599, 6060.060690592965, 6156.015037593964, 453.82074080737925, 455.8632410276904, 458.7752001519232, 1057.7302787789065, 1082.1451053667672, 4097.323941549671, 4128.587141285848, 4147.3778933160265, 4160.750354978711, 4240.618344349024, 11211.383373914514, 11427.257228280136, 11511.884881151249, 11595.304281743123, 11672.241304315192, 11419.37193454368, 11422.771544569228, 11442.199046066862, 11499.0, 11513.827292590619, 8324.45891017094, 8353.16468353172, 8421.70524031656, 8458.36562257833, 8466.872996905051, 6078.331383547717, 6184.412480814315, 6190.907136392868, 6211.844703882259, 6227.844303892439, 10158.796824063329, 10343.069123778896, 10391.168706503613, 10423.270371073952, 10432.947835260775, 9967.112825997721, 10303.227257955834, 10325.586976520855, 10456.895431045592, 10519.474026298501, 7126.2873712628325, 7574.242575742602, 7657.502262352929, 7785.065792104983, 7900.170482099296, 9191.448513089235, 9236.861447078434, 9243.58403871814, 9277.62889484413, 9304.72085837426, 8097.797555061171, 8152.429329946846, 8182.918170818454, 8217.630206640595, 8451.577421129043, 7887.566183859943, 8022.799430014296, 8070.677172913018, 8076.515409075474, 8171.632276547451, 7162.820929476805, 7219.169795473378, 7282.3809976152625, 7303.160136584361, 7448.143463501767, 5318.069337865781, 5340.465953404784, 5378.677279363094, 5403.83788486346, 5443.265159203457, 5136.666116702402, 5230.189320655304, 5260.921086183786, 5491.560675145899, 5633.38032816398], "value": 4762.940100212694, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.79223712349847, 325.4759836662973, 328.916221051635, 341.78809138334236, 343.673510165343, 5375.0, 5493.176023596463, 5561.304836895388, 5590.357108932474, 5662.0, 5496.862578435531, 5510.283663123799, 5521.129012913293, 5525.585581081415, 5531.0, 4276.764777937212, 4277.443932288798, 4283.935740963888, 4293.484781826185, 4299.527052024275, 4132.710710250269, 4244.259853460082, 4282.914341713179, 4300.806463709125, 4328.285832837578, 4212.810423530948, 4221.142697213217, 4237.300845360527, 4254.893627659302, 4272.359146128075, 4244.808983595745, 4251.914961700764, 4262.190183865056, 4299.871003869889, 4320.0711846952745, 3649.2701459708164, 3778.7543809652284, 3800.1069748609057, 3800.9809950950344, 3803.0, 5371.5165635092635, 5381.623286369956, 5384.273123128406, 5390.380106287748, 5399.7030163341, 4912.242134766906, 4915.950840491585, 4943.06081844452, 4948.0, 4948.158813001798, 5545.0, 5767.88464230713, 5779.075347944308, 5791.826245212608, 5820.883582328372, 4053.351463765783, 4057.350823868167, 4064.979675101635, 4073.002114481941, 4090.754554726726, 6025.186599809013, 6049.304330002062, 6077.453029227391, 6096.664683442409, 6104.481119104881, 3963.3064213762477, 4160.167966406731, 4192.685548583869, 4192.769397683096, 4195.685323600742, 4207.221663992172, 4222.873313800591, 4232.873013809591, 4233.25917964355, 4239.300515414983, 4852.757362131879, 4877.463479017305, 4922.606191504671, 4928.0, 4928.654994150375, 5636.210930469738, 5739.311282646109, 5874.206982057368, 5884.882302354014, 5885.588008839426, 1467.5377256164275, 1482.2588705647177, 1490.4410845932775, 1491.8060652115155, 1491.9477818276525, 905.5291248550717, 934.2619330728648, 944.4663764972769, 950.410745337881, 968.0706521739069, 4839.322494850724, 4871.0, 4881.877953051202, 4902.2646603009125, 5046.697198168122, 5211.973940130351, 5228.895422091537, 5231.319928409359, 5292.655977361459, 5335.3864305604575, 4708.788104535304, 4949.267756285343, 4996.277029019193, 4996.725834912074, 5051.363018626583, 2754.663987965835, 2785.2758282846203, 2810.1429064135637, 2810.2271875234064, 2864.4102523099878, 5416.0, 5422.91865622016, 5438.673679579238, 5442.183672449164, 5442.51678925488, 5165.690058596496, 5267.341582302212, 5340.385855626575, 5371.704556249444, 5384.326959130109, 5388.595855310868, 5390.355941437821, 5408.9175667368345, 5434.646747961449, 5543.916841247385, 5306.787728490817, 5349.545288650353, 5359.990003748594, 5389.302369753515, 5389.7930731214865, 4884.876478409915, 5150.433452320317, 5173.3533308336455, 5188.273641690242, 5211.426743058185, 5432.3431353436, 5438.265834112344, 5455.61810673265, 5484.7806087757635, 5511.12621708621, 4025.7987100645437, 4223.662107031491, 4288.656907447335, 4355.934660980024, 4380.6057454829215, 5952.345242023461, 5985.610935289193, 6018.729157188025, 6020.909686354796, 6036.215292012186, 5720.3421606515785, 5833.212516310244, 5839.5912286141265, 5861.296644402699, 5898.793542226002, 5705.7432415542235, 5729.2265544151005, 5734.483896449339, 5763.6541807491685, 5779.711014449176, 5699.601027928005, 5736.533290685045, 5759.424057594207, 5759.452851979056, 5761.222234998221, 5574.442555744393, 5782.814523022686, 5787.421257874179, 5804.807355954293, 5823.970880145572, 5647.29408823897, 5648.378678345461, 5651.06144804691, 5655.321361436654, 5691.7438715257085], "value": 4257.129012923067, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.7498313048261, 796.0328201235498, 805.9959700201498, 844.6705784743949, 868.7784614923191, 16049.83950160498, 17363.392281270142, 17499.825001749963, 17981.730274045865, 17982.190801413934, 16334.75497867533, 16415.097169655663, 16450.506484805475, 16468.0, 16511.33954641816, 814.1085511365046, 832.342449464925, 834.1700008491559, 835.1147783349638, 836.7991681996338, 676.5331920974536, 798.5368486277945, 825.529448214518, 826.8470332988386, 834.4826207751203, 879.1120967822513, 879.463527248378, 880.0143838900409, 880.995595022024, 882.0517943211074, 875.1860769484377, 875.5359659380507, 876.4565969099135, 877.4428238068808, 877.8068824858585, 631.8767840271149, 634.7365843175077, 635.9523035772289, 636.2555809702659, 638.1480723234488, 15313.310901009481, 15397.0, 15414.922925385414, 15425.228738563026, 15438.5368438947, 15476.76784848228, 15569.532914012598, 15620.0, 15777.605559861095, 15815.288312025872, 12562.560310389092, 12768.872311276862, 12892.548760793326, 12973.351332433434, 13839.100458470268, 3990.5809889961715, 3993.920121597581, 3995.440638310639, 4030.052937559671, 4077.408775727503, 77048.22951770523, 77812.22187778106, 81823.5908820452, 83969.58015209946, 84520.57739711323, 11989.940050299692, 12211.389430528438, 12269.141160118706, 12309.56916507918, 12477.438515266835, 21108.261210857545, 21308.36074917755, 21338.359849204237, 21369.145234190768, 21389.78610213878, 8747.12528747133, 8820.426672266281, 8872.334574906909, 9082.31882608807, 9088.681896133736, 4942.728149951788, 5054.671446355975, 5101.464346243665, 5113.8721531961255, 5205.947940520622, 3841.961580384216, 3941.1132495188926, 3946.9013274668364, 3951.150502641888, 3954.9209015819524, 732.6300218389786, 753.4876284126847, 767.781182363035, 769.6075001749036, 781.9491733037335, 728.0135416510658, 746.4625469661835, 781.5701364249753, 794.6503538443067, 816.7672213419147, 10347.689569312932, 10671.345941379099, 10847.349159050482, 11206.831897521544, 11433.828492572618, 688.5180373738303, 706.2972342519226, 715.6493318274113, 767.9270469305407, 790.6165509727778, 5042.85132625599, 5288.576913846883, 5370.2213179089595, 5423.105187643994, 5443.101888188405, 71006.0, 83057.0, 124544.0, 130420.3478982599, 131628.0, 66546.66726666433, 67603.32396675971, 70106.64946675234, 113185.43407282911, 115715.0, 96253.5187324059, 100964.49517752364, 102547.4872625647, 102594.0, 103508.48245758873, 9700.175485083846, 10035.548400321857, 10095.545700443645, 10278.640247591298, 10311.948440257751, 54412.72793636007, 55354.16968745391, 57173.42826571681, 57589.12054397347, 58343.124853126385, 80498.0, 82396.0, 82714.0, 84336.0, 86483.0, 12429.813552796535, 12599.685007874876, 12816.807747883602, 12846.871531284567, 12949.223046617233, 14504.927475362556, 15415.691686166438, 15547.0, 15708.214589270263, 15790.289436975134, 14814.703705926036, 15015.699686006, 15558.611034724223, 15990.840091598935, 15994.600134996717, 14599.781003285172, 14837.851621484078, 15054.096754194783, 15540.84459155394, 15864.206789660702, 13654.522091726742, 13847.584572462842, 14181.716365672422, 14541.636459088608, 14549.563513094625, 12155.513779448724, 12371.814422783485, 13061.281629510087, 13106.60680179596, 13287.800682989957, 12568.120231584067, 12645.304508252135, 12962.870371296167, 13049.151805132637, 13080.476780928657], "value": 8065.668238145407, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 105.0, 109.0, 111.0, 113.0, 113.0, 112.0, 111.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 152.0, 173.0, 121.0, 148.0, 150.0, 119.0, 176.0, 120.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 160.0, 161.0, 164.0, 178.0, 162.0, 160.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 186.0, 75.0, 76.0, 74.0, 74.0, 162.0, 77.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 154.0, 86.0, 87.0, 84.0, 145.0, 84.0, 84.0, 150.0], "value": 104.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 147.0, 146.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 59.0, 54.0, 53.0, 55.0, 54.0, 63.0, 53.0, 53.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 62.0, 65.0, 61.0, 60.0, 61.0, 61.0, 63.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 77.0, 80.0, 81.0, 77.0, 76.0, 81.0, 82.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 130.0, 123.0, 142.0, 123.0, 121.0, 123.0, 121.0, 168.0, 130.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 161.0, 168.0, 162.0, 164.0, 158.0, 157.0, 163.0, 162.0, 160.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 64.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 64.0], "value": 64.2, "unit": "score"}]}]} 07:41:18 INFO - Return code: 0 07:41:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:41:18 ERROR - # TBPL SUCCESS # 07:41:18 INFO - Running post-action listener: _resource_record_post_action 07:41:18 INFO - [mozharness: 2016-08-01 14:41:18.283702Z] Finished run-tests step (success) 07:41:18 INFO - Running post-run listener: _resource_record_post_run 07:41:18 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 7909376; Write bytes: 425123840; Read time: 1632; Write time: 333064 07:41:18 INFO - TinderboxPrint: CPU usage
13.3% 07:41:18 INFO - TinderboxPrint: I/O read bytes / time
7,909,376 / 1,632 07:41:18 INFO - TinderboxPrint: I/O write bytes / time
425,123,840 / 333,064 07:41:18 INFO - TinderboxPrint: CPU idle
8,241.9 (86.7%) 07:41:18 INFO - TinderboxPrint: CPU user
1,209.6 (12.7%) 07:41:18 INFO - TinderboxPrint: Swap in / out
0 / 0 07:41:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 93986816; Read time: 148; Write time: 60464 07:41:18 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 5619712; Write bytes: 249020416; Read time: 1028; Write time: 180516 07:41:18 INFO - Running post-run listener: _upload_blobber_files 07:41:18 INFO - Blob upload gear active. 07:41:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:41:18 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 07:41:18 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'] 07:41:18 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 07:41:19 INFO - (blobuploader) - INFO - Open directory for files ... 07:41:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:41:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:41:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:20 INFO - (blobuploader) - INFO - Done attempting. 07:41:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:41:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:41:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:23 INFO - (blobuploader) - INFO - Done attempting. 07:41:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:41:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:41:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:24 INFO - (blobuploader) - INFO - Done attempting. 07:41:24 INFO - (blobuploader) - INFO - Iteration through files over. 07:41:24 INFO - Return code: 0 07:41:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:41:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:41:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4f72ccc25c1cf358a70238aa5211ae853c48ac96e653e923079ec782426483123e78e86cf6cf2f2f45df582e19a9c25c40dedfe1062479f7ac1eedcefc1ccf48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/561d19c2f5478fad9b36a052586d250503329810e5f77fc705d5cecf2379956c0a2f65f016e647d1697e248f2ff33214f7a3543995567ec5ff347c7a7d15f84c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dfe704fa65dc8218867d28b1a0d50c1fc367ced900ae986266bf88ca089f3a381d4ef0d360f6fafaf62a3e55ca8e55d4357a3713d180af7f396f8b72fefb65a0"} 07:41:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:41:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:41:24 INFO - Contents: 07:41:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4f72ccc25c1cf358a70238aa5211ae853c48ac96e653e923079ec782426483123e78e86cf6cf2f2f45df582e19a9c25c40dedfe1062479f7ac1eedcefc1ccf48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/561d19c2f5478fad9b36a052586d250503329810e5f77fc705d5cecf2379956c0a2f65f016e647d1697e248f2ff33214f7a3543995567ec5ff347c7a7d15f84c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dfe704fa65dc8218867d28b1a0d50c1fc367ced900ae986266bf88ca089f3a381d4ef0d360f6fafaf62a3e55ca8e55d4357a3713d180af7f396f8b72fefb65a0"} 07:41:24 INFO - Running post-run listener: copy_logs_to_upload_dir 07:41:24 INFO - Copying logs to upload dir... 07:41:24 INFO - mkdir: /builds/slave/test/build/upload/logs 07:41:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.398839 ========= master_lag: 138.57 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 49 secs) (at 2016-08-01 07:43:42.703371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 mins, 3 secs) (at 2016-08-01 07:43:42.708811) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464 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/4f72ccc25c1cf358a70238aa5211ae853c48ac96e653e923079ec782426483123e78e86cf6cf2f2f45df582e19a9c25c40dedfe1062479f7ac1eedcefc1ccf48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/561d19c2f5478fad9b36a052586d250503329810e5f77fc705d5cecf2379956c0a2f65f016e647d1697e248f2ff33214f7a3543995567ec5ff347c7a7d15f84c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dfe704fa65dc8218867d28b1a0d50c1fc367ced900ae986266bf88ca089f3a381d4ef0d360f6fafaf62a3e55ca8e55d4357a3713d180af7f396f8b72fefb65a0"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470051002/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/4f72ccc25c1cf358a70238aa5211ae853c48ac96e653e923079ec782426483123e78e86cf6cf2f2f45df582e19a9c25c40dedfe1062479f7ac1eedcefc1ccf48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/561d19c2f5478fad9b36a052586d250503329810e5f77fc705d5cecf2379956c0a2f65f016e647d1697e248f2ff33214f7a3543995567ec5ff347c7a7d15f84c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dfe704fa65dc8218867d28b1a0d50c1fc367ced900ae986266bf88ca089f3a381d4ef0d360f6fafaf62a3e55ca8e55d4357a3713d180af7f396f8b72fefb65a0"}' ========= master_lag: 123.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 mins, 3 secs) (at 2016-08-01 07:45:45.973114) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 07:45:45.973507) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470060708.595433-143289464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004600 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 07:45:46.088799) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 07:45:46.089124) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 07:45:46.091146) ========= ========= Total master_lag: 261.99 =========