builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-040 starttime: 1474406934.17 results: success (0) buildid: 20160920142049 builduid: 3cef16a3c9c544b48a277b5a17d9de67 revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.170294) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.170979) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.171337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.183614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.183913) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 14:28:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-09-20 14:28:54 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124294 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.320658) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.321077) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017131 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:54.373399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 14:28:54.374223) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 14:28:54,711 truncating revision to first 12 chars 2016-09-20 14:28:54,711 Setting DEBUG logging. 2016-09-20 14:28:54,711 attempt 1/10 2016-09-20 14:28:54,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6be9a5c9c8cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 14:28:55,804 unpacking tar archive at: mozilla-beta-6be9a5c9c8cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.423573 ========= master_lag: 0.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 14:28:56.317713) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:56.318115) ========= script_repo_revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:56.318577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:56.318885) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:28:56.329891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 19 secs) (at 2016-09-20 14:28:56.330327) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:28:56 INFO - MultiFileLogger online at 20160920 14:28:56 in /builds/slave/test 14:28:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:28:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:28:56 INFO - {'append_to_log': False, 14:28:56 INFO - 'base_work_dir': '/builds/slave/test', 14:28:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:28:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:28:56 INFO - 'branch': 'Mozilla-Beta', 14:28:56 INFO - 'buildbot_json_path': 'buildprops.json', 14:28:56 INFO - 'config_files': ('talos/linux_config.py',), 14:28:56 INFO - 'default_actions': ('clobber', 14:28:56 INFO - 'read-buildbot-config', 14:28:56 INFO - 'download-and-extract', 14:28:56 INFO - 'populate-webroot', 14:28:56 INFO - 'create-virtualenv', 14:28:56 INFO - 'install', 14:28:56 INFO - 'run-tests'), 14:28:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:28:56 INFO - 'download_minidump_stackwalk': True, 14:28:56 INFO - 'download_symbols': 'ondemand', 14:28:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:28:56 INFO - 'tooltool.py': '/tools/tooltool.py', 14:28:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:28:56 INFO - '/tools/misc-python/virtualenv.py')}, 14:28:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:28:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:28:56 INFO - 'installer_path': 'installer.exe', 14:28:56 INFO - 'log_level': 'info', 14:28:56 INFO - 'log_name': 'talos', 14:28:56 INFO - 'log_to_console': True, 14:28:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:28:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:28:56 INFO - 'opt_config_files': (), 14:28:56 INFO - 'pip_index': False, 14:28:56 INFO - 'sps_profile': False, 14:28:56 INFO - 'sps_profile_interval': 0, 14:28:56 INFO - 'suite': 'dromaeojs-e10s', 14:28:56 INFO - 'system_bits': '32', 14:28:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:28:56 INFO - 'title': 'talos-linux64-ix-040', 14:28:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:28:56 INFO - 'use_talos_json': True, 14:28:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:28:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:28:56 INFO - 'work_dir': 'build'} 14:28:56 INFO - [mozharness: 2016-09-20 21:28:56.757667Z] Running clobber step. 14:28:56 INFO - Running pre-action listener: _resource_record_pre_action 14:28:56 INFO - Running main action method: clobber 14:28:56 INFO - rmtree: /builds/slave/test/build 14:28:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:28:57 INFO - Running post-action listener: _resource_record_post_action 14:28:57 INFO - [mozharness: 2016-09-20 21:28:57.232725Z] Finished clobber step (success) 14:28:57 INFO - [mozharness: 2016-09-20 21:28:57.232827Z] Running read-buildbot-config step. 14:28:57 INFO - Running pre-action listener: _resource_record_pre_action 14:28:57 INFO - Running main action method: read_buildbot_config 14:28:57 INFO - Using buildbot properties: 14:28:57 INFO - { 14:28:57 INFO - "project": "", 14:28:57 INFO - "product": "firefox", 14:28:57 INFO - "script_repo_revision": "production", 14:28:57 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 14:28:57 INFO - "repository": "", 14:28:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 14:28:57 INFO - "buildid": "20160920142049", 14:28:57 INFO - "stage_platform": "linux64-pgo", 14:28:57 INFO - "basedir": "/builds/slave/test", 14:28:57 INFO - "buildnumber": 178, 14:28:57 INFO - "platform": "ubuntu64_hw", 14:28:57 INFO - "mozci_request": { 14:28:57 INFO - "priority": -1, 14:28:57 INFO - "type": "trigger_all_talos_jobs", 14:28:57 INFO - "times": 6 14:28:57 INFO - }, 14:28:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:28:57 INFO - "slavebuilddir": "test", 14:28:57 INFO - "branch": "mozilla-beta", 14:28:57 INFO - "revision": "6be9a5c9c8cd25d2082376495b788d85ce3cca98", 14:28:57 INFO - "slavename": "talos-linux64-ix-040", 14:28:57 INFO - "builduid": "3cef16a3c9c544b48a277b5a17d9de67", 14:28:57 INFO - "repo_path": "releases/mozilla-beta" 14:28:57 INFO - } 14:28:57 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2. 14:28:57 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json. 14:28:57 INFO - Running post-action listener: _resource_record_post_action 14:28:57 INFO - [mozharness: 2016-09-20 21:28:57.235592Z] Finished read-buildbot-config step (success) 14:28:57 INFO - [mozharness: 2016-09-20 21:28:57.235682Z] Running download-and-extract step. 14:28:57 INFO - Running pre-action listener: _resource_record_pre_action 14:28:57 INFO - Running main action method: download_and_extract 14:28:57 INFO - mkdir: /builds/slave/test/build/tests 14:28: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')]} 14:28:57 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:28:57 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:28:57 INFO - Downloaded 1327 bytes. 14:28:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:28:57 INFO - Using the following test package requirements: 14:28:57 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:28:57 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:28:57 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 14:28:57 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'jsshell-linux-x86_64.zip'], 14:28:57 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:28:57 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:28:57 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 14:28:57 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 14:28:57 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:28:57 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:28:57 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:28:57 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:28: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')]} 14:28:57 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:57 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:28:57 INFO - Downloaded 21769913 bytes. 14:28:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:28:58 INFO - Return code: 0 14:28:58 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:28: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')]} 14:28:58 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:58 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:28:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:28:58 INFO - Downloaded 21769913 bytes. 14:28:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:29:00 INFO - Return code: 0 14:29: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')]} 14:29:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:29:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:29:00 INFO - Downloaded 14413740 bytes. 14:29:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:29:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:29:00 INFO - Return code: 0 14:29: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')]} 14:29:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:29:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:29:01 INFO - Downloaded 56771801 bytes. 14:29:01 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:29:01 INFO - mkdir: /builds/slave/test/properties 14:29:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:01 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:01 INFO - Contents: 14:29:01 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:29:01 INFO - Running post-action listener: _resource_record_post_action 14:29:01 INFO - Running post-action listener: set_extra_try_arguments 14:29:01 INFO - [mozharness: 2016-09-20 21:29:01.808124Z] Finished download-and-extract step (success) 14:29:01 INFO - [mozharness: 2016-09-20 21:29:01.808359Z] Running populate-webroot step. 14:29:01 INFO - Running pre-action listener: _resource_record_pre_action 14:29:01 INFO - Running main action method: populate_webroot 14:29:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:29:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:29:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:29:01 INFO - '--tppagecycles', 14:29:01 INFO - '7'], 14:29:01 INFO - 'tests': ['tsvgm']}}, 14:29:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:29:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:29:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:29:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:29:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['damp', 'tps']}, 14:29:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'tests': ['damp', 'tps']}, 14:29:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['dromaeo_dom']}, 14:29:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:29:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['basic_compositor_video']}, 14:29:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:29:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['a11yr', 14:29:01 INFO - 'ts_paint', 14:29:01 INFO - 'tpaint', 14:29:01 INFO - 'sessionrestore', 14:29:01 INFO - 'sessionrestore_no_auto_restore', 14:29:01 INFO - 'tabpaint']}, 14:29:01 INFO - 'other-e10s': {'tests': ['a11yr', 14:29:01 INFO - 'ts_paint', 14:29:01 INFO - 'tpaint', 14:29:01 INFO - 'sessionrestore', 14:29:01 INFO - 'sessionrestore_no_auto_restore', 14:29:01 INFO - 'tabpaint']}, 14:29:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:29:01 INFO - 'ts_paint', 14:29:01 INFO - 'tpaint', 14:29:01 INFO - 'sessionrestore', 14:29:01 INFO - 'sessionrestore_no_auto_restore', 14:29:01 INFO - 'tabpaint']}, 14:29:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:29:01 INFO - 'ts_paint', 14:29:01 INFO - 'tpaint', 14:29:01 INFO - 'sessionrestore', 14:29:01 INFO - 'sessionrestore_no_auto_restore', 14:29:01 INFO - 'tabpaint']}, 14:29:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['a11yr', 14:29:01 INFO - 'ts_paint', 14:29:01 INFO - 'tpaint', 14:29:01 INFO - 'sessionrestore', 14:29:01 INFO - 'sessionrestore_no_auto_restore', 14:29:01 INFO - 'tabpaint']}, 14:29:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['a11yr', 14:29:01 INFO - 'ts_paint', 14:29:01 INFO - 'tpaint', 14:29:01 INFO - 'sessionrestore', 14:29:01 INFO - 'sessionrestore_no_auto_restore', 14:29:01 INFO - 'tabpaint']}, 14:29:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['tsvgx', 14:29:01 INFO - 'tsvgr_opacity', 14:29:01 INFO - 'tart', 14:29:01 INFO - 'tscrollx', 14:29:01 INFO - 'cart']}, 14:29:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:29:01 INFO - 'tsvgr_opacity', 14:29:01 INFO - 'tart', 14:29:01 INFO - 'tscrollx', 14:29:01 INFO - 'cart']}, 14:29:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'talos_options': ['--disable-e10s'], 14:29:01 INFO - 'tests': ['tp5o']}, 14:29:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'tests': ['tp5o']}, 14:29:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'talos_options': ['--disable-e10s', 14:29:01 INFO - '--xperf_path', 14:29:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:01 INFO - 'tests': ['tp5n']}, 14:29:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:01 INFO - 'talos_options': ['--xperf_path', 14:29:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:01 INFO - 'tests': ['tp5n']}}, 14:29:01 INFO - 'talos.zip': {'path': '', 14:29:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:29:01 INFO - Running post-action listener: _resource_record_post_action 14:29:01 INFO - [mozharness: 2016-09-20 21:29:01.827657Z] Finished populate-webroot step (success) 14:29:01 INFO - [mozharness: 2016-09-20 21:29:01.827745Z] Running create-virtualenv step. 14:29:01 INFO - Running pre-action listener: _resource_record_pre_action 14:29:01 INFO - Running main action method: create_virtualenv 14:29:01 INFO - Creating virtualenv /builds/slave/test/build/venv 14:29: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 14:29:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:29:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:29:01 INFO - Using real prefix '/usr' 14:29:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:29:02 INFO - Installing distribute.............................................................................................................................................................................................done. 14:29:04 INFO - Installing pip.................done. 14:29:04 INFO - Return code: 0 14:29:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:04 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:29:04 INFO - Using env: {'DISPLAY': ':0', 14:29:04 INFO - 'HOME': '/home/cltbld', 14:29:04 INFO - 'LANG': 'en_US.UTF-8', 14:29:04 INFO - 'LANGUAGE': 'en_US:en', 14:29:04 INFO - 'LOGNAME': 'cltbld', 14:29:04 INFO - 'MAIL': '/var/mail/cltbld', 14:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:04 INFO - 'MOZ_NO_REMOTE': '1', 14:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:04 INFO - 'NO_EM_RESTART': '1', 14:29:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:04 INFO - 'PWD': '/builds/slave/test', 14:29:04 INFO - 'SHELL': '/bin/bash', 14:29:04 INFO - 'SHLVL': '1', 14:29:04 INFO - 'TERM': 'linux', 14:29:04 INFO - 'TMOUT': '86400', 14:29:04 INFO - 'USER': 'cltbld', 14:29:04 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:04 INFO - '_': '/tools/buildbot/bin/python'} 14:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:29:04 INFO - Cleaning up... 14:29:04 INFO - Return code: 0 14:29:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:04 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:29:04 INFO - Using env: {'DISPLAY': ':0', 14:29:04 INFO - 'HOME': '/home/cltbld', 14:29:04 INFO - 'LANG': 'en_US.UTF-8', 14:29:04 INFO - 'LANGUAGE': 'en_US:en', 14:29:04 INFO - 'LOGNAME': 'cltbld', 14:29:04 INFO - 'MAIL': '/var/mail/cltbld', 14:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:04 INFO - 'MOZ_NO_REMOTE': '1', 14:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:04 INFO - 'NO_EM_RESTART': '1', 14:29:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:04 INFO - 'PWD': '/builds/slave/test', 14:29:04 INFO - 'SHELL': '/bin/bash', 14:29:04 INFO - 'SHLVL': '1', 14:29:04 INFO - 'TERM': 'linux', 14:29:04 INFO - 'TMOUT': '86400', 14:29:04 INFO - 'USER': 'cltbld', 14:29:04 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:04 INFO - '_': '/tools/buildbot/bin/python'} 14:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:04 INFO - Downloading/unpacking psutil>=3.1.1 14:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:29:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:06 INFO - Installing collected packages: psutil 14:29:06 INFO - Running setup.py install for psutil 14:29:06 INFO - building 'psutil._psutil_linux' extension 14:29:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:29:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:29:07 INFO - building 'psutil._psutil_posix' extension 14:29:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:29:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:29:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:07 INFO - Successfully installed psutil 14:29:07 INFO - Cleaning up... 14:29:07 INFO - Return code: 0 14:29:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:07 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:29:07 INFO - Using env: {'DISPLAY': ':0', 14:29:07 INFO - 'HOME': '/home/cltbld', 14:29:07 INFO - 'LANG': 'en_US.UTF-8', 14:29:07 INFO - 'LANGUAGE': 'en_US:en', 14:29:07 INFO - 'LOGNAME': 'cltbld', 14:29:07 INFO - 'MAIL': '/var/mail/cltbld', 14:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:07 INFO - 'MOZ_NO_REMOTE': '1', 14:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:07 INFO - 'NO_EM_RESTART': '1', 14:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:07 INFO - 'PWD': '/builds/slave/test', 14:29:07 INFO - 'SHELL': '/bin/bash', 14:29:07 INFO - 'SHLVL': '1', 14:29:07 INFO - 'TERM': 'linux', 14:29:07 INFO - 'TMOUT': '86400', 14:29:07 INFO - 'USER': 'cltbld', 14:29:07 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:07 INFO - '_': '/tools/buildbot/bin/python'} 14:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:29:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:29:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:29:09 INFO - Installing collected packages: mozsystemmonitor 14:29:09 INFO - Running setup.py install for mozsystemmonitor 14:29:09 INFO - Successfully installed mozsystemmonitor 14:29:09 INFO - Cleaning up... 14:29:09 INFO - Return code: 0 14:29:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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', '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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:29: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 jsonschema==2.5.1 14:29:09 INFO - Using env: {'DISPLAY': ':0', 14:29:09 INFO - 'HOME': '/home/cltbld', 14:29:09 INFO - 'LANG': 'en_US.UTF-8', 14:29:09 INFO - 'LANGUAGE': 'en_US:en', 14:29:09 INFO - 'LOGNAME': 'cltbld', 14:29:09 INFO - 'MAIL': '/var/mail/cltbld', 14:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:09 INFO - 'MOZ_NO_REMOTE': '1', 14:29:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:09 INFO - 'NO_EM_RESTART': '1', 14:29:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:09 INFO - 'PWD': '/builds/slave/test', 14:29:09 INFO - 'SHELL': '/bin/bash', 14:29:09 INFO - 'SHLVL': '1', 14:29:09 INFO - 'TERM': 'linux', 14:29:09 INFO - 'TMOUT': '86400', 14:29:09 INFO - 'USER': 'cltbld', 14:29:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:09 INFO - '_': '/tools/buildbot/bin/python'} 14:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:10 INFO - Downloading/unpacking jsonschema==2.5.1 14:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:29:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:12 INFO - Downloading functools32-3.2.3-2.tar.gz 14:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:29:12 INFO - warning: no files found matching '*.txt' 14:29:12 INFO - no previously-included directories found matching 'build' 14:29:12 INFO - no previously-included directories found matching 'dist' 14:29:12 INFO - no previously-included directories found matching '.git*' 14:29:12 INFO - Installing collected packages: jsonschema, functools32 14:29:12 INFO - Running setup.py install for functools32 14:29:12 INFO - warning: no files found matching '*.txt' 14:29:12 INFO - no previously-included directories found matching 'build' 14:29:12 INFO - no previously-included directories found matching 'dist' 14:29:12 INFO - no previously-included directories found matching '.git*' 14:29:12 INFO - Successfully installed jsonschema functools32 14:29:12 INFO - Cleaning up... 14:29:12 INFO - Return code: 0 14:29:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:12 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:29:12 INFO - Using env: {'DISPLAY': ':0', 14:29:12 INFO - 'HOME': '/home/cltbld', 14:29:12 INFO - 'LANG': 'en_US.UTF-8', 14:29:12 INFO - 'LANGUAGE': 'en_US:en', 14:29:12 INFO - 'LOGNAME': 'cltbld', 14:29:12 INFO - 'MAIL': '/var/mail/cltbld', 14:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:12 INFO - 'MOZ_NO_REMOTE': '1', 14:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:12 INFO - 'NO_EM_RESTART': '1', 14:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:12 INFO - 'PWD': '/builds/slave/test', 14:29:12 INFO - 'SHELL': '/bin/bash', 14:29:12 INFO - 'SHLVL': '1', 14:29:12 INFO - 'TERM': 'linux', 14:29:12 INFO - 'TMOUT': '86400', 14:29:12 INFO - 'USER': 'cltbld', 14:29:12 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:12 INFO - '_': '/tools/buildbot/bin/python'} 14:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:29:12 INFO - Cleaning up... 14:29:12 INFO - Return code: 0 14:29:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:12 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:29:12 INFO - Using env: {'DISPLAY': ':0', 14:29:12 INFO - 'HOME': '/home/cltbld', 14:29:12 INFO - 'LANG': 'en_US.UTF-8', 14:29:12 INFO - 'LANGUAGE': 'en_US:en', 14:29:12 INFO - 'LOGNAME': 'cltbld', 14:29:12 INFO - 'MAIL': '/var/mail/cltbld', 14:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:12 INFO - 'MOZ_NO_REMOTE': '1', 14:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:12 INFO - 'NO_EM_RESTART': '1', 14:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:12 INFO - 'PWD': '/builds/slave/test', 14:29:12 INFO - 'SHELL': '/bin/bash', 14:29:12 INFO - 'SHLVL': '1', 14:29:12 INFO - 'TERM': 'linux', 14:29:12 INFO - 'TMOUT': '86400', 14:29:12 INFO - 'USER': 'cltbld', 14:29:12 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:12 INFO - '_': '/tools/buildbot/bin/python'} 14:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:12 INFO - Downloading/unpacking blobuploader==1.2.4 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:14 INFO - Downloading blobuploader-1.2.4.tar.gz 14:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:29:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:29:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:15 INFO - Downloading docopt-0.6.1.tar.gz 14:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:29:15 INFO - Installing collected packages: blobuploader, requests, docopt 14:29:15 INFO - Running setup.py install for blobuploader 14:29:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:29:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:29:15 INFO - Running setup.py install for requests 14:29:15 INFO - Running setup.py install for docopt 14:29:16 INFO - Successfully installed blobuploader requests docopt 14:29:16 INFO - Cleaning up... 14:29:16 INFO - Return code: 0 14:29:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:16 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:29:16 INFO - Using env: {'DISPLAY': ':0', 14:29:16 INFO - 'HOME': '/home/cltbld', 14:29:16 INFO - 'LANG': 'en_US.UTF-8', 14:29:16 INFO - 'LANGUAGE': 'en_US:en', 14:29:16 INFO - 'LOGNAME': 'cltbld', 14:29:16 INFO - 'MAIL': '/var/mail/cltbld', 14:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:16 INFO - 'MOZ_NO_REMOTE': '1', 14:29:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:16 INFO - 'NO_EM_RESTART': '1', 14:29:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:16 INFO - 'PWD': '/builds/slave/test', 14:29:16 INFO - 'SHELL': '/bin/bash', 14:29:16 INFO - 'SHLVL': '1', 14:29:16 INFO - 'TERM': 'linux', 14:29:16 INFO - 'TMOUT': '86400', 14:29:16 INFO - 'USER': 'cltbld', 14:29:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:16 INFO - '_': '/tools/buildbot/bin/python'} 14:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:16 INFO - Running setup.py (path:/tmp/pip-Udx2NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:16 INFO - Running setup.py (path:/tmp/pip-nK0Urq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:16 INFO - Running setup.py (path:/tmp/pip-ddnY8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:16 INFO - Running setup.py (path:/tmp/pip-Z1iamR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:16 INFO - Running setup.py (path:/tmp/pip-t4knQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:16 INFO - Running setup.py (path:/tmp/pip-oOMPCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:16 INFO - Running setup.py (path:/tmp/pip-FdmjhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:16 INFO - Running setup.py (path:/tmp/pip-2QOwZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:16 INFO - Running setup.py (path:/tmp/pip-8vrDT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:16 INFO - Running setup.py (path:/tmp/pip-Z0GzyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:17 INFO - Running setup.py (path:/tmp/pip-LyrpP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:17 INFO - Running setup.py (path:/tmp/pip-3vTvli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:17 INFO - Running setup.py (path:/tmp/pip-VQfD1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:17 INFO - Running setup.py (path:/tmp/pip-UbBDiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:17 INFO - Running setup.py (path:/tmp/pip-sm57gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:17 INFO - Running setup.py (path:/tmp/pip-Bbs_V9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:17 INFO - Running setup.py (path:/tmp/pip-JvOvwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:29:17 INFO - Running setup.py install for manifestparser 14:29:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:29:17 INFO - Running setup.py install for mozcrash 14:29:17 INFO - Running setup.py install for mozdebug 14:29:17 INFO - Running setup.py install for mozdevice 14:29:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:29:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:29:17 INFO - Running setup.py install for mozfile 14:29:18 INFO - Running setup.py install for mozhttpd 14:29:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for mozinfo 14:29:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for mozInstall 14:29:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for mozleak 14:29:18 INFO - Running setup.py install for mozlog 14:29:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for moznetwork 14:29:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for mozprocess 14:29:18 INFO - Running setup.py install for mozprofile 14:29:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for mozrunner 14:29:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Running setup.py install for mozscreenshot 14:29:19 INFO - Running setup.py install for moztest 14:29:19 INFO - Running setup.py install for mozversion 14:29:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:29:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:29:19 INFO - Cleaning up... 14:29:19 INFO - Return code: 0 14:29:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:19 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:29:19 INFO - Using env: {'DISPLAY': ':0', 14:29:19 INFO - 'HOME': '/home/cltbld', 14:29:19 INFO - 'LANG': 'en_US.UTF-8', 14:29:19 INFO - 'LANGUAGE': 'en_US:en', 14:29:19 INFO - 'LOGNAME': 'cltbld', 14:29:19 INFO - 'MAIL': '/var/mail/cltbld', 14:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:19 INFO - 'MOZ_NO_REMOTE': '1', 14:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:19 INFO - 'NO_EM_RESTART': '1', 14:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:19 INFO - 'PWD': '/builds/slave/test', 14:29:19 INFO - 'SHELL': '/bin/bash', 14:29:19 INFO - 'SHLVL': '1', 14:29:19 INFO - 'TERM': 'linux', 14:29:19 INFO - 'TMOUT': '86400', 14:29:19 INFO - 'USER': 'cltbld', 14:29:19 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:19 INFO - '_': '/tools/buildbot/bin/python'} 14:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:19 INFO - Running setup.py (path:/tmp/pip-LVgs8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:19 INFO - Running setup.py (path:/tmp/pip-XLCGwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:19 INFO - Running setup.py (path:/tmp/pip-Sb0rjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:19 INFO - Running setup.py (path:/tmp/pip-PQPnys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:19 INFO - Running setup.py (path:/tmp/pip-f5B16B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:19 INFO - Running setup.py (path:/tmp/pip-cm_CO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:19 INFO - Running setup.py (path:/tmp/pip-clkyFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:20 INFO - Running setup.py (path:/tmp/pip-2KDLeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:20 INFO - Running setup.py (path:/tmp/pip-VgavGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:20 INFO - Running setup.py (path:/tmp/pip-a9V2Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:20 INFO - Running setup.py (path:/tmp/pip-Gcov2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:20 INFO - Running setup.py (path:/tmp/pip-GlgMiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:20 INFO - Running setup.py (path:/tmp/pip-RSoBVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:20 INFO - Running setup.py (path:/tmp/pip-eOG48Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:20 INFO - Running setup.py (path:/tmp/pip-JN3mVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:20 INFO - Running setup.py (path:/tmp/pip-eKCUyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:20 INFO - Running setup.py (path:/tmp/pip-uVq8A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:22 INFO - Downloading blessings-1.6.tar.gz 14:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:29:22 INFO - Installing collected packages: blessings 14:29:22 INFO - Running setup.py install for blessings 14:29:22 INFO - Successfully installed blessings 14:29:22 INFO - Cleaning up... 14:29:22 INFO - Return code: 0 14:29:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:29:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:23 INFO - Reading from file tmpfile_stdout 14:29:23 INFO - Current package versions: 14:29:23 INFO - argparse == 1.2.1 14:29:23 INFO - blessings == 1.6 14:29:23 INFO - blobuploader == 1.2.4 14:29:23 INFO - docopt == 0.6.1 14:29:23 INFO - functools32 == 3.2.3-2 14:29:23 INFO - jsonschema == 2.5.1 14:29:23 INFO - manifestparser == 1.1 14:29:23 INFO - mozInstall == 1.12 14:29:23 INFO - mozcrash == 0.17 14:29:23 INFO - mozdebug == 0.1 14:29:23 INFO - mozdevice == 0.48 14:29:23 INFO - mozfile == 1.2 14:29:23 INFO - mozhttpd == 0.7 14:29:23 INFO - mozinfo == 0.9 14:29:23 INFO - mozleak == 0.1 14:29:23 INFO - mozlog == 3.3 14:29:23 INFO - moznetwork == 0.27 14:29:23 INFO - mozprocess == 0.23 14:29:23 INFO - mozprofile == 0.28 14:29:23 INFO - mozrunner == 6.12 14:29:23 INFO - mozscreenshot == 0.1 14:29:23 INFO - mozsystemmonitor == 0.3 14:29:23 INFO - moztest == 0.7 14:29:23 INFO - mozversion == 1.4 14:29:23 INFO - psutil == 3.1.1 14:29:23 INFO - requests == 1.2.3 14:29:23 INFO - wsgiref == 0.1.2 14:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:23 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:29:23 INFO - Using env: {'DISPLAY': ':0', 14:29:23 INFO - 'HOME': '/home/cltbld', 14:29:23 INFO - 'LANG': 'en_US.UTF-8', 14:29:23 INFO - 'LANGUAGE': 'en_US:en', 14:29:23 INFO - 'LOGNAME': 'cltbld', 14:29:23 INFO - 'MAIL': '/var/mail/cltbld', 14:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:23 INFO - 'MOZ_NO_REMOTE': '1', 14:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:23 INFO - 'NO_EM_RESTART': '1', 14:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:23 INFO - 'PWD': '/builds/slave/test', 14:29:23 INFO - 'SHELL': '/bin/bash', 14:29:23 INFO - 'SHLVL': '1', 14:29:23 INFO - 'TERM': 'linux', 14:29:23 INFO - 'TMOUT': '86400', 14:29:23 INFO - 'USER': 'cltbld', 14:29:23 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:23 INFO - '_': '/tools/buildbot/bin/python'} 14:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:29:23 INFO - Cleaning up... 14:29:23 INFO - Return code: 0 14:29:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:23 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 0x1257030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1486400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:29:23 INFO - Using env: {'DISPLAY': ':0', 14:29:23 INFO - 'HOME': '/home/cltbld', 14:29:23 INFO - 'LANG': 'en_US.UTF-8', 14:29:23 INFO - 'LANGUAGE': 'en_US:en', 14:29:23 INFO - 'LOGNAME': 'cltbld', 14:29:23 INFO - 'MAIL': '/var/mail/cltbld', 14:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:23 INFO - 'MOZ_NO_REMOTE': '1', 14:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:23 INFO - 'NO_EM_RESTART': '1', 14:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:23 INFO - 'PWD': '/builds/slave/test', 14:29:23 INFO - 'SHELL': '/bin/bash', 14:29:23 INFO - 'SHLVL': '1', 14:29:23 INFO - 'TERM': 'linux', 14:29:23 INFO - 'TMOUT': '86400', 14:29:23 INFO - 'USER': 'cltbld', 14:29:23 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:23 INFO - '_': '/tools/buildbot/bin/python'} 14:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:29:23 INFO - Cleaning up... 14:29:23 INFO - Return code: 0 14:29:23 INFO - Running post-action listener: _resource_record_post_action 14:29:23 INFO - Running post-action listener: _start_resource_monitoring 14:29:23 INFO - Starting resource monitoring. 14:29:23 INFO - [mozharness: 2016-09-20 21:29:23.575186Z] Finished create-virtualenv step (success) 14:29:23 INFO - [mozharness: 2016-09-20 21:29:23.575627Z] Running install step. 14:29:23 INFO - Running pre-action listener: _resource_record_pre_action 14:29:23 INFO - Running main action method: install 14:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:23 INFO - Reading from file tmpfile_stdout 14:29:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:29:23 INFO - Reading from file tmpfile_stdout 14:29:23 INFO - Output received: 14:29:23 INFO - Usage: mozinstall [options] installer 14:29:23 INFO - Options: 14:29:23 INFO - -h, --help show this help message and exit 14:29:23 INFO - -d DEST, --destination=DEST 14:29:23 INFO - Directory to install application into. [default: 14:29:23 INFO - "/builds/slave/test"] 14:29:23 INFO - --app=APP Application being installed. [default: firefox] 14:29:23 INFO - mkdir: /builds/slave/test/build/application 14:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:29:37 INFO - Reading from file tmpfile_stdout 14:29:37 INFO - Output received: 14:29:37 INFO - /builds/slave/test/build/application/firefox/firefox 14:29:37 INFO - Running post-action listener: _resource_record_post_action 14:29:37 INFO - [mozharness: 2016-09-20 21:29:37.361346Z] Finished install step (success) 14:29:37 INFO - [mozharness: 2016-09-20 21:29:37.361630Z] Running run-tests step. 14:29:37 INFO - Running pre-action listener: _resource_record_pre_action 14:29:37 INFO - Running main action method: run_tests 14:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:29:37 INFO - Python 2.7.3 14:29:37 INFO - Return code: 0 14:29:37 INFO - grabbing minidump binary from tooltool 14:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:37 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 0x1515570>, '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 0x1516470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1516900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:29:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:29:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:29:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:29:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:29:37 INFO - Return code: 0 14:29:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:29:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:29:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:29:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:29:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 14:29:37 INFO - Using env: {'DISPLAY': ':0', 14:29:37 INFO - 'HOME': '/home/cltbld', 14:29:37 INFO - 'LANG': 'en_US.UTF-8', 14:29:37 INFO - 'LANGUAGE': 'en_US:en', 14:29:37 INFO - 'LOGNAME': 'cltbld', 14:29:37 INFO - 'MAIL': '/var/mail/cltbld', 14:29:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:29:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:37 INFO - 'MOZ_NO_REMOTE': '1', 14:29:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:37 INFO - 'NO_EM_RESTART': '1', 14:29:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:37 INFO - 'PWD': '/builds/slave/test', 14:29:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:29:37 INFO - 'SHELL': '/bin/bash', 14:29:37 INFO - 'SHLVL': '1', 14:29:37 INFO - 'TERM': 'linux', 14:29:37 INFO - 'TMOUT': '86400', 14:29:37 INFO - 'USER': 'cltbld', 14:29:37 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726', 14:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:37 INFO - '_': '/tools/buildbot/bin/python'} 14:29:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:29:37 INFO - mozversion application_buildid: 20160920092516 14:29:37 INFO - mozversion application_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:29:37 INFO - mozversion application_display_name: Firefox 14:29:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:29:37 INFO - mozversion application_name: Firefox 14:29:37 INFO - mozversion application_remotingname: firefox 14:29:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:29:37 INFO - mozversion application_vendor: Mozilla 14:29:37 INFO - mozversion application_version: 50.0 14:29:37 INFO - mozversion platform_buildid: 20160920092516 14:29:37 INFO - mozversion platform_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:29:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:29:37 INFO - mozversion platform_version: 50.0 14:29:37 INFO - using testdate: 1474406977 14:29:37 INFO - actual date: 1474406977 14:29:37 INFO - starting webserver on 'localhost:50922' 14:29:37 INFO - SUITE-START | Running 2 tests 14:29:37 INFO - TEST-START | dromaeo_css 14:29:37 INFO - operating with platform_type : linux_ 14:29:37 INFO - Initialising browser for dromaeo_css test... 14:29:37 INFO - TEST-INFO | started process 1265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwNmyJo/profile http://localhost:50922/getInfo.html) 14:29:42 INFO - PROCESS | 1265 | __metrics Screen width/height:1600/1200 14:29:42 INFO - PROCESS | 1265 | colorDepth:24 14:29:42 INFO - PROCESS | 1265 | Browser inner width/height: 1024/697 14:29:42 INFO - PROCESS | 1265 | __metrics 14:29:42 ERROR - PROCESS | 1265 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:29:43 INFO - TEST-INFO | 1265: exit 0 14:29:43 INFO - Browser initialized. 14:29:43 INFO - Running cycle 1/1 for dromaeo_css test... 14:29:43 INFO - TEST-INFO | started process 1386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwNmyJo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:55 INFO - PROCESS | 1386 | RSS: Main: 149901312 14:29:55 INFO - PROCESS | 1386 | 14:30:44 INFO - PROCESS | 1386 | 1474407044952 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:32:53 INFO - PROCESS | 1386 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50922/tests/dromaeo/cssquery-ext.html) 14:32:54 INFO - PROCESS | 1386 | RSS: Main: 159035392 14:32:54 INFO - PROCESS | 1386 | 14:35:56 INFO - PROCESS | 1386 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-ext.html (next: http://localhost:50922/tests/dromaeo/cssquery-jquery.html) 14:35:57 INFO - PROCESS | 1386 | RSS: Main: 155701248 14:35:57 INFO - PROCESS | 1386 | 14:38:58 INFO - PROCESS | 1386 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50922/tests/dromaeo/cssquery-mootools.html) 14:38:59 INFO - PROCESS | 1386 | RSS: Main: 155451392 14:38:59 INFO - PROCESS | 1386 | 14:42:01 INFO - PROCESS | 1386 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50922/tests/dromaeo/cssquery-prototype.html) 14:42:01 INFO - PROCESS | 1386 | RSS: Main: 156524544 14:42:01 INFO - PROCESS | 1386 | 14:45:04 INFO - PROCESS | 1386 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50922/tests/dromaeo/cssquery-yui.html) 14:45:04 INFO - PROCESS | 1386 | RSS: Main: 157573120 14:45:04 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-yui.html (next: http://localhost:50922/tests/dromaeo/cssquery-dojo.html) 14:48:07 INFO - PROCESS | 1386 | RSS: Main: 157310976 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | __start_tp_report 14:48:07 INFO - PROCESS | 1386 | _x_x_mozilla_page_load 14:48:07 INFO - PROCESS | 1386 | _x_x_mozilla_page_load_details 14:48:07 INFO - PROCESS | 1386 | |i|pagename|runs| 14:48:07 INFO - PROCESS | 1386 | |0;dojo.html;3296.291297371065;3314.4199765041126;3322.0698204502733;3341.7493687973406;3350.329934013198;5816.098504731768;5922.970385148068;5935.436133567315;5938.287405511339;5942.138389933457;5837.299524057108;5886.793962211324;5896.292444906607;5903.114532820079;5923.259592550931;5310.362756469229;5345.7594408251525;5371.973140134294;5413.160960051178;5435.130379139338;5150.484951504838;5178.8705282368055;5195.636305458619;5242;5262.500062494076;5303.23103150043;5325.707086110262;5329.467053294678;5344.091504444254;5417.26866872973;5392.838214853541;5409.24270602116;5411.21537377082;5413.864653383657;5432.239486471897;5041.369828771403;5123.718195499246;5154.252633368141;5301.6818990860675;5309.177077552986;5667.093265077568;5713.742881570298;5732.942670573324;5767.711614419304;5769.971150144264;6185.288691800411;6231.906521402183;6235.594686345373;6240.968795156041;6241.282252540925;6049.576529642927;6179;6203.348648391899;6247.437730604267;6264.812055638338;5822.82531524055;5851.912221316684;5852.912206316909;5894.557908156906;5918.260217472816;6369.904451433232;6376.29860715321;6384.48924086072;6409.198850143732;6413.358664133642;5896.64620122794;6058.182145410313;6088.452039316483;6111.419415155553;6188.1027251048345;6196.907046394217;6211.9689401552705;6231.3145553989025;6235.781747638811;6245.406686364788;6174.44430001302;6177.907331390033;6183.4125758053815;6186.845328866814;6207.037909124093;5946.583739138218;6118.632882027093;6135.754569817246;6221.4089661482085;6224.221972253468;4705.670603057822;4712;4714.316424118552;4736.313234580966;4744.881377965578;4199.118185181085;4199.769012704331;4202.558731333166;4220.15596880619;4231.344141658048;5772.826815195551;5829.533637309005;5855.59010869235;5930.703464826742;6009.06859436788;6174.969125154345;6208.627482350983;6229.345918678564;6229.470495007975;6235.719392627343;5918.704064796743;5932.822015339547;5939.881202375928;5941.910871336933;5967.194428752063;5167.147420675622;5333.173358129496;5346.518813306821;5351.759170837322;5366.758495867695;6449.484041276686;6478.02829575558;6485.61086334812;6487;6495.1556297681;6300.810975670829;6302.086197501429;6303.432691057827;6305.148804911461;6306.432421082125;6060.515158787198;6268.467180289544;6278.0269058296035;6469.838254043687;6475.805725828232;6126.0416614718015;6134.84055711318;6136.978641397139;6141.025769127022;6150.200473938538;6181.5363847710705;6195.4424101831055;6248.875022499434;6251.624902505864;6256.968715156395;5879.764809407747;6082.878342433038;6085.786997455068;6085.817425477243;6098.054801505774;6146.754129834935;6153.261608606996;6165.691715414122;6166.81499555014;6169.1054797053275;6036.486898613492;6056.454919057306;6076.90884636722;6081.118237855575;6151.446369826737;5885.323187833454;6125.815438131457;6133.785317514044;6162.722677479434;6208.161898143693;6059.091136329585;6063;6081.6351018939;6082.482988945812;6095.481884039907 14:48:07 INFO - PROCESS | 1386 | |1;ext.html;580.8751118509522;636.096742625472;655.1450357283744;670.2928410526895;703.4302215205687;40741.18517629646;44615.107697846026;44974.55025449749;45095.32357014647;45101;46175.30737038947;46394.536054639444;46786.76606616962;46803.53196468034;46811.76594117033;9164.450132992008;9282.303827212952;9300.8604870927;9302.255819534455;9318.813623727556;9059.365844390895;9144.542772861363;9175;9176.632934682604;9203.4477931324;9217.631294748202;9265.444073355586;9272.582733776997;9278.907210927906;9281.767955801091;9111.726648200565;9152.4966126863;9165.679201227926;9175.357724959256;9175.58709858058;9162.404443711137;9190.540472976325;9212.861807072899;9220.44677319363;9231.861522077174;43927.560724392664;44226.33660495095;44228.77885610583;44351.77824110859;44402.333964990554;3607.4228123500375;3749.7187710921517;3781.6029316921604;3869.28418242626;3911;31331.843340783376;31533.684663153534;34583.19068387403;35356.292874142375;35540.64459355425;4683.718976861399;4702.153612349775;4721.976390118062;4752.287156926454;4790.137775200463;359934;362354;363506;363779;364987.9999999973;59718.40281597128;60176.39823601796;60200.69899650474;60337;60366;39639.40540891889;40726.18547629031;40750.388744168864;42466.78766606147;65028.67485662542;15918.204089795463;15918.840811591968;16140.112293823953;16152.111633860015;16163.43427980015;12111.515539378326;12123.878761212274;12141.332226727438;12198.57304994321;12214.26714397139;20106;20168.19327226924;20319.593608127754;20403.38789836307;20488.282910098074;7365.52124111931;7379.409647228209;7665.540067595851;7675.848622706639;7695.769126926201;977.6187286958092;1052.5105825791045;1087.9619213327653;1151.3840095548842;1160.257435241446;31714.682853171173;32530.349393012006;32633.22149491251;32757;33577.83211083978;1127.875933647298;1141.1213365708359;1147.6270212181075;1202.8316035755001;1203.3682316783759;17306.913465432594;17347.566310842078;17389.91305043492;17402.21690023952;17593.648127037388;482991;483121;484910;486331;488681;59982.400175998555;60650.39349606536;60736;60736;60776.08835867466;1418.716256748675;1419.8012278281049;1420.1762977473247;1438.4893362856244;1444.6532832120215;30668.846655766578;31636.525452118698;32079.518807218377;32325.838370807996;32900.177495561846;52546.211806823696;52955;52968.73515632397;53047.46952530425;53116.734416329215;400528;401794;401933;402418;404009;28593.42813143767;29343;29773.255668608457;29820;29928.102156935325;49122.263166051824;49546.50453495419;49786.75106624444;50029.49970500394;50572.24141637952;42681;44124;44129.33805992977;44218.557814421445;45081;42154.789226053676;42596.148077037666;42861;44192.11615767602;44417;41751.37372939469;42202.788986054875;42328.365074524525;42784.14431711411;42954.78522607367;37261;38032.42951355787;38371.424428633036;38893.41659875161;38954.02614934649;28884.27789305284;29163.125106246847;29308.560371594016;29370.559441607966;29392 14:48:07 INFO - PROCESS | 1386 | |2;jquery.html;401.82922258040327;412.6966679490576;423.1811444854207;425.21547744411566;429.0796242060779;19729.11218995145;20533.897330513362;21321.786782132192;21770.238041668545;22124.668129978065;20119.497012574655;20344.084516196806;20418.795812041913;20571.897140514277;21072.68390974133;5793.275840519935;6056.545759068065;6071.058985857199;6089.330173680892;6099.878002439948;5822.767089316422;5909.349971503131;5910.852228694295;5930.911036334458;5961.552883533731;5902.409759024106;5924.763009479616;5932.406759324076;5934.970325148368;5944.959632064393;5753.769849206027;5890.499307558863;5923.614965027259;5931.822045338647;5981.431763982415;5359.705216213107;5376.596755243372;5390.811321603725;5392.865178370572;5403.189521571757;21329.893350533304;21358.359249222394;24792.132275370277;24884.3778905527;25001;2503.8998440062373;2571.472848066142;2603.06289735695;2651.0721247563392;2697.892084316625;15445.150516721576;15661.451849185223;15768.76346854798;15822.76265856013;15924.76112858308;2043.795620437959;2152.612529744645;2156.8382371322214;2176.249194028058;2198.967015494769;85175;86486.70269945957;86553.56723216343;87694.56152719195;88012.5599371999;10650.627228046982;11150.609728659458;11256.606018789304;11299.740051989635;11342.20604557673;15855.969361991434;15858.920705396398;15981.520554383387;15998.76001859973;16007.759883601755;8185.427020108655;8281.29608983231;8363.414560980673;8431.831363372821;8464.619092140869;7980.3615710743;8009.399295052895;8036.236557527026;8136.552489613128;8141.674333026731;6762.594244345355;6968.163820341591;6971.337722916315;7075.540089894141;7082.468814838805;2035.6641154209685;2037.6535988881926;2080.0847627044054;2086.2698055680694;2087.048609550045;963.8795150606175;1015.898410158993;1067.7971185474735;1072.996748040569;1133.4582840865794;20724.481887952923;20794.896025519774;20960.266390676254;21029.579408412053;21144.894275528524;1027.8920713324994;1069.9625513107003;1072.6889202131294;1073.1522097542854;1083.4041277297456;11650.41747912601;11689.930333139704;11841.467133978991;11905.642830715093;12063;529713;543709;546157;557374;559605;81686;85730.57134714286;87947;88049;88184.11815881888;1001.2628090263532;1005.8742657167854;1022;1026.825439675242;1033.9689809305733;18043.188056537223;18206.635867282846;18613.534661633566;18640.34758783436;18683.065846707337;42268.788656056524;42309.57690423056;42721.14557708891;43029.35455968226;43661.34507982447;54215.45784542104;54486.45513544972;55980;57419.1387129185;57639.711801440724;9113.589888454902;9125.726228213165;9160.450372977642;9192.49441280736;9283.48940808262;13785.241811700453;13915.773476243752;14035.929820350833;14299.428022878968;14420.423183072979;12022.75954480923;12275.69310767238;12337.93831030875;12349.629511114681;12791.296478693761;11939.641810745692;12136.635900922633;12328.32194229326;12471.688207794516;12613.684657883627;12133.332666703063;12156.513739450676;12376.62870113898;12400.19398739079;12885.742285154432;9054.909450905407;9212.355135140477;9221.354505184572;9250.306227032865;9402.764930876783;12392.62822115338;12420.875791241971;12433.191842530206;12452.190607610477;12535.561255356384 14:48:07 INFO - PROCESS | 1386 | |3;mootools.html;1544.7451170556863;1555.463365139027;1703.3527259641335;1804.8104948980356;1838.8528917686585;8431.873521897176;9097.090290970908;9209;9761.121499065091;9838.212942964554;9206.815863682723;9566.28252881035;9610.759731006729;9615.182709469686;9626.229901607872;2485.0929410765075;2536.1757428835704;2537.949241015188;2540.4538024324766;2543.605741110131;2503.0613519930025;2590.5984572391217;2594.1439325022793;2595.065776320523;2595.208461419276;2513.4973005398992;2529.8229123961332;2549.6430499730054;2561.743825617433;2563.1541591274836;2486.353548077495;2487.614419764939;2487.8631675257852;2494.5010997800423;2509.535735888848;2448.0574978633176;2470.3947532854568;2472.5673007223663;2493.2644869763462;2535.1760677779794;9662.468564228964;9685.854712179253;9701.563429645683;9729.513524323826;9765.121139097515;5568.49883510482;5730.512906402961;5745.54035677149;5750.05124154514;5752.654840709529;3524.4184709522897;3549.07723991764;3644.6173151818944;3672.8530858765616;3738.4953031340965;1177.3995262416145;1218.2507757729022;1222.1750318534953;2289.587874182663;2385.3678775124604;12861.87138128616;12909.483620655257;13004.804927926089;13057.34713264333;13076.803847942288;4799.928001079917;4826.32431459596;4883.536064073978;4901.313816065754;4999.75001249936;10681.679549613524;10733.248672592843;10744.247902646895;10779.78440431187;10786.514606842555;5598.804041858515;5610.523105535994;5617.662940223599;5622.7750889963945;5628.774849006076;4929.186684197068;5101.923471147936;5106.872328191825;5183.818566350235;5219.373675159005;4185.414042034117;4212.241796476603;4236.6610671146855;4270.252705776476;4397;1257.88050135237;1264.538443468146;1267.8922291605318;1268.6067319131098;1269.9047571431995;976.9658061967939;1075.5160177919968;1080.9243352965375;1093.830456279278;1094.0536435982817;6543.1821022372205;6561.179852518435;6757.493188010919;6860.759873404507;6882.03651488792;484.6050468867861;493.1985523524273;497.477648469106;500.63703814734026;504.50810459802136;4407.206702793464;4556.248219043887;4571;4702.929456058162;4889.926651100236;12082.637520874388;12199.963003144832;12202.206856554296;12210.816837747441;12411.937940310421;11343.20597558163;11819.290842549475;11843.703907402383;12154.392280385946;12212.877871221173;9015.368924175376;9212.401193922507;9222.170004699608;9237.861432078524;9251.67619133327;6466.385693359123;6509.349065093453;6665.300143484864;6804.285550017177;6813.591184528944;11074.169437292074;11118.777624447628;11208.439578021229;11266.436678165896;11502.079833613123;11277.210595258253;11532.192746507664;11622.0702343814;11672.533098675958;11735.530578777094;8399.202075802788;8547.61535730906;8635.6977505787;8688.609012594323;8791.296696264411;9550.235981121632;9645.565949532147;9662.13040826329;9669.564869581027;9670.323077384797;8293.460925039853;8324.875126872981;8394.958025209835;8398.664053437795;8506.532140732072;8330.291925186428;8343.499390036617;8347.749567512985;8362.414630975773;8495.702650407206;7892.763217103496;7979.601019949096;8014.919850801417;8025.9197408025175;8045.316148127475;5520.282363292747;5713.714314284352;5728.799492017926;5848.181254624271;5956.761729530944;5680.6591604503865;5769.826905192851;5784.566157538286;5861.267341582302;5880.500157486662 14:48:07 INFO - PROCESS | 1386 | |4;prototype.html;352.48889110789355;353.8398079723291;355.2114306240146;359.14165145302724;359.9010272175153;5855.5022823060035;5899.941000590004;5925.140854576084;5936.5250779937605;5948.7323070461825;5788.292453726151;5833.82498525045;5870.417016512732;5886.705664716757;5939.376365481617;4557.840475583355;4564.771761411933;4578.397560853708;4614.892425817797;4620.237660785966;4564.97717511412;4571.131485017836;4576.290674945389;4580.633549316063;4587.518310577391;4549.426079315391;4560.3843481130125;4589.219832628444;4598.9422432840465;4606.424196975378;4456.48750393704;4526.592606665399;4552.795124219385;4557.726536407826;4563.680542362019;4049.311617025113;4068.616670332072;4073.450084238649;4077.8368865245357;4078.6940979426663;5692.345380281238;5760.251167348218;5787.363390027093;5795.942040579583;5801.419858014207;5140.917928239069;5185.429602743695;5190;5198.870028249286;5203.372296990034;6044.365341639153;6080.726367313438;6114.510839132859;6178.691065446755;6183.505319574469;4391.802368893407;4454.2873140297725;4460.241758900962;4465.1739428205565;4494.056248187884;5946.226990491252;6119.785807496716;6299.8740025199695;6323.9051414228825;6344.206974128234;4496.21316269658;4504.797284122223;4533.637309015271;4535.455745310518;4537.708457229078;4579.107074120581;4587.128445595326;4588.931166032512;4609.285560738091;4630.768461576908;5125.615578831603;5196.58427325813;5221.7128057956415;5236.633435659467;5237.5548078413;6096.329403765582;6099.329073801879;6107.358727333655;6130.877382452415;6135.9079613805825;1568.4902406717863;1589.9841001590066;1593.2670971353307;1594.7209238383236;1594.7687585300298;1004.4023805835662;1019.0993796569345;1029.562435964712;1046.2571574182416;1073.2648136026846;5861.296644402699;5871.765129394862;5892.234009578728;5902.58681892272;5926.525877929756;6179.752809887644;6190.690465476709;6225.128482012522;6244.37556244381;6267.6866156691985;5678.233438485751;5817.301923769175;5817.534597232211;5875.500582450454;5876.41235876409;3287.7041066304146;3290.3748287825715;3321.2527181383994;3329.8002119872885;3360.19355354718;6250.656213908188;6466.935330646634;6495.805125846232;6563.671816409256;6633.2703402625675;6357.618542887441;6388.456981156561;6428.71070801815;6462.903056454157;6466.256380516207;6357.982722764335;6490.513211509156;6589.308122647149;6589.439897608757;6596.317938925543;6257.906131408124;6331.145295385064;6339.1125242467015;6354.714037868214;6358.936410635834;6116.877662446637;6255.749770009149;6260.906086408799;6284.4344009039405;6310.936890631035;6465.288818229897;6518.250401203923;6523.282438931618;6526.836829079311;6532.7336331834085;4500.347449619852;4536.750478723702;4548.704334218398;4562.749048802348;4564.589186973189;6204.565680402328;6333.714982825692;6372.426481616677;6410.8076757697345;6428.871422571428;6140.355262697352;6161.874153417064;6178.62928224308;6211.81364559064;6258.780942666985;5815.214945982408;6152.7538898445355;6220.222472190976;6244.594101383395;6281.299041412237;6076.726547305293;6150.723217455135;6172.537059720628;6178.289496707756;6220.688965551795;6253.8749225014335;6264.874702506016;6300.936990630035;6305.4009869062365;6324;5921.3190483094995;6046.697665116814;6077.453029227391;6079.604825686316;6123.775244950939 14:48:07 INFO - PROCESS | 1386 | |5;yui.html;772.5248971882294;788.656934233608;817.1950628630799;824.4393812207696;889.7286327670062;15602.453914112994;16641.50075497734;16696.49910502687;16721.581960450996;16722.16389180539;15708.057516549015;15784.605384865385;15819.920900395511;15831.812614053935;15845.762313565307;723.3200791256203;840.1976112812249;851.1318455175717;854.4488804720951;863.1756672377865;871.1419252036738;871.716692075075;871.9825603487926;872.1583671756754;873.244643383472;913.899471058183;914.2914241462886;915.7069737683944;916.1022198245723;917.3440989692368;900.1628485508475;903.9096090390974;904.8552231642972;905.3798148268457;908.5593487158723;675.2976904019863;676.4791110844623;678.0575000748963;678.5385937562455;679.7043286170532;15539.766903496344;15644.217789110613;15650.921745391313;15671.294791734399;15875.841241587552;14167.929160354131;14238.857611423857;14717.411303547846;16320.510384688478;17131.57171070721;13547.45810167592;13595;14147.929260353736;14314.42742290307;14666;4731.574158325733;4778.331033655291;4907.779149938261;4948.505149485059;4951.455339912607;84870;86540.56729716374;86978.5651071747;88889.55555222247;89100.55449722774;11847.466863991142;12493.375331233401;12587.748245035047;12599.93700031494;12627.936860315824;22458.213962511232;22590;22681.092756289894;22707.09171633149;22762.544749104924;8520.829583408422;8641.870371944426;8646.740597782076;8721.694740684046;9032.458052516738;4872.171730805771;4961.330220420197;5038.974805125951;5049.267856160836;5060.240963855377;3610.9638903611153;3681.153334732973;3734.589195188527;3747.1194269346684;3754.8310326035394;625.5308518611042;629.1506466270498;629.9811005669837;640.2285246278224;652.6051738698114;705.5978092487336;711.2460791560962;731.5464412064554;739.0946091038543;742.5470463017555;8927.78599704106;9106.817863642822;9469.715908522756;9647.855282170773;9824.705258842247;621.2451870976785;705.5272967112047;710.9289071092953;716.5843810589845;737.7270409948246;4273.401723758676;4441.71128876622;4491.977540112344;4495.235809912323;4521.389612402284;98028.50985745026;130125;130166.34916825355;133862.33068834594;134557.32721336526;92275.53862230646;104604;106879;107400.46299768452;109694;55514;83930.58034709787;84021.57989210138;87532.56233718919;94087;8650.56747162652;8887.600057997535;8967.865482017905;9388.202002829836;9474.715758527254;53210.2018469731;54391.728041359536;55059.17411238915;55099.72450137884;55728.72135639296;76828;81016.18983810087;82960.58519707363;85110;85963.57018214869;13237.867621324049;13251.66870828237;13458.192508449525;13494;13656.93171534136;14017.158970461805;14169;14468.131912085308;14508.709825803637;14807.629809254855;14030.649233769238;14119;14694.779578306549;14703.411863525767;14753.483628072965;13662.31688415595;13773.862261377659;13985.440582376998;14883.553493395215;14930.85069149338;13827.446902123802;13854.955776781962;13940.233287169303;14719;15021.098734075991;11688.240264383128;11846.407679615812;11955.342456164646;11971.461284242405;12415.813762793747;10945.781084378108;11499.310041397543;11538.653840384803;11889.346085965355;11933.582324618597 14:48:07 INFO - PROCESS | 1386 | __end_tp_report 14:48:07 INFO - PROCESS | 1386 | __start_cc_report 14:48:07 INFO - PROCESS | 1386 | _x_x_mozilla_cycle_collect,356 14:48:07 INFO - PROCESS | 1386 | __end_cc_report 14:48:07 INFO - PROCESS | 1386 | __startTimestamp1474408087456__endTimestamp 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | ------- Summary: start ------- 14:48:07 INFO - PROCESS | 1386 | Number of tests: 6 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | [#0] dojo.html Cycles:165 Average:5783.86 Median:6058.18 stddev:665.99 (11.0%) stddev-sans-first:638.80 14:48:07 INFO - PROCESS | 1386 | Values: 3296.3 3314.4 3322.1 3341.7 3350.3 5816.1 5923.0 5935.4 5938.3 5942.1 5837.3 5886.8 5896.3 5903.1 5923.3 5310.4 5345.8 5372.0 5413.2 5435.1 5150.5 5178.9 5195.6 5242.0 5262.5 5303.2 5325.7 5329.5 5344.1 5417.3 5392.8 5409.2 5411.2 5413.9 5432.2 5041.4 5123.7 5154.3 5301.7 5309.2 5667.1 5713.7 5732.9 5767.7 5770.0 6185.3 6231.9 6235.6 6241.0 6241.3 6049.6 6179.0 6203.3 6247.4 6264.8 5822.8 5851.9 5852.9 5894.6 5918.3 6369.9 6376.3 6384.5 6409.2 6413.4 5896.6 6058.2 6088.5 6111.4 6188.1 6196.9 6212.0 6231.3 6235.8 6245.4 6174.4 6177.9 6183.4 6186.8 6207.0 5946.6 6118.6 6135.8 6221.4 6224.2 4705.7 4712.0 4714.3 4736.3 4744.9 4199.1 4199.8 4202.6 4220.2 4231.3 5772.8 5829.5 5855.6 5930.7 6009.1 6175.0 6208.6 6229.3 6229.5 6235.7 5918.7 5932.8 5939.9 5941.9 5967.2 5167.1 5333.2 5346.5 5351.8 5366.8 6449.5 6478.0 6485.6 6487.0 6495.2 6300.8 6302.1 6303.4 6305.1 6306.4 6060.5 6268.5 6278.0 6469.8 6475.8 6126.0 6134.8 6137.0 6141.0 6150.2 6181.5 6195.4 6248.9 6251.6 6257.0 5879.8 6082.9 6085.8 6085.8 6098.1 6146.8 6153.3 6165.7 6166.8 6169.1 6036.5 6056.5 6076.9 6081.1 6151.4 5885.3 6125.8 6133.8 6162.7 6208.2 6059.1 6063.0 6081.6 6082.5 6095.5 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | [#1] ext.html Cycles:180 Average:58658.18 Median:42822.57 stddev:110797.54 (258.7%) stddev-sans-first:111022.55 14:48:07 INFO - PROCESS | 1386 | Values: 580.9 636.1 655.1 670.3 703.4 40741.2 44615.1 44974.6 45095.3 45101.0 46175.3 46394.5 46786.8 46803.5 46811.8 9164.5 9282.3 9300.9 9302.3 9318.8 9059.4 9144.5 9175.0 9176.6 9203.4 9217.6 9265.4 9272.6 9278.9 9281.8 9111.7 9152.5 9165.7 9175.4 9175.6 9162.4 9190.5 9212.9 9220.4 9231.9 43927.6 44226.3 44228.8 44351.8 44402.3 3607.4 3749.7 3781.6 3869.3 3911.0 31331.8 31533.7 34583.2 35356.3 35540.6 4683.7 4702.2 4722.0 4752.3 4790.1 359934.0 362354.0 363506.0 363779.0 364988.0 59718.4 60176.4 60200.7 60337.0 60366.0 39639.4 40726.2 40750.4 42466.8 65028.7 15918.2 15918.8 16140.1 16152.1 16163.4 12111.5 12123.9 12141.3 12198.6 12214.3 20106.0 20168.2 20319.6 20403.4 20488.3 7365.5 7379.4 7665.5 7675.8 7695.8 977.6 1052.5 1088.0 1151.4 1160.3 31714.7 32530.3 32633.2 32757.0 33577.8 1127.9 1141.1 1147.6 1202.8 1203.4 17306.9 17347.6 17389.9 17402.2 17593.6 482991.0 483121.0 484910.0 486331.0 488681.0 59982.4 60650.4 60736.0 60736.0 60776.1 1418.7 1419.8 1420.2 1438.5 1444.7 30668.8 31636.5 32079.5 32325.8 32900.2 52546.2 52955.0 52968.7 53047.5 53116.7 400528.0 401794.0 401933.0 402418.0 404009.0 28593.4 29343.0 29773.3 29820.0 29928.1 49122.3 49546.5 49786.8 50029.5 50572.2 42681.0 44124.0 44129.3 44218.6 45081.0 42154.8 42596.1 42861.0 44192.1 44417.0 41751.4 42202.8 42328.4 42784.1 42954.8 37261.0 38032.4 38371.4 38893.4 38954.0 28884.3 29163.1 29308.6 29370.6 29392.0 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | [#2] jquery.html Cycles:180 Average:31197.51 Median:23458.40 stddev:89831.93 (382.9%) stddev-sans-first:90054.17 14:48:07 INFO - PROCESS | 1386 | Values: 401.8 412.7 423.2 425.2 429.1 19729.1 20533.9 21321.8 21770.2 22124.7 20119.5 20344.1 20418.8 20571.9 21072.7 5793.3 6056.5 6071.1 6089.3 6099.9 5822.8 5909.3 5910.9 5930.9 5961.6 5902.4 5924.8 5932.4 5935.0 5945.0 5753.8 5890.5 5923.6 5931.8 5981.4 5359.7 5376.6 5390.8 5392.9 5403.2 21329.9 21358.4 24792.1 24884.4 25001.0 2503.9 2571.5 2603.1 2651.1 2697.9 15445.2 15661.5 15768.8 15822.8 15924.8 2043.8 2152.6 2156.8 2176.2 2199.0 85175.0 86486.7 86553.6 87694.6 88012.6 10650.6 11150.6 11256.6 11299.7 11342.2 15856.0 15858.9 15981.5 15998.8 16007.8 8185.4 8281.3 8363.4 8431.8 8464.6 7980.4 8009.4 8036.2 8136.6 8141.7 6762.6 6968.2 6971.3 7075.5 7082.5 2035.7 2037.7 2080.1 2086.3 2087.0 963.9 1015.9 1067.8 1073.0 1133.5 20724.5 20794.9 20960.3 21029.6 21144.9 1027.9 1070.0 1072.7 1073.2 1083.4 11650.4 11689.9 11841.5 11905.6 12063.0 529713.0 543709.0 546157.0 557374.0 559605.0 81686.0 85730.6 87947.0 88049.0 88184.1 1001.3 1005.9 1022.0 1026.8 1034.0 18043.2 18206.6 18613.5 18640.3 18683.1 42268.8 42309.6 42721.1 43029.4 43661.3 54215.5 54486.5 55980.0 57419.1 57639.7 9113.6 9125.7 9160.5 9192.5 9283.5 13785.2 13915.8 14035.9 14299.4 14420.4 12022.8 12275.7 12337.9 12349.6 12791.3 11939.6 12136.6 12328.3 12471.7 12613.7 12133.3 12156.5 12376.6 12400.2 12885.7 9054.9 9212.4 9221.4 9250.3 9402.8 12392.6 12420.9 12433.2 12452.2 12535.6 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | [#3] mootools.html Cycles:180 Average:6357.38 Median:2684.07 stddev:3624.75 (135.0%) stddev-sans-first:3616.88 14:48:07 INFO - PROCESS | 1386 | Values: 1544.7 1555.5 1703.4 1804.8 1838.9 8431.9 9097.1 9209.0 9761.1 9838.2 9206.8 9566.3 9610.8 9615.2 9626.2 2485.1 2536.2 2537.9 2540.5 2543.6 2503.1 2590.6 2594.1 2595.1 2595.2 2513.5 2529.8 2549.6 2561.7 2563.2 2486.4 2487.6 2487.9 2494.5 2509.5 2448.1 2470.4 2472.6 2493.3 2535.2 9662.5 9685.9 9701.6 9729.5 9765.1 5568.5 5730.5 5745.5 5750.1 5752.7 3524.4 3549.1 3644.6 3672.9 3738.5 1177.4 1218.3 1222.2 2289.6 2385.4 12861.9 12909.5 13004.8 13057.3 13076.8 4799.9 4826.3 4883.5 4901.3 4999.8 10681.7 10733.2 10744.2 10779.8 10786.5 5598.8 5610.5 5617.7 5622.8 5628.8 4929.2 5101.9 5106.9 5183.8 5219.4 4185.4 4212.2 4236.7 4270.3 4397.0 1257.9 1264.5 1267.9 1268.6 1269.9 977.0 1075.5 1080.9 1093.8 1094.1 6543.2 6561.2 6757.5 6860.8 6882.0 484.6 493.2 497.5 500.6 504.5 4407.2 4556.2 4571.0 4702.9 4889.9 12082.6 12200.0 12202.2 12210.8 12411.9 11343.2 11819.3 11843.7 12154.4 12212.9 9015.4 9212.4 9222.2 9237.9 9251.7 6466.4 6509.3 6665.3 6804.3 6813.6 11074.2 11118.8 11208.4 11266.4 11502.1 11277.2 11532.2 11622.1 11672.5 11735.5 8399.2 8547.6 8635.7 8688.6 8791.3 9550.2 9645.6 9662.1 9669.6 9670.3 8293.5 8324.9 8395.0 8398.7 8506.5 8330.3 8343.5 8347.7 8362.4 8495.7 7892.8 7979.6 8014.9 8025.9 8045.3 5520.3 5713.7 5728.8 5848.2 5956.8 5680.7 5769.8 5784.6 5861.3 5880.5 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | [#4] prototype.html Cycles:180 Average:5187.61 Median:5875.96 stddev:1523.31 (25.9%) stddev-sans-first:1483.73 14:48:07 INFO - PROCESS | 1386 | Values: 352.5 353.8 355.2 359.1 359.9 5855.5 5899.9 5925.1 5936.5 5948.7 5788.3 5833.8 5870.4 5886.7 5939.4 4557.8 4564.8 4578.4 4614.9 4620.2 4565.0 4571.1 4576.3 4580.6 4587.5 4549.4 4560.4 4589.2 4598.9 4606.4 4456.5 4526.6 4552.8 4557.7 4563.7 4049.3 4068.6 4073.5 4077.8 4078.7 5692.3 5760.3 5787.4 5795.9 5801.4 5140.9 5185.4 5190.0 5198.9 5203.4 6044.4 6080.7 6114.5 6178.7 6183.5 4391.8 4454.3 4460.2 4465.2 4494.1 5946.2 6119.8 6299.9 6323.9 6344.2 4496.2 4504.8 4533.6 4535.5 4537.7 4579.1 4587.1 4588.9 4609.3 4630.8 5125.6 5196.6 5221.7 5236.6 5237.6 6096.3 6099.3 6107.4 6130.9 6135.9 1568.5 1590.0 1593.3 1594.7 1594.8 1004.4 1019.1 1029.6 1046.3 1073.3 5861.3 5871.8 5892.2 5902.6 5926.5 6179.8 6190.7 6225.1 6244.4 6267.7 5678.2 5817.3 5817.5 5875.5 5876.4 3287.7 3290.4 3321.3 3329.8 3360.2 6250.7 6466.9 6495.8 6563.7 6633.3 6357.6 6388.5 6428.7 6462.9 6466.3 6358.0 6490.5 6589.3 6589.4 6596.3 6257.9 6331.1 6339.1 6354.7 6358.9 6116.9 6255.7 6260.9 6284.4 6310.9 6465.3 6518.3 6523.3 6526.8 6532.7 4500.3 4536.8 4548.7 4562.7 4564.6 6204.6 6333.7 6372.4 6410.8 6428.9 6140.4 6161.9 6178.6 6211.8 6258.8 5815.2 6152.8 6220.2 6244.6 6281.3 6076.7 6150.7 6172.5 6178.3 6220.7 6253.9 6264.9 6300.9 6305.4 6324.0 5921.3 6046.7 6077.5 6079.6 6123.8 14:48:07 INFO - PROCESS | 1386 | 14:48:07 INFO - PROCESS | 1386 | [#5] yui.html Cycles:180 Average:22052.07 Median:4889.98 stddev:32112.35 (656.7%) stddev-sans-first:32162.68 14:48:07 INFO - PROCESS | 1386 | Values: 772.5 788.7 817.2 824.4 889.7 15602.5 16641.5 16696.5 16721.6 16722.2 15708.1 15784.6 15819.9 15831.8 15845.8 723.3 840.2 851.1 854.4 863.2 871.1 871.7 872.0 872.2 873.2 913.9 914.3 915.7 916.1 917.3 900.2 903.9 904.9 905.4 908.6 675.3 676.5 678.1 678.5 679.7 15539.8 15644.2 15650.9 15671.3 15875.8 14167.9 14238.9 14717.4 16320.5 17131.6 13547.5 13595.0 14147.9 14314.4 14666.0 4731.6 4778.3 4907.8 4948.5 4951.5 84870.0 86540.6 86978.6 88889.6 89100.6 11847.5 12493.4 12587.7 12599.9 12627.9 22458.2 22590.0 22681.1 22707.1 22762.5 8520.8 8641.9 8646.7 8721.7 9032.5 4872.2 4961.3 5039.0 5049.3 5060.2 3611.0 3681.2 3734.6 3747.1 3754.8 625.5 629.2 630.0 640.2 652.6 705.6 711.2 731.5 739.1 742.5 8927.8 9106.8 9469.7 9647.9 9824.7 621.2 705.5 710.9 716.6 737.7 4273.4 4441.7 4492.0 4495.2 4521.4 98028.5 130125.0 130166.3 133862.3 134557.3 92275.5 104604.0 106879.0 107400.5 109694.0 55514.0 83930.6 84021.6 87532.6 94087.0 8650.6 8887.6 8967.9 9388.2 9474.7 53210.2 54391.7 55059.2 55099.7 55728.7 76828.0 81016.2 82960.6 85110.0 85963.6 13237.9 13251.7 13458.2 13494.0 13656.9 14017.2 14169.0 14468.1 14508.7 14807.6 14030.6 14119.0 14694.8 14703.4 14753.5 13662.3 13773.9 13985.4 14883.6 14930.9 13827.4 13855.0 13940.2 14719.0 15021.1 11688.2 11846.4 11955.3 11971.5 12415.8 10945.8 11499.3 11538.7 11889.3 11933.6 14:48:07 INFO - PROCESS | 1386 | -------- Summary: end -------- 14:48:07 INFO - PROCESS | 1386 | 14:48:08 INFO - TEST-INFO | 1386: exit 0 14:48:08 INFO - TEST-OK | dromaeo_css | took 1110495ms 14:48:08 INFO - TEST-START | kraken 14:48:08 INFO - operating with platform_type : linux_ 14:48:08 INFO - Initialising browser for kraken test... 14:48:08 INFO - TEST-INFO | started process 1621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAPwmLB/profile http://localhost:50922/getInfo.html) 14:48:12 INFO - PROCESS | 1621 | __metrics Screen width/height:1600/1200 14:48:12 INFO - PROCESS | 1621 | colorDepth:24 14:48:12 INFO - PROCESS | 1621 | Browser inner width/height: 1024/697 14:48:12 INFO - PROCESS | 1621 | __metrics 14:48:13 ERROR - PROCESS | 1621 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:48:13 INFO - TEST-INFO | 1621: exit 0 14:48:13 INFO - Browser initialized. 14:48:13 INFO - Running cycle 1/1 for kraken test... 14:48:13 INFO - TEST-INFO | started process 1745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAPwmLB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:48:25 INFO - PROCESS | 1745 | RSS: Main: 150020096 14:48:25 INFO - PROCESS | 1745 | 14:48:28 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-beat-detection) 14:48:28 INFO - PROCESS | 1745 | RSS: Main: 156856320 14:48:28 INFO - PROCESS | 1745 | 14:48:31 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-dft) 14:48:32 INFO - PROCESS | 1745 | RSS: Main: 155705344 14:48:32 INFO - PROCESS | 1745 | 14:48:35 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-fft) 14:48:35 INFO - PROCESS | 1745 | RSS: Main: 156631040 14:48:35 INFO - PROCESS | 1745 | 14:48:37 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-oscillator) 14:48:38 INFO - PROCESS | 1745 | RSS: Main: 156127232 14:48:38 INFO - PROCESS | 1745 | 14:48:40 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50922/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:48:41 INFO - PROCESS | 1745 | RSS: Main: 156684288 14:48:41 INFO - PROCESS | 1745 | 14:48:44 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50922/tests/kraken/driver.html?testName=imaging-darkroom) 14:48:44 INFO - PROCESS | 1745 | RSS: Main: 156266496 14:48:44 INFO - PROCESS | 1745 | 14:48:48 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50922/tests/kraken/driver.html?testName=imaging-desaturate) 14:48:48 INFO - PROCESS | 1745 | RSS: Main: 156692480 14:48:48 INFO - PROCESS | 1745 | 14:48:51 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50922/tests/kraken/driver.html?testName=json-parse-financial) 14:48:52 INFO - PROCESS | 1745 | RSS: Main: 156856320 14:48:52 INFO - PROCESS | 1745 | 14:48:53 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50922/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:48:54 INFO - PROCESS | 1745 | RSS: Main: 156332032 14:48:54 INFO - PROCESS | 1745 | 14:48:56 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:48:56 INFO - PROCESS | 1745 | RSS: Main: 156577792 14:48:56 INFO - PROCESS | 1745 | 14:48:58 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:48:59 INFO - PROCESS | 1745 | RSS: Main: 156975104 14:48:59 INFO - PROCESS | 1745 | 14:49:01 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:49:02 INFO - PROCESS | 1745 | RSS: Main: 156610560 14:49:02 INFO - PROCESS | 1745 | 14:49:05 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:49:05 INFO - PROCESS | 1745 | RSS: Main: 156938240 14:49:05 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50922/tests/kraken/driver.html?testName=ai-astar) 14:49:07 INFO - PROCESS | 1745 | RSS: Main: 158556160 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | __start_tp_report 14:49:07 INFO - PROCESS | 1745 | _x_x_mozilla_page_load 14:49:07 INFO - PROCESS | 1745 | _x_x_mozilla_page_load_details 14:49:07 INFO - PROCESS | 1745 | |i|pagename|runs| 14:49:07 INFO - PROCESS | 1745 | |0;ai-astar;103;112;103;109;114;111;112;111;110;110 14:49:07 INFO - PROCESS | 1745 | |1;audio-beat-detection;147;116;144;171;117;147;146;121;176;121 14:49:07 INFO - PROCESS | 1745 | |2;audio-dft;163;162;162;164;162;163;162;161;162;162 14:49:07 INFO - PROCESS | 1745 | |3;audio-fft;75;76;75;180;78;74;75;76;170;75 14:49:07 INFO - PROCESS | 1745 | |4;audio-oscillator;84;84;143;85;84;84;145;83;84;143 14:49:07 INFO - PROCESS | 1745 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;118;118;117 14:49:07 INFO - PROCESS | 1745 | |6;imaging-darkroom;146;147;147;148;146;146;145;146;149;146 14:49:07 INFO - PROCESS | 1745 | |7;imaging-desaturate;95;95;96;95;96;95;95;95;96;94 14:49:07 INFO - PROCESS | 1745 | |8;json-parse-financial;56;54;53;52;53;55;56;53;57;55 14:49:07 INFO - PROCESS | 1745 | |9;json-stringify-tinderbox;60;59;59;60;60;59;59;59;64;60 14:49:07 INFO - PROCESS | 1745 | |10;stanford-crypto-aes;78;76;77;77;77;73;78;72;74;82 14:49:07 INFO - PROCESS | 1745 | |11;stanford-crypto-ccm;119;116;131;117;116;120;132;132;151;131 14:49:07 INFO - PROCESS | 1745 | |12;stanford-crypto-pbkdf2;158;153;157;149;152;151;151;152;155;150 14:49:07 INFO - PROCESS | 1745 | |13;stanford-crypto-sha256-iterative;69;66;62;62;61;61;61;61;60;61 14:49:07 INFO - PROCESS | 1745 | __end_tp_report 14:49:07 INFO - PROCESS | 1745 | __start_cc_report 14:49:07 INFO - PROCESS | 1745 | _x_x_mozilla_cycle_collect,849 14:49:07 INFO - PROCESS | 1745 | __end_cc_report 14:49:07 INFO - PROCESS | 1745 | __startTimestamp1474408147732__endTimestamp 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | ------- Summary: start ------- 14:49:07 INFO - PROCESS | 1745 | Number of tests: 14 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.69 (3.3%) stddev-sans-first:3.07 14:49:07 INFO - PROCESS | 1745 | Values: 103.0 112.0 103.0 109.0 114.0 111.0 112.0 111.0 110.0 110.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.50 stddev:21.68 (14.8%) stddev-sans-first:22.87 14:49:07 INFO - PROCESS | 1745 | Values: 147.0 116.0 144.0 171.0 117.0 147.0 146.0 121.0 176.0 121.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 14:49:07 INFO - PROCESS | 1745 | Values: 163.0 162.0 162.0 164.0 162.0 163.0 162.0 161.0 162.0 162.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#3] audio-fft Cycles:10 Average:95.40 Median:75.00 stddev:42.03 (56.0%) stddev-sans-first:43.93 14:49:07 INFO - PROCESS | 1745 | Values: 75.0 76.0 75.0 180.0 78.0 74.0 75.0 76.0 170.0 75.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:84.00 stddev:28.83 (34.3%) stddev-sans-first:29.84 14:49:07 INFO - PROCESS | 1745 | Values: 84.0 84.0 143.0 85.0 84.0 84.0 145.0 83.0 84.0 143.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 14:49:07 INFO - PROCESS | 1745 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 118.0 118.0 117.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:146.50 stddev:1.17 (0.8%) stddev-sans-first:1.22 14:49:07 INFO - PROCESS | 1745 | Values: 146.0 147.0 147.0 148.0 146.0 146.0 145.0 146.0 149.0 146.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:49:07 INFO - PROCESS | 1745 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 96.0 94.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.65 (3.0%) stddev-sans-first:1.64 14:49:07 INFO - PROCESS | 1745 | Values: 56.0 54.0 53.0 52.0 53.0 55.0 56.0 53.0 57.0 55.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:1.52 (2.5%) stddev-sans-first:1.62 14:49:07 INFO - PROCESS | 1745 | Values: 60.0 59.0 59.0 60.0 60.0 59.0 59.0 59.0 64.0 60.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:77.00 stddev:2.88 (3.7%) stddev-sans-first:2.99 14:49:07 INFO - PROCESS | 1745 | Values: 78.0 76.0 77.0 77.0 77.0 73.0 78.0 72.0 74.0 82.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:131.00 stddev:11.11 (8.5%) stddev-sans-first:11.45 14:49:07 INFO - PROCESS | 1745 | Values: 119.0 116.0 131.0 117.0 116.0 120.0 132.0 132.0 151.0 131.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:152.50 stddev:2.97 (2.0%) stddev-sans-first:2.49 14:49:07 INFO - PROCESS | 1745 | Values: 158.0 153.0 157.0 149.0 152.0 151.0 151.0 152.0 155.0 150.0 14:49:07 INFO - PROCESS | 1745 | 14:49:07 INFO - PROCESS | 1745 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:61.50 stddev:2.84 (4.6%) stddev-sans-first:1.73 14:49:07 INFO - PROCESS | 1745 | Values: 69.0 66.0 62.0 62.0 61.0 61.0 61.0 61.0 60.0 61.0 14:49:07 INFO - PROCESS | 1745 | -------- Summary: end -------- 14:49:07 INFO - PROCESS | 1745 | 14:49:08 INFO - TEST-INFO | 1745: exit 0 14:49:08 INFO - TEST-OK | kraken | took 60528ms 14:49:08 INFO - SUITE-END | took 1171s 14:49:08 INFO - Completed test suite (00:19:31) 14:49:08 INFO - javascript benchmark 14:49:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7758.350783056567, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.291297371065, 3314.4199765041126, 3322.0698204502733, 3341.7493687973406, 3350.329934013198, 5816.098504731768, 5922.970385148068, 5935.436133567315, 5938.287405511339, 5942.138389933457, 5837.299524057108, 5886.793962211324, 5896.292444906607, 5903.114532820079, 5923.259592550931, 5310.362756469229, 5345.7594408251525, 5371.973140134294, 5413.160960051178, 5435.130379139338, 5150.484951504838, 5178.8705282368055, 5195.636305458619, 5242.0, 5262.500062494076, 5303.23103150043, 5325.707086110262, 5329.467053294678, 5344.091504444254, 5417.26866872973, 5392.838214853541, 5409.24270602116, 5411.21537377082, 5413.864653383657, 5432.239486471897, 5041.369828771403, 5123.718195499246, 5154.252633368141, 5301.6818990860675, 5309.177077552986, 5667.093265077568, 5713.742881570298, 5732.942670573324, 5767.711614419304, 5769.971150144264, 6185.288691800411, 6231.906521402183, 6235.594686345373, 6240.968795156041, 6241.282252540925, 6049.576529642927, 6179.0, 6203.348648391899, 6247.437730604267, 6264.812055638338, 5822.82531524055, 5851.912221316684, 5852.912206316909, 5894.557908156906, 5918.260217472816, 6369.904451433232, 6376.29860715321, 6384.48924086072, 6409.198850143732, 6413.358664133642, 5896.64620122794, 6058.182145410313, 6088.452039316483, 6111.419415155553, 6188.1027251048345, 6196.907046394217, 6211.9689401552705, 6231.3145553989025, 6235.781747638811, 6245.406686364788, 6174.44430001302, 6177.907331390033, 6183.4125758053815, 6186.845328866814, 6207.037909124093, 5946.583739138218, 6118.632882027093, 6135.754569817246, 6221.4089661482085, 6224.221972253468, 4705.670603057822, 4712.0, 4714.316424118552, 4736.313234580966, 4744.881377965578, 4199.118185181085, 4199.769012704331, 4202.558731333166, 4220.15596880619, 4231.344141658048, 5772.826815195551, 5829.533637309005, 5855.59010869235, 5930.703464826742, 6009.06859436788, 6174.969125154345, 6208.627482350983, 6229.345918678564, 6229.470495007975, 6235.719392627343, 5918.704064796743, 5932.822015339547, 5939.881202375928, 5941.910871336933, 5967.194428752063, 5167.147420675622, 5333.173358129496, 5346.518813306821, 5351.759170837322, 5366.758495867695, 6449.484041276686, 6478.02829575558, 6485.61086334812, 6487.0, 6495.1556297681, 6300.810975670829, 6302.086197501429, 6303.432691057827, 6305.148804911461, 6306.432421082125, 6060.515158787198, 6268.467180289544, 6278.0269058296035, 6469.838254043687, 6475.805725828232, 6126.0416614718015, 6134.84055711318, 6136.978641397139, 6141.025769127022, 6150.200473938538, 6181.5363847710705, 6195.4424101831055, 6248.875022499434, 6251.624902505864, 6256.968715156395, 5879.764809407747, 6082.878342433038, 6085.786997455068, 6085.817425477243, 6098.054801505774, 6146.754129834935, 6153.261608606996, 6165.691715414122, 6166.81499555014, 6169.1054797053275, 6036.486898613492, 6056.454919057306, 6076.90884636722, 6081.118237855575, 6151.446369826737, 5885.323187833454, 6125.815438131457, 6133.785317514044, 6162.722677479434, 6208.161898143693, 6059.091136329585, 6063.0, 6081.6351018939, 6082.482988945812, 6095.481884039907], "value": 5737.867943038069, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.8751118509522, 636.096742625472, 655.1450357283744, 670.2928410526895, 703.4302215205687, 40741.18517629646, 44615.107697846026, 44974.55025449749, 45095.32357014647, 45101.0, 46175.30737038947, 46394.536054639444, 46786.76606616962, 46803.53196468034, 46811.76594117033, 9164.450132992008, 9282.303827212952, 9300.8604870927, 9302.255819534455, 9318.813623727556, 9059.365844390895, 9144.542772861363, 9175.0, 9176.632934682604, 9203.4477931324, 9217.631294748202, 9265.444073355586, 9272.582733776997, 9278.907210927906, 9281.767955801091, 9111.726648200565, 9152.4966126863, 9165.679201227926, 9175.357724959256, 9175.58709858058, 9162.404443711137, 9190.540472976325, 9212.861807072899, 9220.44677319363, 9231.861522077174, 43927.560724392664, 44226.33660495095, 44228.77885610583, 44351.77824110859, 44402.333964990554, 3607.4228123500375, 3749.7187710921517, 3781.6029316921604, 3869.28418242626, 3911.0, 31331.843340783376, 31533.684663153534, 34583.19068387403, 35356.292874142375, 35540.64459355425, 4683.718976861399, 4702.153612349775, 4721.976390118062, 4752.287156926454, 4790.137775200463, 359934.0, 362354.0, 363506.0, 363779.0, 364987.9999999973, 59718.40281597128, 60176.39823601796, 60200.69899650474, 60337.0, 60366.0, 39639.40540891889, 40726.18547629031, 40750.388744168864, 42466.78766606147, 65028.67485662542, 15918.204089795463, 15918.840811591968, 16140.112293823953, 16152.111633860015, 16163.43427980015, 12111.515539378326, 12123.878761212274, 12141.332226727438, 12198.57304994321, 12214.26714397139, 20106.0, 20168.19327226924, 20319.593608127754, 20403.38789836307, 20488.282910098074, 7365.52124111931, 7379.409647228209, 7665.540067595851, 7675.848622706639, 7695.769126926201, 977.6187286958092, 1052.5105825791045, 1087.9619213327653, 1151.3840095548842, 1160.257435241446, 31714.682853171173, 32530.349393012006, 32633.22149491251, 32757.0, 33577.83211083978, 1127.875933647298, 1141.1213365708359, 1147.6270212181075, 1202.8316035755001, 1203.3682316783759, 17306.913465432594, 17347.566310842078, 17389.91305043492, 17402.21690023952, 17593.648127037388, 482991.0, 483121.0, 484910.0, 486331.0, 488681.0, 59982.400175998555, 60650.39349606536, 60736.0, 60736.0, 60776.08835867466, 1418.716256748675, 1419.8012278281049, 1420.1762977473247, 1438.4893362856244, 1444.6532832120215, 30668.846655766578, 31636.525452118698, 32079.518807218377, 32325.838370807996, 32900.177495561846, 52546.211806823696, 52955.0, 52968.73515632397, 53047.46952530425, 53116.734416329215, 400528.0, 401794.0, 401933.0, 402418.0, 404009.0, 28593.42813143767, 29343.0, 29773.255668608457, 29820.0, 29928.102156935325, 49122.263166051824, 49546.50453495419, 49786.75106624444, 50029.49970500394, 50572.24141637952, 42681.0, 44124.0, 44129.33805992977, 44218.557814421445, 45081.0, 42154.789226053676, 42596.148077037666, 42861.0, 44192.11615767602, 44417.0, 41751.37372939469, 42202.788986054875, 42328.365074524525, 42784.14431711411, 42954.78522607367, 37261.0, 38032.42951355787, 38371.424428633036, 38893.41659875161, 38954.02614934649, 28884.27789305284, 29163.125106246847, 29308.560371594016, 29370.559441607966, 29392.0], "value": 20580.463220601963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.82922258040327, 412.6966679490576, 423.1811444854207, 425.21547744411566, 429.0796242060779, 19729.11218995145, 20533.897330513362, 21321.786782132192, 21770.238041668545, 22124.668129978065, 20119.497012574655, 20344.084516196806, 20418.795812041913, 20571.897140514277, 21072.68390974133, 5793.275840519935, 6056.545759068065, 6071.058985857199, 6089.330173680892, 6099.878002439948, 5822.767089316422, 5909.349971503131, 5910.852228694295, 5930.911036334458, 5961.552883533731, 5902.409759024106, 5924.763009479616, 5932.406759324076, 5934.970325148368, 5944.959632064393, 5753.769849206027, 5890.499307558863, 5923.614965027259, 5931.822045338647, 5981.431763982415, 5359.705216213107, 5376.596755243372, 5390.811321603725, 5392.865178370572, 5403.189521571757, 21329.893350533304, 21358.359249222394, 24792.132275370277, 24884.3778905527, 25001.0, 2503.8998440062373, 2571.472848066142, 2603.06289735695, 2651.0721247563392, 2697.892084316625, 15445.150516721576, 15661.451849185223, 15768.76346854798, 15822.76265856013, 15924.76112858308, 2043.795620437959, 2152.612529744645, 2156.8382371322214, 2176.249194028058, 2198.967015494769, 85175.0, 86486.70269945957, 86553.56723216343, 87694.56152719195, 88012.5599371999, 10650.627228046982, 11150.609728659458, 11256.606018789304, 11299.740051989635, 11342.20604557673, 15855.969361991434, 15858.920705396398, 15981.520554383387, 15998.76001859973, 16007.759883601755, 8185.427020108655, 8281.29608983231, 8363.414560980673, 8431.831363372821, 8464.619092140869, 7980.3615710743, 8009.399295052895, 8036.236557527026, 8136.552489613128, 8141.674333026731, 6762.594244345355, 6968.163820341591, 6971.337722916315, 7075.540089894141, 7082.468814838805, 2035.6641154209685, 2037.6535988881926, 2080.0847627044054, 2086.2698055680694, 2087.048609550045, 963.8795150606175, 1015.898410158993, 1067.7971185474735, 1072.996748040569, 1133.4582840865794, 20724.481887952923, 20794.896025519774, 20960.266390676254, 21029.579408412053, 21144.894275528524, 1027.8920713324994, 1069.9625513107003, 1072.6889202131294, 1073.1522097542854, 1083.4041277297456, 11650.41747912601, 11689.930333139704, 11841.467133978991, 11905.642830715093, 12063.0, 529713.0, 543709.0, 546157.0, 557374.0, 559605.0, 81686.0, 85730.57134714286, 87947.0, 88049.0, 88184.11815881888, 1001.2628090263532, 1005.8742657167854, 1022.0, 1026.825439675242, 1033.9689809305733, 18043.188056537223, 18206.635867282846, 18613.534661633566, 18640.34758783436, 18683.065846707337, 42268.788656056524, 42309.57690423056, 42721.14557708891, 43029.35455968226, 43661.34507982447, 54215.45784542104, 54486.45513544972, 55980.0, 57419.1387129185, 57639.711801440724, 9113.589888454902, 9125.726228213165, 9160.450372977642, 9192.49441280736, 9283.48940808262, 13785.241811700453, 13915.773476243752, 14035.929820350833, 14299.428022878968, 14420.423183072979, 12022.75954480923, 12275.69310767238, 12337.93831030875, 12349.629511114681, 12791.296478693761, 11939.641810745692, 12136.635900922633, 12328.32194229326, 12471.688207794516, 12613.684657883627, 12133.332666703063, 12156.513739450676, 12376.62870113898, 12400.19398739079, 12885.742285154432, 9054.909450905407, 9212.355135140477, 9221.354505184572, 9250.306227032865, 9402.764930876783, 12392.62822115338, 12420.875791241971, 12433.191842530206, 12452.190607610477, 12535.561255356384], "value": 9867.027355129869, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.7451170556863, 1555.463365139027, 1703.3527259641335, 1804.8104948980356, 1838.8528917686585, 8431.873521897176, 9097.090290970908, 9209.0, 9761.121499065091, 9838.212942964554, 9206.815863682723, 9566.28252881035, 9610.759731006729, 9615.182709469686, 9626.229901607872, 2485.0929410765075, 2536.1757428835704, 2537.949241015188, 2540.4538024324766, 2543.605741110131, 2503.0613519930025, 2590.5984572391217, 2594.1439325022793, 2595.065776320523, 2595.208461419276, 2513.4973005398992, 2529.8229123961332, 2549.6430499730054, 2561.743825617433, 2563.1541591274836, 2486.353548077495, 2487.614419764939, 2487.8631675257852, 2494.5010997800423, 2509.535735888848, 2448.0574978633176, 2470.3947532854568, 2472.5673007223663, 2493.2644869763462, 2535.1760677779794, 9662.468564228964, 9685.854712179253, 9701.563429645683, 9729.513524323826, 9765.121139097515, 5568.49883510482, 5730.512906402961, 5745.54035677149, 5750.05124154514, 5752.654840709529, 3524.4184709522897, 3549.07723991764, 3644.6173151818944, 3672.8530858765616, 3738.4953031340965, 1177.3995262416145, 1218.2507757729022, 1222.1750318534953, 2289.587874182663, 2385.3678775124604, 12861.87138128616, 12909.483620655257, 13004.804927926089, 13057.34713264333, 13076.803847942288, 4799.928001079917, 4826.32431459596, 4883.536064073978, 4901.313816065754, 4999.75001249936, 10681.679549613524, 10733.248672592843, 10744.247902646895, 10779.78440431187, 10786.514606842555, 5598.804041858515, 5610.523105535994, 5617.662940223599, 5622.7750889963945, 5628.774849006076, 4929.186684197068, 5101.923471147936, 5106.872328191825, 5183.818566350235, 5219.373675159005, 4185.414042034117, 4212.241796476603, 4236.6610671146855, 4270.252705776476, 4397.0, 1257.88050135237, 1264.538443468146, 1267.8922291605318, 1268.6067319131098, 1269.9047571431995, 976.9658061967939, 1075.5160177919968, 1080.9243352965375, 1093.830456279278, 1094.0536435982817, 6543.1821022372205, 6561.179852518435, 6757.493188010919, 6860.759873404507, 6882.03651488792, 484.6050468867861, 493.1985523524273, 497.477648469106, 500.63703814734026, 504.50810459802136, 4407.206702793464, 4556.248219043887, 4571.0, 4702.929456058162, 4889.926651100236, 12082.637520874388, 12199.963003144832, 12202.206856554296, 12210.816837747441, 12411.937940310421, 11343.20597558163, 11819.290842549475, 11843.703907402383, 12154.392280385946, 12212.877871221173, 9015.368924175376, 9212.401193922507, 9222.170004699608, 9237.861432078524, 9251.67619133327, 6466.385693359123, 6509.349065093453, 6665.300143484864, 6804.285550017177, 6813.591184528944, 11074.169437292074, 11118.777624447628, 11208.439578021229, 11266.436678165896, 11502.079833613123, 11277.210595258253, 11532.192746507664, 11622.0702343814, 11672.533098675958, 11735.530578777094, 8399.202075802788, 8547.61535730906, 8635.6977505787, 8688.609012594323, 8791.296696264411, 9550.235981121632, 9645.565949532147, 9662.13040826329, 9669.564869581027, 9670.323077384797, 8293.460925039853, 8324.875126872981, 8394.958025209835, 8398.664053437795, 8506.532140732072, 8330.291925186428, 8343.499390036617, 8347.749567512985, 8362.414630975773, 8495.702650407206, 7892.763217103496, 7979.601019949096, 8014.919850801417, 8025.9197408025175, 8045.316148127475, 5520.282363292747, 5713.714314284352, 5728.799492017926, 5848.181254624271, 5956.761729530944, 5680.6591604503865, 5769.826905192851, 5784.566157538286, 5861.267341582302, 5880.500157486662], "value": 5011.832300715842, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.48889110789355, 353.8398079723291, 355.2114306240146, 359.14165145302724, 359.9010272175153, 5855.5022823060035, 5899.941000590004, 5925.140854576084, 5936.5250779937605, 5948.7323070461825, 5788.292453726151, 5833.82498525045, 5870.417016512732, 5886.705664716757, 5939.376365481617, 4557.840475583355, 4564.771761411933, 4578.397560853708, 4614.892425817797, 4620.237660785966, 4564.97717511412, 4571.131485017836, 4576.290674945389, 4580.633549316063, 4587.518310577391, 4549.426079315391, 4560.3843481130125, 4589.219832628444, 4598.9422432840465, 4606.424196975378, 4456.48750393704, 4526.592606665399, 4552.795124219385, 4557.726536407826, 4563.680542362019, 4049.311617025113, 4068.616670332072, 4073.450084238649, 4077.8368865245357, 4078.6940979426663, 5692.345380281238, 5760.251167348218, 5787.363390027093, 5795.942040579583, 5801.419858014207, 5140.917928239069, 5185.429602743695, 5190.0, 5198.870028249286, 5203.372296990034, 6044.365341639153, 6080.726367313438, 6114.510839132859, 6178.691065446755, 6183.505319574469, 4391.802368893407, 4454.2873140297725, 4460.241758900962, 4465.1739428205565, 4494.056248187884, 5946.226990491252, 6119.785807496716, 6299.8740025199695, 6323.9051414228825, 6344.206974128234, 4496.21316269658, 4504.797284122223, 4533.637309015271, 4535.455745310518, 4537.708457229078, 4579.107074120581, 4587.128445595326, 4588.931166032512, 4609.285560738091, 4630.768461576908, 5125.615578831603, 5196.58427325813, 5221.7128057956415, 5236.633435659467, 5237.5548078413, 6096.329403765582, 6099.329073801879, 6107.358727333655, 6130.877382452415, 6135.9079613805825, 1568.4902406717863, 1589.9841001590066, 1593.2670971353307, 1594.7209238383236, 1594.7687585300298, 1004.4023805835662, 1019.0993796569345, 1029.562435964712, 1046.2571574182416, 1073.2648136026846, 5861.296644402699, 5871.765129394862, 5892.234009578728, 5902.58681892272, 5926.525877929756, 6179.752809887644, 6190.690465476709, 6225.128482012522, 6244.37556244381, 6267.6866156691985, 5678.233438485751, 5817.301923769175, 5817.534597232211, 5875.500582450454, 5876.41235876409, 3287.7041066304146, 3290.3748287825715, 3321.2527181383994, 3329.8002119872885, 3360.19355354718, 6250.656213908188, 6466.935330646634, 6495.805125846232, 6563.671816409256, 6633.2703402625675, 6357.618542887441, 6388.456981156561, 6428.71070801815, 6462.903056454157, 6466.256380516207, 6357.982722764335, 6490.513211509156, 6589.308122647149, 6589.439897608757, 6596.317938925543, 6257.906131408124, 6331.145295385064, 6339.1125242467015, 6354.714037868214, 6358.936410635834, 6116.877662446637, 6255.749770009149, 6260.906086408799, 6284.4344009039405, 6310.936890631035, 6465.288818229897, 6518.250401203923, 6523.282438931618, 6526.836829079311, 6532.7336331834085, 4500.347449619852, 4536.750478723702, 4548.704334218398, 4562.749048802348, 4564.589186973189, 6204.565680402328, 6333.714982825692, 6372.426481616677, 6410.8076757697345, 6428.871422571428, 6140.355262697352, 6161.874153417064, 6178.62928224308, 6211.81364559064, 6258.780942666985, 5815.214945982408, 6152.7538898445355, 6220.222472190976, 6244.594101383395, 6281.299041412237, 6076.726547305293, 6150.723217455135, 6172.537059720628, 6178.289496707756, 6220.688965551795, 6253.8749225014335, 6264.874702506016, 6300.936990630035, 6305.4009869062365, 6324.0, 5921.3190483094995, 6046.697665116814, 6077.453029227391, 6079.604825686316, 6123.775244950939], "value": 4697.065826738006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.5248971882294, 788.656934233608, 817.1950628630799, 824.4393812207696, 889.7286327670062, 15602.453914112994, 16641.50075497734, 16696.49910502687, 16721.581960450996, 16722.16389180539, 15708.057516549015, 15784.605384865385, 15819.920900395511, 15831.812614053935, 15845.762313565307, 723.3200791256203, 840.1976112812249, 851.1318455175717, 854.4488804720951, 863.1756672377865, 871.1419252036738, 871.716692075075, 871.9825603487926, 872.1583671756754, 873.244643383472, 913.899471058183, 914.2914241462886, 915.7069737683944, 916.1022198245723, 917.3440989692368, 900.1628485508475, 903.9096090390974, 904.8552231642972, 905.3798148268457, 908.5593487158723, 675.2976904019863, 676.4791110844623, 678.0575000748963, 678.5385937562455, 679.7043286170532, 15539.766903496344, 15644.217789110613, 15650.921745391313, 15671.294791734399, 15875.841241587552, 14167.929160354131, 14238.857611423857, 14717.411303547846, 16320.510384688478, 17131.57171070721, 13547.45810167592, 13595.0, 14147.929260353736, 14314.42742290307, 14666.0, 4731.574158325733, 4778.331033655291, 4907.779149938261, 4948.505149485059, 4951.455339912607, 84870.0, 86540.56729716374, 86978.5651071747, 88889.55555222247, 89100.55449722774, 11847.466863991142, 12493.375331233401, 12587.748245035047, 12599.93700031494, 12627.936860315824, 22458.213962511232, 22590.0, 22681.092756289894, 22707.09171633149, 22762.544749104924, 8520.829583408422, 8641.870371944426, 8646.740597782076, 8721.694740684046, 9032.458052516738, 4872.171730805771, 4961.330220420197, 5038.974805125951, 5049.267856160836, 5060.240963855377, 3610.9638903611153, 3681.153334732973, 3734.589195188527, 3747.1194269346684, 3754.8310326035394, 625.5308518611042, 629.1506466270498, 629.9811005669837, 640.2285246278224, 652.6051738698114, 705.5978092487336, 711.2460791560962, 731.5464412064554, 739.0946091038543, 742.5470463017555, 8927.78599704106, 9106.817863642822, 9469.715908522756, 9647.855282170773, 9824.705258842247, 621.2451870976785, 705.5272967112047, 710.9289071092953, 716.5843810589845, 737.7270409948246, 4273.401723758676, 4441.71128876622, 4491.977540112344, 4495.235809912323, 4521.389612402284, 98028.50985745026, 130125.0, 130166.34916825355, 133862.33068834594, 134557.32721336526, 92275.53862230646, 104604.0, 106879.0, 107400.46299768452, 109694.0, 55514.0, 83930.58034709787, 84021.57989210138, 87532.56233718919, 94087.0, 8650.56747162652, 8887.600057997535, 8967.865482017905, 9388.202002829836, 9474.715758527254, 53210.2018469731, 54391.728041359536, 55059.17411238915, 55099.72450137884, 55728.72135639296, 76828.0, 81016.18983810087, 82960.58519707363, 85110.0, 85963.57018214869, 13237.867621324049, 13251.66870828237, 13458.192508449525, 13494.0, 13656.93171534136, 14017.158970461805, 14169.0, 14468.131912085308, 14508.709825803637, 14807.629809254855, 14030.649233769238, 14119.0, 14694.779578306549, 14703.411863525767, 14753.483628072965, 13662.31688415595, 13773.862261377659, 13985.440582376998, 14883.553493395215, 14930.85069149338, 13827.446902123802, 13854.955776781962, 13940.233287169303, 14719.0, 15021.098734075991, 11688.240264383128, 11846.407679615812, 11955.342456164646, 11971.461284242405, 12415.813762793747, 10945.781084378108, 11499.310041397543, 11538.653840384803, 11889.346085965355, 11933.582324618597], "value": 7949.916964498713, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 103.0, 109.0, 114.0, 111.0, 112.0, 111.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 144.0, 171.0, 117.0, 147.0, 146.0, 121.0, 176.0, 121.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 164.0, 162.0, 163.0, 162.0, 161.0, 162.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 180.0, 78.0, 74.0, 75.0, 76.0, 170.0, 75.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 143.0, 85.0, 84.0, 84.0, 145.0, 83.0, 84.0, 143.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 148.0, 146.0, 146.0, 145.0, 146.0, 149.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 52.0, 53.0, 55.0, 56.0, 53.0, 57.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 64.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 77.0, 77.0, 73.0, 78.0, 72.0, 74.0, 82.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 131.0, 117.0, 116.0, 120.0, 132.0, 132.0, 151.0, 131.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 153.0, 157.0, 149.0, 152.0, 151.0, 151.0, 152.0, 155.0, 150.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0], "value": 62.4, "unit": "score"}]}]} 14:49:08 INFO - Return code: 0 14:49:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:49:09 ERROR - # TBPL SUCCESS # 14:49:09 INFO - Running post-action listener: _resource_record_post_action 14:49:09 INFO - [mozharness: 2016-09-20 21:49:09.042310Z] Finished run-tests step (success) 14:49:09 INFO - Running post-run listener: _resource_record_post_run 14:49:09 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 477782016; Read time: 16; Write time: 412148 14:49:09 INFO - TinderboxPrint: CPU usage
13.2% 14:49:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 14:49:09 INFO - TinderboxPrint: I/O write bytes / time
477,782,016 / 412,148 14:49:09 INFO - TinderboxPrint: CPU idle
8,211.7 (86.8%) 14:49:09 INFO - TinderboxPrint: CPU user
1,192.3 (12.6%) 14:49:09 INFO - TinderboxPrint: Swap in / out
0 / 0 14:49:09 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 251498496; Read time: 0; Write time: 291864 14:49:09 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 226226176; Read time: 16; Write time: 120284 14:49:09 INFO - Running post-run listener: _upload_blobber_files 14:49:09 INFO - Blob upload gear active. 14:49:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:49:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:49:10 INFO - (blobuploader) - INFO - Open directory for files ... 14:49:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:49:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:49:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:10 INFO - (blobuploader) - INFO - Done attempting. 14:49:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:49:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:49:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:15 INFO - (blobuploader) - INFO - Done attempting. 14:49:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:49:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:49:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:15 INFO - (blobuploader) - INFO - Done attempting. 14:49:15 INFO - (blobuploader) - INFO - Iteration through files over. 14:49:15 INFO - Return code: 0 14:49:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:49:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:49:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88cad36b7653c533dede61e6f983e0204226b52103fcb8d37d6adcc04fc49d8130e34419c6b9eeb3053327c4ce1d180edd2ffc8e0334387861acb7b5d08c291f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/815d082bb67aa1562092ad7935331f764fb8e5ec9491c289baa7b54b3facb3cbacda2c474d150df282c534c03e52e70110b770977c3d99882fe50fbb4f465f90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f98fb17bca09c5b13d8267d32abd0f9c905cccce0f7a8376d708cb9c7b628be895fff449510c8e790591e92e6085c2258d6db7563a6cd6bedf87870ad3cff75"} 14:49:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:49:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:49:15 INFO - Contents: 14:49:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88cad36b7653c533dede61e6f983e0204226b52103fcb8d37d6adcc04fc49d8130e34419c6b9eeb3053327c4ce1d180edd2ffc8e0334387861acb7b5d08c291f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/815d082bb67aa1562092ad7935331f764fb8e5ec9491c289baa7b54b3facb3cbacda2c474d150df282c534c03e52e70110b770977c3d99882fe50fbb4f465f90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f98fb17bca09c5b13d8267d32abd0f9c905cccce0f7a8376d708cb9c7b628be895fff449510c8e790591e92e6085c2258d6db7563a6cd6bedf87870ad3cff75"} 14:49:15 INFO - Running post-run listener: copy_logs_to_upload_dir 14:49:15 INFO - Copying logs to upload dir... 14:49:15 INFO - mkdir: /builds/slave/test/build/upload/logs 14:49:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1219.339315 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 19 secs) (at 2016-09-20 14:49:15.691603) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:49:15.695365) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726 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-Beta/sha512/88cad36b7653c533dede61e6f983e0204226b52103fcb8d37d6adcc04fc49d8130e34419c6b9eeb3053327c4ce1d180edd2ffc8e0334387861acb7b5d08c291f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/815d082bb67aa1562092ad7935331f764fb8e5ec9491c289baa7b54b3facb3cbacda2c474d150df282c534c03e52e70110b770977c3d99882fe50fbb4f465f90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f98fb17bca09c5b13d8267d32abd0f9c905cccce0f7a8376d708cb9c7b628be895fff449510c8e790591e92e6085c2258d6db7563a6cd6bedf87870ad3cff75"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88cad36b7653c533dede61e6f983e0204226b52103fcb8d37d6adcc04fc49d8130e34419c6b9eeb3053327c4ce1d180edd2ffc8e0334387861acb7b5d08c291f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/815d082bb67aa1562092ad7935331f764fb8e5ec9491c289baa7b54b3facb3cbacda2c474d150df282c534c03e52e70110b770977c3d99882fe50fbb4f465f90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f98fb17bca09c5b13d8267d32abd0f9c905cccce0f7a8376d708cb9c7b628be895fff449510c8e790591e92e6085c2258d6db7563a6cd6bedf87870ad3cff75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:49:15.722732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:49:15.723394) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1474406931.566225-1373497726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:49:15.810158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:49:15.810826) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:49:15.811720) ========= ========= Total master_lag: 0.69 =========