builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1474406856.34 results: success (0) buildid: 20160920142043 builduid: 29f27e93c73b46cfbab093695de715c1 revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.338279) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.338837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.339192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.354282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.354586) ========= 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=7de08804244eb36560b44971000001c3-1474406854.980459-2058220414 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 14:27:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 136M=0s 2016-09-20 14:27:36 (136 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.544634) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.545223) ========= 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=7de08804244eb36560b44971000001c3-1474406854.980459-2058220414 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015234 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.598887) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:36.599716) ========= 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=7de08804244eb36560b44971000001c3-1474406854.980459-2058220414 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 14:27:36,908 truncating revision to first 12 chars 2016-09-20 14:27:36,908 Setting DEBUG logging. 2016-09-20 14:27:36,909 attempt 1/10 2016-09-20 14:27:36,909 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:27:37,227 unpacking tar archive at: mozilla-beta-6be9a5c9c8cd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653400 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:37.269780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:37.270217) ========= script_repo_revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:37.270727) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:37.271283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:27:37.281157) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 20 secs) (at 2016-09-20 14:27:37.281525) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1474406854.980459-2058220414 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:27:37 INFO - MultiFileLogger online at 20160920 14:27:37 in /builds/slave/test 14:27:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:27:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:27:37 INFO - {'append_to_log': False, 14:27:37 INFO - 'base_work_dir': '/builds/slave/test', 14:27:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:27:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:27:37 INFO - 'branch': 'Mozilla-Beta', 14:27:37 INFO - 'buildbot_json_path': 'buildprops.json', 14:27:37 INFO - 'config_files': ('talos/linux_config.py',), 14:27:37 INFO - 'default_actions': ('clobber', 14:27:37 INFO - 'read-buildbot-config', 14:27:37 INFO - 'download-and-extract', 14:27:37 INFO - 'populate-webroot', 14:27:37 INFO - 'create-virtualenv', 14:27:37 INFO - 'install', 14:27:37 INFO - 'run-tests'), 14:27:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:27:37 INFO - 'download_minidump_stackwalk': True, 14:27:37 INFO - 'download_symbols': 'ondemand', 14:27:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:27:37 INFO - 'tooltool.py': '/tools/tooltool.py', 14:27:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:27:37 INFO - '/tools/misc-python/virtualenv.py')}, 14:27:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:27:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:27:37 INFO - 'installer_path': 'installer.exe', 14:27:37 INFO - 'log_level': 'info', 14:27:37 INFO - 'log_name': 'talos', 14:27:37 INFO - 'log_to_console': True, 14:27:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:27:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:27:37 INFO - 'opt_config_files': (), 14:27:37 INFO - 'pip_index': False, 14:27:37 INFO - 'sps_profile': False, 14:27:37 INFO - 'sps_profile_interval': 0, 14:27:37 INFO - 'suite': 'dromaeojs', 14:27:37 INFO - 'system_bits': '32', 14:27:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:27:37 INFO - 'title': 'talos-linux64-ix-003', 14:27:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:27:37 INFO - 'use_talos_json': True, 14:27:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:27:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:27:37 INFO - 'work_dir': 'build'} 14:27:37 INFO - [mozharness: 2016-09-20 21:27:37.681476Z] Running clobber step. 14:27:37 INFO - Running pre-action listener: _resource_record_pre_action 14:27:37 INFO - Running main action method: clobber 14:27:37 INFO - rmtree: /builds/slave/test/build 14:27:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:27:38 INFO - Running post-action listener: _resource_record_post_action 14:27:38 INFO - [mozharness: 2016-09-20 21:27:38.139184Z] Finished clobber step (success) 14:27:38 INFO - [mozharness: 2016-09-20 21:27:38.139284Z] Running read-buildbot-config step. 14:27:38 INFO - Running pre-action listener: _resource_record_pre_action 14:27:38 INFO - Running main action method: read_buildbot_config 14:27:38 INFO - Using buildbot properties: 14:27:38 INFO - { 14:27:38 INFO - "project": "", 14:27:38 INFO - "product": "firefox", 14:27:38 INFO - "script_repo_revision": "production", 14:27:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:27:38 INFO - "repository": "", 14:27:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:27:38 INFO - "buildid": "20160920142043", 14:27:38 INFO - "stage_platform": "linux64-pgo", 14:27:38 INFO - "basedir": "/builds/slave/test", 14:27:38 INFO - "buildnumber": 165, 14:27:38 INFO - "platform": "ubuntu64_hw", 14:27:38 INFO - "mozci_request": { 14:27:38 INFO - "priority": -1, 14:27:38 INFO - "type": "trigger_all_talos_jobs", 14:27:38 INFO - "times": 6 14:27:38 INFO - }, 14:27:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:27:38 INFO - "slavebuilddir": "test", 14:27:38 INFO - "branch": "mozilla-beta", 14:27:38 INFO - "revision": "6be9a5c9c8cd25d2082376495b788d85ce3cca98", 14:27:38 INFO - "slavename": "talos-linux64-ix-003", 14:27:38 INFO - "builduid": "29f27e93c73b46cfbab093695de715c1", 14:27:38 INFO - "repo_path": "releases/mozilla-beta" 14:27:38 INFO - } 14:27:38 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:27:38 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:27:38 INFO - Running post-action listener: _resource_record_post_action 14:27:38 INFO - [mozharness: 2016-09-20 21:27:38.142037Z] Finished read-buildbot-config step (success) 14:27:38 INFO - [mozharness: 2016-09-20 21:27:38.142127Z] Running download-and-extract step. 14:27:38 INFO - Running pre-action listener: _resource_record_pre_action 14:27:38 INFO - Running main action method: download_and_extract 14:27:38 INFO - mkdir: /builds/slave/test/build/tests 14:27:38 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:27:38 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:27:38 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:27:38 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:27:38 INFO - Downloaded 1327 bytes. 14:27:38 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:27:38 INFO - Using the following test package requirements: 14:27:38 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:27:38 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:27:38 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 14:27:38 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'jsshell-linux-x86_64.zip'], 14:27:38 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:27:38 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:27:38 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 14:27:38 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 14:27:38 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:27:38 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:27:38 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:27:38 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:27:38 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:27:38 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:27:38 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:27:38 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:27:38 INFO - Downloaded 21769913 bytes. 14:27:38 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:27:38 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:27:39 INFO - Return code: 0 14:27:39 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:27:39 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:27:39 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:27:39 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:27:39 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:27:39 INFO - Downloaded 21769913 bytes. 14:27:39 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:27:39 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:27:41 INFO - Return code: 0 14:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:27:41 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:27:41 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:27:41 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:27:41 INFO - Downloaded 14413740 bytes. 14:27:41 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:27:41 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:27:41 INFO - Return code: 0 14:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:27:41 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:27:41 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:27:41 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:27:42 INFO - Downloaded 56771801 bytes. 14:27:42 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:27:42 INFO - mkdir: /builds/slave/test/properties 14:27:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:27:42 INFO - Writing to file /builds/slave/test/properties/build_url 14:27:42 INFO - Contents: 14:27:42 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:27:42 INFO - Running post-action listener: _resource_record_post_action 14:27:42 INFO - Running post-action listener: set_extra_try_arguments 14:27:42 INFO - [mozharness: 2016-09-20 21:27:42.784560Z] Finished download-and-extract step (success) 14:27:42 INFO - [mozharness: 2016-09-20 21:27:42.784794Z] Running populate-webroot step. 14:27:42 INFO - Running pre-action listener: _resource_record_pre_action 14:27:42 INFO - Running main action method: populate_webroot 14:27:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:27:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:27:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:27:42 INFO - '--tppagecycles', 14:27:42 INFO - '7'], 14:27:42 INFO - 'tests': ['tsvgm']}}, 14:27:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:27:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:27:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:27:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:27:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['damp', 'tps']}, 14:27:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'tests': ['damp', 'tps']}, 14:27:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['dromaeo_dom']}, 14:27:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:27:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['basic_compositor_video']}, 14:27:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:27:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['a11yr', 14:27:42 INFO - 'ts_paint', 14:27:42 INFO - 'tpaint', 14:27:42 INFO - 'sessionrestore', 14:27:42 INFO - 'sessionrestore_no_auto_restore', 14:27:42 INFO - 'tabpaint']}, 14:27:42 INFO - 'other-e10s': {'tests': ['a11yr', 14:27:42 INFO - 'ts_paint', 14:27:42 INFO - 'tpaint', 14:27:42 INFO - 'sessionrestore', 14:27:42 INFO - 'sessionrestore_no_auto_restore', 14:27:42 INFO - 'tabpaint']}, 14:27:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:27:42 INFO - 'ts_paint', 14:27:42 INFO - 'tpaint', 14:27:42 INFO - 'sessionrestore', 14:27:42 INFO - 'sessionrestore_no_auto_restore', 14:27:42 INFO - 'tabpaint']}, 14:27:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:27:42 INFO - 'ts_paint', 14:27:42 INFO - 'tpaint', 14:27:42 INFO - 'sessionrestore', 14:27:42 INFO - 'sessionrestore_no_auto_restore', 14:27:42 INFO - 'tabpaint']}, 14:27:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['a11yr', 14:27:42 INFO - 'ts_paint', 14:27:42 INFO - 'tpaint', 14:27:42 INFO - 'sessionrestore', 14:27:42 INFO - 'sessionrestore_no_auto_restore', 14:27:42 INFO - 'tabpaint']}, 14:27:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['a11yr', 14:27:42 INFO - 'ts_paint', 14:27:42 INFO - 'tpaint', 14:27:42 INFO - 'sessionrestore', 14:27:42 INFO - 'sessionrestore_no_auto_restore', 14:27:42 INFO - 'tabpaint']}, 14:27:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['tsvgx', 14:27:42 INFO - 'tsvgr_opacity', 14:27:42 INFO - 'tart', 14:27:42 INFO - 'tscrollx', 14:27:42 INFO - 'cart']}, 14:27:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:27:42 INFO - 'tsvgr_opacity', 14:27:42 INFO - 'tart', 14:27:42 INFO - 'tscrollx', 14:27:42 INFO - 'cart']}, 14:27:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'talos_options': ['--disable-e10s'], 14:27:42 INFO - 'tests': ['tp5o']}, 14:27:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'tests': ['tp5o']}, 14:27:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'talos_options': ['--disable-e10s', 14:27:42 INFO - '--xperf_path', 14:27:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:42 INFO - 'tests': ['tp5n']}, 14:27:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:27:42 INFO - 'talos_options': ['--xperf_path', 14:27:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:42 INFO - 'tests': ['tp5n']}}, 14:27:42 INFO - 'talos.zip': {'path': '', 14:27:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:27:42 INFO - Running post-action listener: _resource_record_post_action 14:27:42 INFO - [mozharness: 2016-09-20 21:27:42.800860Z] Finished populate-webroot step (success) 14:27:42 INFO - [mozharness: 2016-09-20 21:27:42.800950Z] Running create-virtualenv step. 14:27:42 INFO - Running pre-action listener: _resource_record_pre_action 14:27:42 INFO - Running main action method: create_virtualenv 14:27:42 INFO - Creating virtualenv /builds/slave/test/build/venv 14:27:42 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:27:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:27:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:27:42 INFO - Using real prefix '/usr' 14:27:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:27:43 INFO - Installing distribute.............................................................................................................................................................................................done. 14:27:45 INFO - Installing pip.................done. 14:27:45 INFO - Return code: 0 14:27:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:27:45 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:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:45 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:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:45 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:45 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:27:45 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:27:45 INFO - Using env: {'DISPLAY': ':0', 14:27:45 INFO - 'HOME': '/home/cltbld', 14:27:45 INFO - 'LANG': 'en_US.UTF-8', 14:27:45 INFO - 'LANGUAGE': 'en_US:en', 14:27:45 INFO - 'LOGNAME': 'cltbld', 14:27:45 INFO - 'MAIL': '/var/mail/cltbld', 14:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:45 INFO - 'MOZ_NO_REMOTE': '1', 14:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:45 INFO - 'NO_EM_RESTART': '1', 14:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:45 INFO - 'PWD': '/builds/slave/test', 14:27:45 INFO - 'SHELL': '/bin/bash', 14:27:45 INFO - 'SHLVL': '1', 14:27:45 INFO - 'TERM': 'linux', 14:27:45 INFO - 'TMOUT': '86400', 14:27:45 INFO - 'USER': 'cltbld', 14:27:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:45 INFO - '_': '/tools/buildbot/bin/python'} 14:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:45 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:27:45 INFO - Cleaning up... 14:27:45 INFO - Return code: 0 14:27:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:27:45 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:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:45 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:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:45 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:45 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:27:45 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:27:45 INFO - Using env: {'DISPLAY': ':0', 14:27:45 INFO - 'HOME': '/home/cltbld', 14:27:45 INFO - 'LANG': 'en_US.UTF-8', 14:27:45 INFO - 'LANGUAGE': 'en_US:en', 14:27:45 INFO - 'LOGNAME': 'cltbld', 14:27:45 INFO - 'MAIL': '/var/mail/cltbld', 14:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:45 INFO - 'MOZ_NO_REMOTE': '1', 14:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:45 INFO - 'NO_EM_RESTART': '1', 14:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:45 INFO - 'PWD': '/builds/slave/test', 14:27:45 INFO - 'SHELL': '/bin/bash', 14:27:45 INFO - 'SHLVL': '1', 14:27:45 INFO - 'TERM': 'linux', 14:27:45 INFO - 'TMOUT': '86400', 14:27:45 INFO - 'USER': 'cltbld', 14:27:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:45 INFO - '_': '/tools/buildbot/bin/python'} 14:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:45 INFO - Downloading/unpacking psutil>=3.1.1 14:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:27:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:47 INFO - Installing collected packages: psutil 14:27:47 INFO - Running setup.py install for psutil 14:27:47 INFO - building 'psutil._psutil_linux' extension 14:27:47 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:27:48 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:27:48 INFO - building 'psutil._psutil_posix' extension 14:27:48 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:27:48 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:27:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:48 INFO - Successfully installed psutil 14:27:48 INFO - Cleaning up... 14:27:48 INFO - Return code: 0 14:27:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:27:48 INFO - Using env: {'DISPLAY': ':0', 14:27:48 INFO - 'HOME': '/home/cltbld', 14:27:48 INFO - 'LANG': 'en_US.UTF-8', 14:27:48 INFO - 'LANGUAGE': 'en_US:en', 14:27:48 INFO - 'LOGNAME': 'cltbld', 14:27:48 INFO - 'MAIL': '/var/mail/cltbld', 14:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:48 INFO - 'MOZ_NO_REMOTE': '1', 14:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:48 INFO - 'NO_EM_RESTART': '1', 14:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:48 INFO - 'PWD': '/builds/slave/test', 14:27:48 INFO - 'SHELL': '/bin/bash', 14:27:48 INFO - 'SHLVL': '1', 14:27:48 INFO - 'TERM': 'linux', 14:27:48 INFO - 'TMOUT': '86400', 14:27:48 INFO - 'USER': 'cltbld', 14:27:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:48 INFO - '_': '/tools/buildbot/bin/python'} 14:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:27:50 INFO - Installing collected packages: mozsystemmonitor 14:27:50 INFO - Running setup.py install for mozsystemmonitor 14:27:50 INFO - Successfully installed mozsystemmonitor 14:27:50 INFO - Cleaning up... 14:27:50 INFO - Return code: 0 14:27:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:27:50 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:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:50 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:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:50 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:50 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:27:50 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:27:50 INFO - Using env: {'DISPLAY': ':0', 14:27:50 INFO - 'HOME': '/home/cltbld', 14:27:50 INFO - 'LANG': 'en_US.UTF-8', 14:27:50 INFO - 'LANGUAGE': 'en_US:en', 14:27:50 INFO - 'LOGNAME': 'cltbld', 14:27:50 INFO - 'MAIL': '/var/mail/cltbld', 14:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:50 INFO - 'MOZ_NO_REMOTE': '1', 14:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:50 INFO - 'NO_EM_RESTART': '1', 14:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:50 INFO - 'PWD': '/builds/slave/test', 14:27:50 INFO - 'SHELL': '/bin/bash', 14:27:50 INFO - 'SHLVL': '1', 14:27:50 INFO - 'TERM': 'linux', 14:27:50 INFO - 'TMOUT': '86400', 14:27:50 INFO - 'USER': 'cltbld', 14:27:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:50 INFO - '_': '/tools/buildbot/bin/python'} 14:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:50 INFO - Downloading/unpacking jsonschema==2.5.1 14:27:50 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:27:50 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:27:50 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:27:50 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:27:50 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:27:50 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:27:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:27:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 INFO - Downloading functools32-3.2.3-2.tar.gz 14:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:27:53 INFO - warning: no files found matching '*.txt' 14:27:53 INFO - no previously-included directories found matching 'build' 14:27:53 INFO - no previously-included directories found matching 'dist' 14:27:53 INFO - no previously-included directories found matching '.git*' 14:27:53 INFO - Installing collected packages: jsonschema, functools32 14:27:53 INFO - Running setup.py install for functools32 14:27:53 INFO - warning: no files found matching '*.txt' 14:27:53 INFO - no previously-included directories found matching 'build' 14:27:53 INFO - no previously-included directories found matching 'dist' 14:27:53 INFO - no previously-included directories found matching '.git*' 14:27:53 INFO - Successfully installed jsonschema functools32 14:27:53 INFO - Cleaning up... 14:27:53 INFO - Return code: 0 14:27:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:27:53 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:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:53 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:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:53 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:53 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:27:53 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:27:53 INFO - Using env: {'DISPLAY': ':0', 14:27:53 INFO - 'HOME': '/home/cltbld', 14:27:53 INFO - 'LANG': 'en_US.UTF-8', 14:27:53 INFO - 'LANGUAGE': 'en_US:en', 14:27:53 INFO - 'LOGNAME': 'cltbld', 14:27:53 INFO - 'MAIL': '/var/mail/cltbld', 14:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:53 INFO - 'MOZ_NO_REMOTE': '1', 14:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:53 INFO - 'NO_EM_RESTART': '1', 14:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:53 INFO - 'PWD': '/builds/slave/test', 14:27:53 INFO - 'SHELL': '/bin/bash', 14:27:53 INFO - 'SHLVL': '1', 14:27:53 INFO - 'TERM': 'linux', 14:27:53 INFO - 'TMOUT': '86400', 14:27:53 INFO - 'USER': 'cltbld', 14:27:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:53 INFO - '_': '/tools/buildbot/bin/python'} 14:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:27:53 INFO - Cleaning up... 14:27:53 INFO - Return code: 0 14:27:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:27:53 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:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:53 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:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:53 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:53 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:27:53 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:27:53 INFO - Using env: {'DISPLAY': ':0', 14:27:53 INFO - 'HOME': '/home/cltbld', 14:27:53 INFO - 'LANG': 'en_US.UTF-8', 14:27:53 INFO - 'LANGUAGE': 'en_US:en', 14:27:53 INFO - 'LOGNAME': 'cltbld', 14:27:53 INFO - 'MAIL': '/var/mail/cltbld', 14:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:53 INFO - 'MOZ_NO_REMOTE': '1', 14:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:53 INFO - 'NO_EM_RESTART': '1', 14:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:53 INFO - 'PWD': '/builds/slave/test', 14:27:53 INFO - 'SHELL': '/bin/bash', 14:27:53 INFO - 'SHLVL': '1', 14:27:53 INFO - 'TERM': 'linux', 14:27:53 INFO - 'TMOUT': '86400', 14:27:53 INFO - 'USER': 'cltbld', 14:27:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:53 INFO - '_': '/tools/buildbot/bin/python'} 14:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:53 INFO - Downloading/unpacking blobuploader==1.2.4 14:27:53 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:27:53 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:27:53 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:27:53 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:27:53 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:27:53 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:27:55 INFO - Downloading blobuploader-1.2.4.tar.gz 14:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:27:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:27:55 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:27:55 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:27:55 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:27:55 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:27:55 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:27:55 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:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:27:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:27:56 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:27:56 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:27:56 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:27:56 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:27:56 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:27:56 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:27:56 INFO - Downloading docopt-0.6.1.tar.gz 14:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:27:56 INFO - Installing collected packages: blobuploader, requests, docopt 14:27:56 INFO - Running setup.py install for blobuploader 14:27:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:27:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:27:56 INFO - Running setup.py install for requests 14:27:56 INFO - Running setup.py install for docopt 14:27:56 INFO - Successfully installed blobuploader requests docopt 14:27:56 INFO - Cleaning up... 14:27:56 INFO - Return code: 0 14:27:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:27:56 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:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:56 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:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:56 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', '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:27:56 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:27:56 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:27:56 INFO - Using env: {'DISPLAY': ':0', 14:27:56 INFO - 'HOME': '/home/cltbld', 14:27:56 INFO - 'LANG': 'en_US.UTF-8', 14:27:56 INFO - 'LANGUAGE': 'en_US:en', 14:27:56 INFO - 'LOGNAME': 'cltbld', 14:27:56 INFO - 'MAIL': '/var/mail/cltbld', 14:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:56 INFO - 'MOZ_NO_REMOTE': '1', 14:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:27:56 INFO - 'NO_EM_RESTART': '1', 14:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:56 INFO - 'PWD': '/builds/slave/test', 14:27:56 INFO - 'SHELL': '/bin/bash', 14:27:56 INFO - 'SHLVL': '1', 14:27:56 INFO - 'TERM': 'linux', 14:27:56 INFO - 'TMOUT': '86400', 14:27:56 INFO - 'USER': 'cltbld', 14:27:56 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:56 INFO - '_': '/tools/buildbot/bin/python'} 14:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:27:57 INFO - Running setup.py (path:/tmp/pip-yKt0gN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:27:57 INFO - Running setup.py (path:/tmp/pip-vO7xD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:27:57 INFO - Running setup.py (path:/tmp/pip-u9XeeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:27:57 INFO - Running setup.py (path:/tmp/pip-7wRGQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:27:57 INFO - Running setup.py (path:/tmp/pip-CzOA47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:27:57 INFO - Running setup.py (path:/tmp/pip-kBtAxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:27:57 INFO - Running setup.py (path:/tmp/pip-hVL7qJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:27:57 INFO - Running setup.py (path:/tmp/pip-enr9IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:27:57 INFO - Running setup.py (path:/tmp/pip-vUbJI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:27:57 INFO - Running setup.py (path:/tmp/pip-FgQTBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:27:57 INFO - Running setup.py (path:/tmp/pip-LmNnuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:27:57 INFO - Running setup.py (path:/tmp/pip-qBinVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:27:57 INFO - Running setup.py (path:/tmp/pip-Bghi5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:27:58 INFO - Running setup.py (path:/tmp/pip-tlj94g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:58 INFO - Running setup.py (path:/tmp/pip-lKvlfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:27:58 INFO - Running setup.py (path:/tmp/pip-jJCCzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:27:58 INFO - Running setup.py (path:/tmp/pip-QhFclR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:27:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:27:58 INFO - Running setup.py install for manifestparser 14:27:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:27:58 INFO - Running setup.py install for mozcrash 14:27:58 INFO - Running setup.py install for mozdebug 14:27:58 INFO - Running setup.py install for mozdevice 14:27:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:27:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:27:58 INFO - Running setup.py install for mozfile 14:27:58 INFO - Running setup.py install for mozhttpd 14:27:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:27:58 INFO - Running setup.py install for mozinfo 14:27:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Running setup.py install for mozInstall 14:27:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Running setup.py install for mozleak 14:27:59 INFO - Running setup.py install for mozlog 14:27:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Running setup.py install for moznetwork 14:27:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Running setup.py install for mozprocess 14:27:59 INFO - Running setup.py install for mozprofile 14:27:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Running setup.py install for mozrunner 14:27:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:27:59 INFO - Running setup.py install for mozscreenshot 14:27:59 INFO - Running setup.py install for moztest 14:28:00 INFO - Running setup.py install for mozversion 14:28:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:28:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:28:00 INFO - Cleaning up... 14:28:00 INFO - Return code: 0 14:28:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28: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:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:00 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:28:00 INFO - Using env: {'DISPLAY': ':0', 14:28:00 INFO - 'HOME': '/home/cltbld', 14:28:00 INFO - 'LANG': 'en_US.UTF-8', 14:28:00 INFO - 'LANGUAGE': 'en_US:en', 14:28:00 INFO - 'LOGNAME': 'cltbld', 14:28:00 INFO - 'MAIL': '/var/mail/cltbld', 14:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:00 INFO - 'MOZ_NO_REMOTE': '1', 14:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:00 INFO - 'NO_EM_RESTART': '1', 14:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:00 INFO - 'PWD': '/builds/slave/test', 14:28:00 INFO - 'SHELL': '/bin/bash', 14:28:00 INFO - 'SHLVL': '1', 14:28:00 INFO - 'TERM': 'linux', 14:28:00 INFO - 'TMOUT': '86400', 14:28:00 INFO - 'USER': 'cltbld', 14:28:00 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:00 INFO - '_': '/tools/buildbot/bin/python'} 14:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:00 INFO - Running setup.py (path:/tmp/pip-Fg4Exh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:00 INFO - Running setup.py (path:/tmp/pip-GsAa5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:00 INFO - Running setup.py (path:/tmp/pip-mBTEV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:00 INFO - Running setup.py (path:/tmp/pip-_mLC3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:00 INFO - Running setup.py (path:/tmp/pip-_bEUwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:00 INFO - Running setup.py (path:/tmp/pip-ZbPDsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:00 INFO - Running setup.py (path:/tmp/pip-fuKmI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:00 INFO - Running setup.py (path:/tmp/pip-7MXq39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:00 INFO - Running setup.py (path:/tmp/pip-ATBX_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:00 INFO - Running setup.py (path:/tmp/pip-AStBvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:01 INFO - Running setup.py (path:/tmp/pip-wDSezh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:01 INFO - Running setup.py (path:/tmp/pip-SjEzRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:01 INFO - Running setup.py (path:/tmp/pip-oZtarY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:01 INFO - Running setup.py (path:/tmp/pip-vhdMB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:01 INFO - Running setup.py (path:/tmp/pip-UWmjgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:01 INFO - Running setup.py (path:/tmp/pip-qznJIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:01 INFO - Running setup.py (path:/tmp/pip-EnsR5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:28:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:03 INFO - Downloading blessings-1.6.tar.gz 14:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:28:03 INFO - Installing collected packages: blessings 14:28:03 INFO - Running setup.py install for blessings 14:28:03 INFO - Successfully installed blessings 14:28:03 INFO - Cleaning up... 14:28:03 INFO - Return code: 0 14:28:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:03 INFO - Reading from file tmpfile_stdout 14:28:03 INFO - Current package versions: 14:28:03 INFO - argparse == 1.2.1 14:28:03 INFO - blessings == 1.6 14:28:03 INFO - blobuploader == 1.2.4 14:28:03 INFO - docopt == 0.6.1 14:28:03 INFO - functools32 == 3.2.3-2 14:28:03 INFO - jsonschema == 2.5.1 14:28:03 INFO - manifestparser == 1.1 14:28:03 INFO - mozInstall == 1.12 14:28:03 INFO - mozcrash == 0.17 14:28:03 INFO - mozdebug == 0.1 14:28:03 INFO - mozdevice == 0.48 14:28:03 INFO - mozfile == 1.2 14:28:03 INFO - mozhttpd == 0.7 14:28:03 INFO - mozinfo == 0.9 14:28:03 INFO - mozleak == 0.1 14:28:03 INFO - mozlog == 3.3 14:28:03 INFO - moznetwork == 0.27 14:28:03 INFO - mozprocess == 0.23 14:28:03 INFO - mozprofile == 0.28 14:28:03 INFO - mozrunner == 6.12 14:28:03 INFO - mozscreenshot == 0.1 14:28:03 INFO - mozsystemmonitor == 0.3 14:28:03 INFO - moztest == 0.7 14:28:03 INFO - mozversion == 1.4 14:28:03 INFO - psutil == 3.1.1 14:28:03 INFO - requests == 1.2.3 14:28:03 INFO - wsgiref == 0.1.2 14:28:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:03 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 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:28:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:28:03 INFO - Using env: {'DISPLAY': ':0', 14:28:03 INFO - 'HOME': '/home/cltbld', 14:28:03 INFO - 'LANG': 'en_US.UTF-8', 14:28:03 INFO - 'LANGUAGE': 'en_US:en', 14:28:03 INFO - 'LOGNAME': 'cltbld', 14:28:03 INFO - 'MAIL': '/var/mail/cltbld', 14:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:03 INFO - 'MOZ_NO_REMOTE': '1', 14:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:03 INFO - 'NO_EM_RESTART': '1', 14:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:03 INFO - 'PWD': '/builds/slave/test', 14:28:03 INFO - 'SHELL': '/bin/bash', 14:28:03 INFO - 'SHLVL': '1', 14:28:03 INFO - 'TERM': 'linux', 14:28:03 INFO - 'TMOUT': '86400', 14:28:03 INFO - 'USER': 'cltbld', 14:28:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:03 INFO - '_': '/tools/buildbot/bin/python'} 14:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:28:04 INFO - Cleaning up... 14:28:04 INFO - Return code: 0 14:28:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:28: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:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, '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': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:28: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', 'jsonschema'] in /builds/slave/test/build 14:28: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 jsonschema 14:28:04 INFO - Using env: {'DISPLAY': ':0', 14:28:04 INFO - 'HOME': '/home/cltbld', 14:28:04 INFO - 'LANG': 'en_US.UTF-8', 14:28:04 INFO - 'LANGUAGE': 'en_US:en', 14:28:04 INFO - 'LOGNAME': 'cltbld', 14:28:04 INFO - 'MAIL': '/var/mail/cltbld', 14:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:04 INFO - 'MOZ_NO_REMOTE': '1', 14:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:04 INFO - 'NO_EM_RESTART': '1', 14:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:04 INFO - 'PWD': '/builds/slave/test', 14:28:04 INFO - 'SHELL': '/bin/bash', 14:28:04 INFO - 'SHLVL': '1', 14:28:04 INFO - 'TERM': 'linux', 14:28:04 INFO - 'TMOUT': '86400', 14:28:04 INFO - 'USER': 'cltbld', 14:28:04 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:04 INFO - '_': '/tools/buildbot/bin/python'} 14:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:28:04 INFO - Cleaning up... 14:28:04 INFO - Return code: 0 14:28:04 INFO - Running post-action listener: _resource_record_post_action 14:28:04 INFO - Running post-action listener: _start_resource_monitoring 14:28:04 INFO - Starting resource monitoring. 14:28:04 INFO - [mozharness: 2016-09-20 21:28:04.394329Z] Finished create-virtualenv step (success) 14:28:04 INFO - [mozharness: 2016-09-20 21:28:04.394803Z] Running install step. 14:28:04 INFO - Running pre-action listener: _resource_record_pre_action 14:28:04 INFO - Running main action method: install 14:28:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:04 INFO - Reading from file tmpfile_stdout 14:28:04 INFO - Detecting whether we're running mozinstall >=1.0... 14:28:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:28:04 INFO - Reading from file tmpfile_stdout 14:28:04 INFO - Output received: 14:28:04 INFO - Usage: mozinstall [options] installer 14:28:04 INFO - Options: 14:28:04 INFO - -h, --help show this help message and exit 14:28:04 INFO - -d DEST, --destination=DEST 14:28:04 INFO - Directory to install application into. [default: 14:28:04 INFO - "/builds/slave/test"] 14:28:04 INFO - --app=APP Application being installed. [default: firefox] 14:28:04 INFO - mkdir: /builds/slave/test/build/application 14:28:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:28:04 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:28:18 INFO - Reading from file tmpfile_stdout 14:28:18 INFO - Output received: 14:28:18 INFO - /builds/slave/test/build/application/firefox/firefox 14:28:18 INFO - Running post-action listener: _resource_record_post_action 14:28:18 INFO - [mozharness: 2016-09-20 21:28:18.268791Z] Finished install step (success) 14:28:18 INFO - [mozharness: 2016-09-20 21:28:18.269147Z] Running run-tests step. 14:28:18 INFO - Running pre-action listener: _resource_record_pre_action 14:28:18 INFO - Running main action method: run_tests 14:28:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:28:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:28:18 INFO - Python 2.7.3 14:28:18 INFO - Return code: 0 14:28:18 INFO - grabbing minidump binary from tooltool 14:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:18 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 0x107b3b0>, '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 0x107c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:28:18 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:28:18 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:28:18 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:28:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:28:18 INFO - Return code: 0 14:28:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:28:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:28:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:28:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:28:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:28:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:28:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:28:18 INFO - Using env: {'DISPLAY': ':0', 14:28:18 INFO - 'HOME': '/home/cltbld', 14:28:18 INFO - 'LANG': 'en_US.UTF-8', 14:28:18 INFO - 'LANGUAGE': 'en_US:en', 14:28:18 INFO - 'LOGNAME': 'cltbld', 14:28:18 INFO - 'MAIL': '/var/mail/cltbld', 14:28:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:28:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:18 INFO - 'MOZ_NO_REMOTE': '1', 14:28:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:28:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:28:18 INFO - 'NO_EM_RESTART': '1', 14:28:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:18 INFO - 'PWD': '/builds/slave/test', 14:28:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:28:18 INFO - 'SHELL': '/bin/bash', 14:28:18 INFO - 'SHLVL': '1', 14:28:18 INFO - 'TERM': 'linux', 14:28:18 INFO - 'TMOUT': '86400', 14:28:18 INFO - 'USER': 'cltbld', 14:28:18 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474406854.980459-2058220414', 14:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:18 INFO - '_': '/tools/buildbot/bin/python'} 14:28:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:28:18 INFO - mozversion application_buildid: 20160920092516 14:28:18 INFO - mozversion application_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:28:18 INFO - mozversion application_display_name: Firefox 14:28:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:28:18 INFO - mozversion application_name: Firefox 14:28:18 INFO - mozversion application_remotingname: firefox 14:28:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:28:18 INFO - mozversion application_vendor: Mozilla 14:28:18 INFO - mozversion application_version: 50.0 14:28:18 INFO - mozversion platform_buildid: 20160920092516 14:28:18 INFO - mozversion platform_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:28:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:28:18 INFO - mozversion platform_version: 50.0 14:28:18 INFO - using testdate: 1474406898 14:28:18 INFO - actual date: 1474406898 14:28:18 INFO - starting webserver on 'localhost:34246' 14:28:18 INFO - SUITE-START | Running 2 tests 14:28:18 INFO - TEST-START | dromaeo_css 14:28:18 INFO - operating with platform_type : linux_ 14:28:18 INFO - Initialising browser for dromaeo_css test... 14:28:18 INFO - TEST-INFO | started process 4902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb7xswg/profile http://localhost:34246/getInfo.html) 14:28:22 INFO - PROCESS | 4902 | __metrics Screen width/height:1600/1200 14:28:22 INFO - PROCESS | 4902 | colorDepth:24 14:28:22 INFO - PROCESS | 4902 | Browser inner width/height: 1024/697 14:28:22 INFO - PROCESS | 4902 | __metrics 14:28:23 INFO - PROCESS | 4902 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:28:23 INFO - PROCESS | 4902 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:28:24 INFO - TEST-INFO | 4902: exit 0 14:28:24 INFO - Browser initialized. 14:28:24 INFO - Running cycle 1/1 for dromaeo_css test... 14:28:24 INFO - TEST-INFO | started process 4990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb7xswg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:28:36 INFO - PROCESS | 4990 | RSS: Main: 143884288 14:28:36 INFO - PROCESS | 4990 | 14:29:27 INFO - PROCESS | 4990 | 1474406967884 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:31:35 INFO - PROCESS | 4990 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34246/tests/dromaeo/cssquery-ext.html) 14:31:37 INFO - PROCESS | 4990 | RSS: Main: 271347712 14:31:37 INFO - PROCESS | 4990 | 14:34:39 INFO - PROCESS | 4990 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-ext.html (next: http://localhost:34246/tests/dromaeo/cssquery-jquery.html) 14:34:40 INFO - PROCESS | 4990 | RSS: Main: 232927232 14:34:40 INFO - PROCESS | 4990 | 14:37:41 INFO - PROCESS | 4990 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34246/tests/dromaeo/cssquery-mootools.html) 14:37:42 INFO - PROCESS | 4990 | RSS: Main: 226525184 14:37:42 INFO - PROCESS | 4990 | 14:40:44 INFO - PROCESS | 4990 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34246/tests/dromaeo/cssquery-prototype.html) 14:40:44 INFO - PROCESS | 4990 | RSS: Main: 175906816 14:40:44 INFO - PROCESS | 4990 | 14:43:47 INFO - PROCESS | 4990 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34246/tests/dromaeo/cssquery-yui.html) 14:43:48 INFO - PROCESS | 4990 | RSS: Main: 176377856 14:43:48 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-yui.html (next: http://localhost:34246/tests/dromaeo/cssquery-dojo.html) 14:46:50 INFO - PROCESS | 4990 | RSS: Main: 258899968 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | __start_tp_report 14:46:50 INFO - PROCESS | 4990 | _x_x_mozilla_page_load 14:46:50 INFO - PROCESS | 4990 | _x_x_mozilla_page_load_details 14:46:50 INFO - PROCESS | 4990 | |i|pagename|runs| 14:46:50 INFO - PROCESS | 4990 | |0;dojo.html;3204.615446146463;3208.1017315151757;3234.191452136966;3257.4950882613202;3267.852946617402;5590.301212348457;5683.488486036256;5692.943070569293;5694.943050569493;5707.9999999999945;5635.943640563593;5697.373288938224;5708.914366284499;5710.828675139742;5712.657240565559;4947.356843610343;5151.150060240056;5159.355080614923;5218.112920803473;5229.581633469314;4969.080720066781;5087.821926232583;5131.3842338919385;5138.332016837806;5141.228815677641;5100.056489549426;5106.208537676647;5117.590592752589;5125.487451254882;5176.430592634807;5228.08508511009;5239.345081864767;5246.108161612535;5248.212768084818;5282.339707536577;4965.90068198634;5017.046761115413;5067.873303167414;5142.100132476839;5153.304303918961;5487.533559647435;5491.890162196773;5505.394406615269;5506.531944784698;5517.337919449652;6034.064719968412;6039.063945088518;6041.456268935773;6043.516518678495;6046.062860256668;5933.19901813254;5957.434043765836;5978.8505287367725;6023.096535519663;6056.394360563952;5594.216809646653;5639.238702775114;5642;5649.096144616841;5679.403662615448;5954.434328738763;6092.817215483543;6095.329513753483;6104.664243466563;6110.297315808739;5877.5591830612875;5894.2926848778425;5913.172155898092;5966.701664916737;6005.039193728982;6034.456898879122;6039.124326972652;6041.124037014608;6042.879142417127;6050.304215015241;5835.241418615553;5941.851453713692;5991.910121348184;5995.640261584319;6014.789482368184;5974.133750606138;6020.909686354708;6025.969870150621;6078.574499784972;6100.145979562864;4639.350490931272;4648.070385922829;4648.535146485392;4649.18639238132;4680.789364478606;3994.2610617035944;4130.050088479667;4144.79276036197;4159.251334759711;4174.666026717855;5578.9163162552595;5658.971705141448;5694.829155125353;5802.82591522255;5810.970945145247;6066.181065556182;6086.391360863879;6095.146679464879;6100.176476175749;6100.847478812976;5741.253637027244;5750.712464376765;5752.108423194411;5758.1362795581;5758.280214973129;5103.770330335144;5204.609654275944;5215.739213039333;5230.476952304739;5246.947530524722;6327.145835312266;6340.365963403623;6343.555951083472;6359.745610175633;6361.745530178833;6079.240094988127;6085.421884920926;6093.482054025458;6121.938780612137;6125.295591007091;5989.221401217814;6110.953680641605;6119.685352982247;6387.744490220339;6397.104405383343;5965.522758179249;5975.462208401264;5978.312494063064;6006;6016.7292471838;6053.122297266959;6064.636121832704;6064.666443345482;6066.636001839904;6066.666333351708;5714.685692286798;5822.475977162076;5853.64878107315;5920.79277225295;5930.318013428338;5857.062869940789;5897.469227769521;5910.349861515316;5913.46778789911;5946.851328716643;5808.970955145197;5849;5856.297244330537;5856.824295271149;5858.297004359504;5702.173184888251;5849;5851.61964472308;5901.616394934315;5926.4666180044005;5813.534917206529;5815.79644712433;5819.738111784895;5830.912536312044;5855.590108692521 14:46:50 INFO - PROCESS | 4990 | |1;ext.html;586.9559783016272;634.7461015593761;648.8961766117422;662.0730976632717;670.1187937861713;34658.480122798144;44621.55378446214;45856.77071614646;45945.7702711486;49698;47551.28673069907;47697.28454073192;47891.760541197335;47912.28131578029;48047;9212.585433655484;9347.579358928831;9348.205402540792;9356.29827762917;9368.859467107999;8548.871766923501;10877.510512026978;11174.329540227573;11181.944090279538;11464.598739044135;11342.489587968561;11403.745587985357;11424.143189260838;11427.542898284059;11431.714207144805;11116.166287528427;11174.161937854693;11178.888211117866;11182.217244792868;11185.888141118567;11067.446627668583;11131.332120072822;11135.387553684544;11141.387223702694;11147.609833655864;44865.55134448646;45088.3236751449;45155.32267015997;45165.322520162226;45224.54775452236;3855.306044911915;4028.8589899353674;4233.682473814445;4277.550857159985;4610.170169369512;33912.49131263033;33936.66063339385;34407.311853763036;34438;34454;4621.676482646216;4672.883177920545;4687.9531204687855;4705.388299521074;4729.763511824395;352837;353351;353906;354207;354226;61788.69105654533;61917.380826192064;62163.689181554706;62190.68904655448;62261.377386226464;40113;40764;40907.7954610231;42851.35722964158;64630.67684661547;15430;15529.145896975775;15555.22223888801;15579.844201558066;15588.844111558967;10537.315074520286;11323.37721425313;11359.034482068952;11366.318020918772;11496.655100347003;19646;20289.49276268105;20368.28710995108;20380.184792608427;20392.796072039386;7594.81012974669;7624.123225829101;7685.884711729329;7798.454108212371;7814.726484573012;1043.2592859070146;1074.425182527344;1099.312929419113;1193.4689063366893;1242.211195890616;33126.503102453484;33652.83173584165;34031;34225.31549368999;34314.82842585771;1178.3401295274673;1201.501376928565;1226.2336039975016;1229.7110179107904;1250.3623152192265;16644.750328745078;17594.82405175932;17672;17699.29202831898;17737.29050837952;604224;611273;611580;611780;611890;64135.679321604024;64596.677016614616;64695.676521618036;64754.352456475775;64831.67584162049;1303.1008604063165;1365.1126767601138;1402.705431859321;1431.0698046270008;1449.9855001450062;31554.684453156093;35406.11484712903;35709.80680992554;35774.8211258942;35785.31386803569;54672.72663636656;55553.44446555483;55584.44415555955;55602;55635.44364556303;474513;481577;481695;482063;482228;27255.182344529694;29173.270668233465;29468.852655736584;30033.69966300309;30525.01587396812;50463.74768126136;50688.23967640562;50797;50804.23793643025;50814.49185508245;43151.13697726091;43180.13639727125;43227;44688;47546;44065;44670.10659786851;44994;46278.53721462877;46570.301445479025;44380.21773185992;44472.33291500695;44671.10657786891;45016;45057.774711127546;37947;39838;39873.20253594854;40084;40493.59506405016;29285.41429171362;29753.553696695;29978.25054373571;30007.249818754706;30127.246818829706 14:46:50 INFO - PROCESS | 4990 | |2;jquery.html;414.9087200815819;416.58965918570186;421.87765547991086;427.88447119277794;432.7814453700881;19460.41618751438;20328.085236164352;20990.89504552477;21249.150033998623;21409.785902140975;19450.51373715658;19929.800701992976;20081.799182008177;20144.294949676765;20196.798032019713;5396.406395296504;5562.527185189264;5605.691686957216;5614.578906582003;5614.831555053355;5543.77824887004;5614.410486898878;5683.374828768853;6005.549583781212;6048.848778780522;6015.879682406371;6019.819405417845;6020.066889632092;6029.366916473751;6031.24609423822;5823.359430462646;5986.940130598704;5987.55093367997;6033.5173186145;6033.637981721067;5442.6462279952075;5454.699991500466;5461.317335333083;5462.126059830408;5466.480684334982;14458.349374278077;14647.560573182822;23385.947632356416;23484.53030939372;23927.16254931069;2343.94140146496;2433.7322894481595;2441.719202291724;2479.652848601197;2488.7884529814987;10392.688219353431;10696.679099627023;10714.517846696917;10814.243002989793;11435.714107147305;1974.02285868496;1978.5350442645968;2002.7897070807646;2030.9289174878809;2064.632849233098;89225.55387223023;92256.53871730666;92918.53540732253;93751.53124234335;95000;11653;11655.475503602189;11719.648410547697;11959.940200299117;12100.152989290842;16769.91615041917;16880.240389182516;16880.577985550215;16905.32378704838;16916.746248806277;7475.364594009461;7697.653605587762;7769.572673503011;7780.688772449039;8010.719624813103;7564.886526702104;7602.049743782028;7775.377969762406;7778.766637000899;7814.335781458526;4951;4979.3277907482025;4989.725565093882;5012.423571289275;5082.796688132507;2032.9898350508154;2102.138123369411;2108.9578208435746;2116.9732679650356;2125.7449106107365;952.766572189804;1004.5379125602159;1020.8417695257099;1022.2844009193594;1023.7287118913493;20112;21018.291740034707;21036.789632103788;21234.362969110953;21314.04086816066;1022.6931920423843;1034.3276870034538;1039.3867618105203;1068.1188019883627;1075.6450371377427;10528.210384221215;11589.652310430532;11624.535018599161;11643.41782910851;11646.184767066225;504630;532440;535677;537608;567090;71553;90847;100855.49572252092;105891.4705426468;107765;1013.4172850611076;1016.8525563793356;1053.3574519543072;1059.6662051453768;1062.3997441445558;18140.365087221882;18528.44414667562;18585.33147390059;18725.90637046806;19013.71479427782;44231.55768442275;45129.774351128035;46277.30584041175;46761.53238467708;47242.29136563023;59177.70411148089;60415;61639.38360616336;67899;69093.96359054517;8797.164269394398;9130.726078217664;9171.312151588523;9411.200047995722;9443;13226.272555009182;13666.86333136656;13877.583672489842;13899.930500347433;13998.790018149939;12323.322217277777;12340.3212823292;12362.876371236172;12364.938175309066;12436.937815310866;11050.33697978124;11620.012298954684;11740.941295293469;11741.706457338294;11851.940740296242;11806.35065071395;12001;12251.816222756845;12587.978885067701;13012.154209976321;9121.77195570116;9179.082091790768;9187.402818816756;9345.953270233877;9580.425174489554;12043.819342710041;12371.938140309241;12466.065045121471;12820;12912.741745164856 14:46:50 INFO - PROCESS | 4990 | |3;mootools.html;1446.717890011448;1502.7370210213214;1643.4740882917463;1779.5551112221945;1830.4142674344216;8967.596458159382;9632;9648.855267170999;9651.662191823287;9654.227661787058;7961.6019199039965;8185.631646575904;8191.426600137993;8386.706465273717;9409.576569054392;2317.8029867461287;2427.987860060706;2432.6715893354435;2432.793212576933;2433.4889673168655;2475.2945410557904;2487.404292458447;2517.0812653381568;2520.3573088862404;2527.9241622751347;2492.8379655322346;2496.787773039294;2510.472800711853;2517.874106294687;2527.519771243467;2513.886875090607;2521.760432758885;2537.2261460254613;2538.200466852945;2544.3639090227443;2436.281297017384;2455.8649274289905;2461.4707837814954;2461.963070553943;2463.408781892351;9347.76630584234;9368.20370268528;9376.718698439057;9387.249020078378;9414.482203478807;5484.890302193974;5575.247341608912;5623.212750214973;5625.578081643893;5626.774929002835;3554.48459973305;3666.77999320039;3719.590845007047;3758.229562939591;3776.9433458498142;1168.783775001619;1171.2152857585438;1171.619223752284;1199.0886925936263;1211.1582450197116;13239;13344.666383340396;13422.798658020138;13423.3959471824;13453.730925381535;4865.026994601094;4936;4995.750212489361;5068.847934561969;5115.87210319745;11307.265027773168;11315.83026254607;11327.886721132683;11331.716707082389;11356;5642.746076426571;5661;5670.518005969457;5674.375818659944;5679.943200568024;4322.22200003996;4357.651387888961;5149;5166.225066240094;5205.089109405839;4314.115606300701;4378.562143785596;4468.463784345834;4472.597466228055;4473.507914129443;1364.7543442180304;1388.9236092015037;1412.4279666734783;1430.5493769462794;1440.4598275646633;966.2656381150376;1032.7573020340214;1038.5430410619304;1051.069803224152;1075.2365820267698;6415.166028416276;6439.54923155384;6471;6604.960683573688;6643.634600097041;457.0447764172895;458.3857630774778;458.71101206239854;470.94584122826313;471.36130543114274;4392.209402307615;4420.292753159479;4444.355568442557;4532.093581283756;4551.499335073139;11511.884881151249;11734.06127509797;11809.23239989398;11809.881901180877;11838;10637.9468102659;11669.124815638861;11680.240784348991;11685.824712629317;11692.47383867728;8585.957070214734;8872.201501864864;8954.552272386354;8968.865467018;8979.640814367353;6385.808425747235;6450.677466126769;6554.279029306867;6646.867062658816;6750.35871592198;10704.197185210984;10884.40135792539;10936.343819370863;11052.226344155832;11068.059214966817;9781.804363912825;10691.625793097204;11217.607383741857;11264.324140551593;11362.602308919148;8254.793630159294;8413.70552030676;8674.956625217086;8682.826343473222;8683.131686831266;9133;9314.44113353201;9375.484348360906;9393;9402.200812930703;8107.1892810718455;8129.349652027942;8311.625976830936;8462.492250464991;8552.572371381282;7918.406119541172;8042.517448953082;8047.356211502949;8125.228103330411;8288.627011784363;7431.516951398142;7487.962560187164;7604.8479030417975;7605.505642133465;7612.7716168515035;5467.863303417287;5510.531604813636;5527.8894422112135;5571.331440227036;5659.066254068197;5334.093204155225;5398.568114550748;5486.396496385315;5505.587080968863;5598.3282006159525 14:46:50 INFO - PROCESS | 4990 | |4;prototype.html;344.8327561132851;351.37455329513466;352.8447483107434;355.79008385052816;357.71204180634595;5523.143912693529;5568.607848037986;5586.888262234758;5589.916151257735;5594.468525490082;5435.0162190800365;5462.934727728095;5527.53015993641;5531.1426728857095;5539.224508568794;3821.464994900702;4144.958550414487;4312.1806856697285;4317.618362124122;4338.071801533968;4274.397101087093;4297.699161058727;4311.482622085354;4319.956800431987;4348.434703488542;4293.454356431682;4303.7848107594655;4306.956930430687;4335.6965012449145;4359.433273674418;4097.815598298054;4258.787060646986;4301.5698430157045;4362.760048197348;4365.105153443552;3769.792661403622;3772.020104117744;3778.678812300958;3786.772793632391;3814.5422549293985;5402.702851343174;5410.756515956791;5428.294321738188;5430.262316058863;5431.212474191259;4819.44576373718;4926.45808961014;4927.285543596195;4932.950670493285;4973.577245934064;5712.200291959129;5717.857053573653;5719.571032172604;5723.7963384097;5758.942410575883;4178.603032711918;4198.8243291878325;4199.328107502785;4201.642860356873;4203.5376108628025;5628.887422251573;5807.941920580783;5849.61977471463;5864.501517371071;5873.765049398019;4232.851850185229;4236.2586547354085;4239.258129827268;4239.915201695949;4244.85143019999;4323.286657701507;4336.761478118734;4344.45694288214;4344.522102568715;4345.543717909574;4821.493743156988;4859.829905953275;4881.561941969789;4934.7285899275885;4941.331680892278;5688.9715551422805;5692.345380281321;5701.686407247641;5727.255456790674;5757.539396848242;1453.128123126137;1473.2265560580568;1476.7194233095677;1477.637978695205;1484.5991582272725;914.9451032938046;950.740269143374;956.3974695941512;959.8464245720651;969.2827307792184;4894.902101957941;4899.583535399531;4906.411230652345;4924.63065270099;4929.408470983433;5085.8728531787;5165.380154381498;5173.560247379015;5178.249153872667;5220.451852555427;4605.769711514411;4875.829345972874;4899.853004409873;4914.311996320518;4932;2737.630419893329;2740.657417822772;2745.4646343962736;2775.5428400090113;2787.958180627292;5273.947260527346;5341.86645333862;5342.412334643186;5383.623146379794;5401.648892821954;5265.7103859287345;5308.23030660552;5542.722863856791;5655.802046928337;5987.341392446828;5910.852228694317;6078.0882867569335;6082.057281121433;6094.634321940698;6099.512039036955;5917.201177841109;5931.1992880960515;5934.436228558453;5949.167116603592;5958.165856779968;5499.202615620793;5762.913556296743;5856.736446859816;5867.823965281048;5929.851753706192;6098.908516372347;6112.602680825732;6125.081237814185;6126.846828829315;6138.416850399383;4082.5100987880455;4298.527162012114;4325.307950727868;4341.60925516705;4342.435483387139;5820.283016510224;5826.533877289722;5848.912266315923;5857.794977175949;5869.178315035983;5722.054501469521;5733.913991290051;5747.252857128547;5768.913466297925;5771.4517120875125;5610.775568977195;5613.607047506635;5695.943040569541;5718.771249149988;5739.253896993364;5623.971880140573;5645.71771411436;5674.4892959633835;5680.194269839135;5688.061469857427;5629.802956896653;5742.339630942495;5748.137779333133;5794.18881356602;5801.767929282782;5606.542299002207;5613.24221230145;5621.634593751393;5622.5501959842295;5654.971725141348 14:46:50 INFO - PROCESS | 4990 | |5;yui.html;792.5522080024786;827.5655280977483;872.2803686958259;880.225401646551;887.5473508510665;16029.438969636065;16788.664226715475;16822.57943551412;16895.577610559725;16913.91543042286;15638.13990230537;15985.760213596805;16004.919975400107;16120.355185792583;16121.435749748762;728.0353531570677;852.045708806138;870.7692461497708;876.3996662286354;876.5617191404298;857.9656813727444;859.1365677494109;859.6647307550061;860.6342304520585;860.8149247911698;919.31511024287;922.6816748221886;924.0574613893824;924.5839372282467;925.6158694141923;915.217489046862;917.289100946765;917.8577320515365;918.0314767919842;918.0773322810566;678.6369292428527;679.4564348521212;680.0547239337276;680.4760334542375;680.5814424129175;14749.557513274618;14774.778378324334;14797.038192517559;14797.704045919128;14804.70390592193;15575.766363504557;15728.842711572966;15774.921125394414;16214.027158370418;16549.427804358565;12827.615171544869;12982.870171298355;13024.153430027018;13222.735545289137;13279.734405311936;3971.4439978403047;3978.8209530571194;3999.1801680655394;4115.053537686349;4121.196366708493;79881.60059199667;79885;83656;83823.5808820952;84000.15999839985;11306.265092768943;11334.129868571757;11451.541938322374;11619.825702614467;11661.300321980709;22106.33680989541;22231;22282.554348913254;22282.777172228394;22294.77705222927;8696.21734043939;8818.735437936872;8874.334424918157;8876;9362.391444556082;4615.976920115445;4756.6432517561325;4781.115493633689;4905.2151655734915;4959.156943319645;3860.1700634363747;3922.607739226112;4010.8796736097966;4012.297847876668;4015.5181378234797;659.5646873063755;661.8411581220472;725.0863911471583;748.3078152708723;762.8931949527071;615.8583525789094;748.5882764479624;756.8032311599023;760.5892817878283;765.1239330966013;9707.417554946725;9779.755506112404;9955.800883982425;10060.23765677007;10221.693349199535;594.4560726934893;772.5326177662433;774.0788461730523;778.4200770426069;797.3461761355633;4450.977745111319;4874.95125048745;4882.316475693406;4903.754812259444;4921.680090794087;118079;118263;121416.39291803485;123106;123363;67906.66046669734;100554;103155;103281;107665;93631;95479;95493.5225323855;96297.5185124084;96830.5158474203;8752.304487067271;8809.471431714117;8967.865482017905;9046.773830654023;9374.906250937676;56367.43632563786;56571.71714141403;56738.148927765295;57980;58986.705066474395;77794.2220577787;78427.60786196032;82499;83502;84625;11296.943515282372;11633.88366116328;11655.358955257543;11730.94134529356;11892.762144756884;12497.37513124322;12567.308798016196;12881.935590321988;12974.091813573337;13055.608331750063;12108.273503589813;12192.75614487723;12218.450169742206;12327.137100402886;12492.875071249535;11746.17776755619;11979.28124312544;12017.278963262232;12155.878441215475;12483.12618116723;11313.830292545454;11393.829092563785;12002.339871307162;12200.389980501117;12370.505179792708;9841.212702983887;9947.353422027545;9970.451625160686;9997;10115.645952391631;10023.69928902134;10238.385696858213;10393.532291047073;10400.27198096126;10429.322094063862 14:46:50 INFO - PROCESS | 4990 | __end_tp_report 14:46:50 INFO - PROCESS | 4990 | __start_cc_report 14:46:50 INFO - PROCESS | 4990 | _x_x_mozilla_cycle_collect,1107 14:46:50 INFO - PROCESS | 4990 | __end_cc_report 14:46:50 INFO - PROCESS | 4990 | __startTimestamp1474408010655__endTimestamp 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | ------- Summary: start ------- 14:46:50 INFO - PROCESS | 4990 | Number of tests: 6 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | [#0] dojo.html Cycles:165 Average:5605.78 Median:5830.91 stddev:638.89 (11.0%) stddev-sans-first:612.45 14:46:50 INFO - PROCESS | 4990 | Values: 3204.6 3208.1 3234.2 3257.5 3267.9 5590.3 5683.5 5692.9 5694.9 5708.0 5635.9 5697.4 5708.9 5710.8 5712.7 4947.4 5151.2 5159.4 5218.1 5229.6 4969.1 5087.8 5131.4 5138.3 5141.2 5100.1 5106.2 5117.6 5125.5 5176.4 5228.1 5239.3 5246.1 5248.2 5282.3 4965.9 5017.0 5067.9 5142.1 5153.3 5487.5 5491.9 5505.4 5506.5 5517.3 6034.1 6039.1 6041.5 6043.5 6046.1 5933.2 5957.4 5978.9 6023.1 6056.4 5594.2 5639.2 5642.0 5649.1 5679.4 5954.4 6092.8 6095.3 6104.7 6110.3 5877.6 5894.3 5913.2 5966.7 6005.0 6034.5 6039.1 6041.1 6042.9 6050.3 5835.2 5941.9 5991.9 5995.6 6014.8 5974.1 6020.9 6026.0 6078.6 6100.1 4639.4 4648.1 4648.5 4649.2 4680.8 3994.3 4130.1 4144.8 4159.3 4174.7 5578.9 5659.0 5694.8 5802.8 5811.0 6066.2 6086.4 6095.1 6100.2 6100.8 5741.3 5750.7 5752.1 5758.1 5758.3 5103.8 5204.6 5215.7 5230.5 5246.9 6327.1 6340.4 6343.6 6359.7 6361.7 6079.2 6085.4 6093.5 6121.9 6125.3 5989.2 6111.0 6119.7 6387.7 6397.1 5965.5 5975.5 5978.3 6006.0 6016.7 6053.1 6064.6 6064.7 6066.6 6066.7 5714.7 5822.5 5853.6 5920.8 5930.3 5857.1 5897.5 5910.3 5913.5 5946.9 5809.0 5849.0 5856.3 5856.8 5858.3 5702.2 5849.0 5851.6 5901.6 5926.5 5813.5 5815.8 5819.7 5830.9 5855.6 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | [#1] ext.html Cycles:180 Average:64866.03 Median:22070.93 stddev:130911.78 (593.1%) stddev-sans-first:131190.06 14:46:50 INFO - PROCESS | 4990 | Values: 587.0 634.7 648.9 662.1 670.1 34658.5 44621.6 45856.8 45945.8 49698.0 47551.3 47697.3 47891.8 47912.3 48047.0 9212.6 9347.6 9348.2 9356.3 9368.9 8548.9 10877.5 11174.3 11181.9 11464.6 11342.5 11403.7 11424.1 11427.5 11431.7 11116.2 11174.2 11178.9 11182.2 11185.9 11067.4 11131.3 11135.4 11141.4 11147.6 44865.6 45088.3 45155.3 45165.3 45224.5 3855.3 4028.9 4233.7 4277.6 4610.2 33912.5 33936.7 34407.3 34438.0 34454.0 4621.7 4672.9 4688.0 4705.4 4729.8 352837.0 353351.0 353906.0 354207.0 354226.0 61788.7 61917.4 62163.7 62190.7 62261.4 40113.0 40764.0 40907.8 42851.4 64630.7 15430.0 15529.1 15555.2 15579.8 15588.8 10537.3 11323.4 11359.0 11366.3 11496.7 19646.0 20289.5 20368.3 20380.2 20392.8 7594.8 7624.1 7685.9 7798.5 7814.7 1043.3 1074.4 1099.3 1193.5 1242.2 33126.5 33652.8 34031.0 34225.3 34314.8 1178.3 1201.5 1226.2 1229.7 1250.4 16644.8 17594.8 17672.0 17699.3 17737.3 604224.0 611273.0 611580.0 611780.0 611890.0 64135.7 64596.7 64695.7 64754.4 64831.7 1303.1 1365.1 1402.7 1431.1 1450.0 31554.7 35406.1 35709.8 35774.8 35785.3 54672.7 55553.4 55584.4 55602.0 55635.4 474513.0 481577.0 481695.0 482063.0 482228.0 27255.2 29173.3 29468.9 30033.7 30525.0 50463.7 50688.2 50797.0 50804.2 50814.5 43151.1 43180.1 43227.0 44688.0 47546.0 44065.0 44670.1 44994.0 46278.5 46570.3 44380.2 44472.3 44671.1 45016.0 45057.8 37947.0 39838.0 39873.2 40084.0 40493.6 29285.4 29753.6 29978.3 30007.2 30127.2 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | [#2] jquery.html Cycles:180 Average:31235.86 Median:11687.45 stddev:88444.01 (756.7%) stddev-sans-first:88661.84 14:46:50 INFO - PROCESS | 4990 | Values: 414.9 416.6 421.9 427.9 432.8 19460.4 20328.1 20990.9 21249.2 21409.8 19450.5 19929.8 20081.8 20144.3 20196.8 5396.4 5562.5 5605.7 5614.6 5614.8 5543.8 5614.4 5683.4 6005.5 6048.8 6015.9 6019.8 6020.1 6029.4 6031.2 5823.4 5986.9 5987.6 6033.5 6033.6 5442.6 5454.7 5461.3 5462.1 5466.5 14458.3 14647.6 23385.9 23484.5 23927.2 2343.9 2433.7 2441.7 2479.7 2488.8 10392.7 10696.7 10714.5 10814.2 11435.7 1974.0 1978.5 2002.8 2030.9 2064.6 89225.6 92256.5 92918.5 93751.5 95000.0 11653.0 11655.5 11719.6 11959.9 12100.2 16769.9 16880.2 16880.6 16905.3 16916.7 7475.4 7697.7 7769.6 7780.7 8010.7 7564.9 7602.0 7775.4 7778.8 7814.3 4951.0 4979.3 4989.7 5012.4 5082.8 2033.0 2102.1 2109.0 2117.0 2125.7 952.8 1004.5 1020.8 1022.3 1023.7 20112.0 21018.3 21036.8 21234.4 21314.0 1022.7 1034.3 1039.4 1068.1 1075.6 10528.2 11589.7 11624.5 11643.4 11646.2 504630.0 532440.0 535677.0 537608.0 567090.0 71553.0 90847.0 100855.5 105891.5 107765.0 1013.4 1016.9 1053.4 1059.7 1062.4 18140.4 18528.4 18585.3 18725.9 19013.7 44231.6 45129.8 46277.3 46761.5 47242.3 59177.7 60415.0 61639.4 67899.0 69094.0 8797.2 9130.7 9171.3 9411.2 9443.0 13226.3 13666.9 13877.6 13899.9 13998.8 12323.3 12340.3 12362.9 12364.9 12436.9 11050.3 11620.0 11740.9 11741.7 11851.9 11806.4 12001.0 12251.8 12588.0 13012.2 9121.8 9179.1 9187.4 9346.0 9580.4 12043.8 12371.9 12466.1 12820.0 12912.7 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | [#3] mootools.html Cycles:180 Average:6211.34 Median:457.72 stddev:3565.65 (779.0%) stddev-sans-first:3557.68 14:46:50 INFO - PROCESS | 4990 | Values: 1446.7 1502.7 1643.5 1779.6 1830.4 8967.6 9632.0 9648.9 9651.7 9654.2 7961.6 8185.6 8191.4 8386.7 9409.6 2317.8 2428.0 2432.7 2432.8 2433.5 2475.3 2487.4 2517.1 2520.4 2527.9 2492.8 2496.8 2510.5 2517.9 2527.5 2513.9 2521.8 2537.2 2538.2 2544.4 2436.3 2455.9 2461.5 2462.0 2463.4 9347.8 9368.2 9376.7 9387.2 9414.5 5484.9 5575.2 5623.2 5625.6 5626.8 3554.5 3666.8 3719.6 3758.2 3776.9 1168.8 1171.2 1171.6 1199.1 1211.2 13239.0 13344.7 13422.8 13423.4 13453.7 4865.0 4936.0 4995.8 5068.8 5115.9 11307.3 11315.8 11327.9 11331.7 11356.0 5642.7 5661.0 5670.5 5674.4 5679.9 4322.2 4357.7 5149.0 5166.2 5205.1 4314.1 4378.6 4468.5 4472.6 4473.5 1364.8 1388.9 1412.4 1430.5 1440.5 966.3 1032.8 1038.5 1051.1 1075.2 6415.2 6439.5 6471.0 6605.0 6643.6 457.0 458.4 458.7 470.9 471.4 4392.2 4420.3 4444.4 4532.1 4551.5 11511.9 11734.1 11809.2 11809.9 11838.0 10637.9 11669.1 11680.2 11685.8 11692.5 8586.0 8872.2 8954.6 8968.9 8979.6 6385.8 6450.7 6554.3 6646.9 6750.4 10704.2 10884.4 10936.3 11052.2 11068.1 9781.8 10691.6 11217.6 11264.3 11362.6 8254.8 8413.7 8675.0 8682.8 8683.1 9133.0 9314.4 9375.5 9393.0 9402.2 8107.2 8129.3 8311.6 8462.5 8552.6 7918.4 8042.5 8047.4 8125.2 8288.6 7431.5 7488.0 7604.8 7605.5 7612.8 5467.9 5510.5 5527.9 5571.3 5659.1 5334.1 5398.6 5486.4 5505.6 5598.3 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | [#4] prototype.html Cycles:180 Average:4750.56 Median:5392.64 stddev:1389.54 (25.8%) stddev-sans-first:1353.52 14:46:50 INFO - PROCESS | 4990 | Values: 344.8 351.4 352.8 355.8 357.7 5523.1 5568.6 5586.9 5589.9 5594.5 5435.0 5462.9 5527.5 5531.1 5539.2 3821.5 4145.0 4312.2 4317.6 4338.1 4274.4 4297.7 4311.5 4320.0 4348.4 4293.5 4303.8 4307.0 4335.7 4359.4 4097.8 4258.8 4301.6 4362.8 4365.1 3769.8 3772.0 3778.7 3786.8 3814.5 5402.7 5410.8 5428.3 5430.3 5431.2 4819.4 4926.5 4927.3 4933.0 4973.6 5712.2 5717.9 5719.6 5723.8 5758.9 4178.6 4198.8 4199.3 4201.6 4203.5 5628.9 5807.9 5849.6 5864.5 5873.8 4232.9 4236.3 4239.3 4239.9 4244.9 4323.3 4336.8 4344.5 4344.5 4345.5 4821.5 4859.8 4881.6 4934.7 4941.3 5689.0 5692.3 5701.7 5727.3 5757.5 1453.1 1473.2 1476.7 1477.6 1484.6 914.9 950.7 956.4 959.8 969.3 4894.9 4899.6 4906.4 4924.6 4929.4 5085.9 5165.4 5173.6 5178.2 5220.5 4605.8 4875.8 4899.9 4914.3 4932.0 2737.6 2740.7 2745.5 2775.5 2788.0 5273.9 5341.9 5342.4 5383.6 5401.6 5265.7 5308.2 5542.7 5655.8 5987.3 5910.9 6078.1 6082.1 6094.6 6099.5 5917.2 5931.2 5934.4 5949.2 5958.2 5499.2 5762.9 5856.7 5867.8 5929.9 6098.9 6112.6 6125.1 6126.8 6138.4 4082.5 4298.5 4325.3 4341.6 4342.4 5820.3 5826.5 5848.9 5857.8 5869.2 5722.1 5733.9 5747.3 5768.9 5771.5 5610.8 5613.6 5695.9 5718.8 5739.3 5624.0 5645.7 5674.5 5680.2 5688.1 5629.8 5742.3 5748.1 5794.2 5801.8 5606.5 5613.2 5621.6 5622.6 5655.0 14:46:50 INFO - PROCESS | 4990 | 14:46:50 INFO - PROCESS | 4990 | [#5] yui.html Cycles:180 Average:21602.49 Median:4828.03 stddev:31815.99 (659.0%) stddev-sans-first:31866.87 14:46:50 INFO - PROCESS | 4990 | Values: 792.6 827.6 872.3 880.2 887.5 16029.4 16788.7 16822.6 16895.6 16913.9 15638.1 15985.8 16004.9 16120.4 16121.4 728.0 852.0 870.8 876.4 876.6 858.0 859.1 859.7 860.6 860.8 919.3 922.7 924.1 924.6 925.6 915.2 917.3 917.9 918.0 918.1 678.6 679.5 680.1 680.5 680.6 14749.6 14774.8 14797.0 14797.7 14804.7 15575.8 15728.8 15774.9 16214.0 16549.4 12827.6 12982.9 13024.2 13222.7 13279.7 3971.4 3978.8 3999.2 4115.1 4121.2 79881.6 79885.0 83656.0 83823.6 84000.2 11306.3 11334.1 11451.5 11619.8 11661.3 22106.3 22231.0 22282.6 22282.8 22294.8 8696.2 8818.7 8874.3 8876.0 9362.4 4616.0 4756.6 4781.1 4905.2 4959.2 3860.2 3922.6 4010.9 4012.3 4015.5 659.6 661.8 725.1 748.3 762.9 615.9 748.6 756.8 760.6 765.1 9707.4 9779.8 9955.8 10060.2 10221.7 594.5 772.5 774.1 778.4 797.3 4451.0 4875.0 4882.3 4903.8 4921.7 118079.0 118263.0 121416.4 123106.0 123363.0 67906.7 100554.0 103155.0 103281.0 107665.0 93631.0 95479.0 95493.5 96297.5 96830.5 8752.3 8809.5 8967.9 9046.8 9374.9 56367.4 56571.7 56738.1 57980.0 58986.7 77794.2 78427.6 82499.0 83502.0 84625.0 11296.9 11633.9 11655.4 11730.9 11892.8 12497.4 12567.3 12881.9 12974.1 13055.6 12108.3 12192.8 12218.5 12327.1 12492.9 11746.2 11979.3 12017.3 12155.9 12483.1 11313.8 11393.8 12002.3 12200.4 12370.5 9841.2 9947.4 9970.5 9997.0 10115.6 10023.7 10238.4 10393.5 10400.3 10429.3 14:46:50 INFO - PROCESS | 4990 | -------- Summary: end -------- 14:46:50 INFO - PROCESS | 4990 | 14:46:51 INFO - TEST-INFO | 4990: exit 0 14:46:51 INFO - TEST-OK | dromaeo_css | took 1112824ms 14:46:51 INFO - TEST-START | kraken 14:46:51 INFO - operating with platform_type : linux_ 14:46:51 INFO - Initialising browser for kraken test... 14:46:51 INFO - TEST-INFO | started process 5178 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC35UWE/profile http://localhost:34246/getInfo.html) 14:46:55 INFO - PROCESS | 5178 | __metrics Screen width/height:1600/1200 14:46:55 INFO - PROCESS | 5178 | colorDepth:24 14:46:55 INFO - PROCESS | 5178 | Browser inner width/height: 1024/697 14:46:55 INFO - PROCESS | 5178 | __metrics 14:46:56 INFO - PROCESS | 5178 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:46:56 INFO - PROCESS | 5178 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:46:57 INFO - TEST-INFO | 5178: exit 0 14:46:57 INFO - Browser initialized. 14:46:57 INFO - Running cycle 1/1 for kraken test... 14:46:57 INFO - TEST-INFO | started process 5266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC35UWE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:09 INFO - PROCESS | 5266 | RSS: Main: 143392768 14:47:09 INFO - PROCESS | 5266 | 14:47:11 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-beat-detection) 14:47:12 INFO - PROCESS | 5266 | RSS: Main: 199450624 14:47:12 INFO - PROCESS | 5266 | 14:47:14 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-dft) 14:47:15 INFO - PROCESS | 5266 | RSS: Main: 183230464 14:47:15 INFO - PROCESS | 5266 | 14:47:18 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-fft) 14:47:18 INFO - PROCESS | 5266 | RSS: Main: 273772544 14:47:18 INFO - PROCESS | 5266 | 14:47:21 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-oscillator) 14:47:21 INFO - PROCESS | 5266 | RSS: Main: 180039680 14:47:21 INFO - PROCESS | 5266 | 14:47:23 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:47:24 INFO - PROCESS | 5266 | RSS: Main: 178606080 14:47:24 INFO - PROCESS | 5266 | 14:47:27 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34246/tests/kraken/driver.html?testName=imaging-darkroom) 14:47:27 INFO - PROCESS | 5266 | RSS: Main: 214261760 14:47:27 INFO - PROCESS | 5266 | 14:47:31 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34246/tests/kraken/driver.html?testName=imaging-desaturate) 14:47:31 INFO - PROCESS | 5266 | RSS: Main: 214339584 14:47:31 INFO - PROCESS | 5266 | 14:47:34 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34246/tests/kraken/driver.html?testName=json-parse-financial) 14:47:35 INFO - PROCESS | 5266 | RSS: Main: 214077440 14:47:35 INFO - PROCESS | 5266 | 14:47:36 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:47:37 INFO - PROCESS | 5266 | RSS: Main: 176017408 14:47:37 INFO - PROCESS | 5266 | 14:47:39 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:47:39 INFO - PROCESS | 5266 | RSS: Main: 182550528 14:47:39 INFO - PROCESS | 5266 | 14:47:41 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:47:42 INFO - PROCESS | 5266 | RSS: Main: 184733696 14:47:42 INFO - PROCESS | 5266 | 14:47:44 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:47:45 INFO - PROCESS | 5266 | RSS: Main: 179732480 14:47:45 INFO - PROCESS | 5266 | 14:47:47 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:47:48 INFO - PROCESS | 5266 | RSS: Main: 182472704 14:47:48 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34246/tests/kraken/driver.html?testName=ai-astar) 14:47:50 INFO - PROCESS | 5266 | RSS: Main: 234020864 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | __start_tp_report 14:47:50 INFO - PROCESS | 5266 | _x_x_mozilla_page_load 14:47:50 INFO - PROCESS | 5266 | _x_x_mozilla_page_load_details 14:47:50 INFO - PROCESS | 5266 | |i|pagename|runs| 14:47:50 INFO - PROCESS | 5266 | |0;ai-astar;111;111;119;111;110;110;111;109;110;109 14:47:50 INFO - PROCESS | 5266 | |1;audio-beat-detection;145;119;148;171;119;141;141;118;177;121 14:47:50 INFO - PROCESS | 5266 | |2;audio-dft;163;163;163;162;160;161;161;160;162;163 14:47:50 INFO - PROCESS | 5266 | |3;audio-fft;73;75;74;185;76;76;74;74;167;76 14:47:50 INFO - PROCESS | 5266 | |4;audio-oscillator;83;82;155;83;82;84;144;84;83;146 14:47:50 INFO - PROCESS | 5266 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;117;116 14:47:50 INFO - PROCESS | 5266 | |6;imaging-darkroom;146;146;145;145;147;146;146;146;147;145 14:47:50 INFO - PROCESS | 5266 | |7;imaging-desaturate;94;94;95;94;94;95;95;94;94;94 14:47:50 INFO - PROCESS | 5266 | |8;json-parse-financial;55;51;53;52;52;52;58;52;54;54 14:47:50 INFO - PROCESS | 5266 | |9;json-stringify-tinderbox;59;58;58;59;60;59;59;59;59;59 14:47:50 INFO - PROCESS | 5266 | |10;stanford-crypto-aes;75;72;71;71;79;73;72;73;82;73 14:47:50 INFO - PROCESS | 5266 | |11;stanford-crypto-ccm;121;116;118;117;113;117;116;115;162;120 14:47:50 INFO - PROCESS | 5266 | |12;stanford-crypto-pbkdf2;152;157;152;152;153;150;147;152;152;152 14:47:50 INFO - PROCESS | 5266 | |13;stanford-crypto-sha256-iterative;63;62;61;64;61;61;60;60;60;60 14:47:50 INFO - PROCESS | 5266 | __end_tp_report 14:47:50 INFO - PROCESS | 5266 | __start_cc_report 14:47:50 INFO - PROCESS | 5266 | _x_x_mozilla_cycle_collect,1660 14:47:50 INFO - PROCESS | 5266 | __end_cc_report 14:47:50 INFO - PROCESS | 5266 | __startTimestamp1474408070468__endTimestamp 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | ------- Summary: start ------- 14:47:50 INFO - PROCESS | 5266 | Number of tests: 14 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:2.88 (2.6%) stddev-sans-first:3.06 14:47:50 INFO - PROCESS | 5266 | Values: 111.0 111.0 119.0 111.0 110.0 110.0 111.0 109.0 110.0 109.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:143.00 stddev:21.47 (15.0%) stddev-sans-first:22.69 14:47:50 INFO - PROCESS | 5266 | Values: 145.0 119.0 148.0 171.0 119.0 141.0 141.0 118.0 177.0 121.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.50 stddev:1.23 (0.8%) stddev-sans-first:1.22 14:47:50 INFO - PROCESS | 5266 | Values: 163.0 163.0 163.0 162.0 160.0 161.0 161.0 160.0 162.0 163.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#3] audio-fft Cycles:10 Average:95.00 Median:74.50 stddev:42.91 (57.6%) stddev-sans-first:44.77 14:47:50 INFO - PROCESS | 5266 | Values: 73.0 75.0 74.0 185.0 76.0 76.0 74.0 74.0 167.0 76.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#4] audio-oscillator Cycles:10 Average:102.60 Median:83.00 stddev:31.69 (38.2%) stddev-sans-first:32.81 14:47:50 INFO - PROCESS | 5266 | Values: 83.0 82.0 155.0 83.0 82.0 84.0 144.0 84.0 83.0 146.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 14:47:50 INFO - PROCESS | 5266 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 14:47:50 INFO - PROCESS | 5266 | Values: 146.0 146.0 145.0 145.0 147.0 146.0 146.0 146.0 147.0 145.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:47:50 INFO - PROCESS | 5266 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:2.06 (3.8%) stddev-sans-first:2.09 14:47:50 INFO - PROCESS | 5266 | Values: 55.0 51.0 53.0 52.0 52.0 52.0 58.0 52.0 54.0 54.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 14:47:50 INFO - PROCESS | 5266 | Values: 59.0 58.0 58.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:73.00 stddev:3.63 (5.0%) stddev-sans-first:3.84 14:47:50 INFO - PROCESS | 5266 | Values: 75.0 72.0 71.0 71.0 79.0 73.0 72.0 73.0 82.0 73.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#11] stanford-crypto-ccm Cycles:10 Average:121.50 Median:117.50 stddev:14.42 (12.3%) stddev-sans-first:15.29 14:47:50 INFO - PROCESS | 5266 | Values: 121.0 116.0 118.0 117.0 113.0 117.0 116.0 115.0 162.0 120.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.00 stddev:2.47 (1.6%) stddev-sans-first:2.62 14:47:50 INFO - PROCESS | 5266 | Values: 152.0 157.0 152.0 152.0 153.0 150.0 147.0 152.0 152.0 152.0 14:47:50 INFO - PROCESS | 5266 | 14:47:50 INFO - PROCESS | 5266 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.40 (2.3%) stddev-sans-first:1.32 14:47:50 INFO - PROCESS | 5266 | Values: 63.0 62.0 61.0 64.0 61.0 61.0 60.0 60.0 60.0 60.0 14:47:50 INFO - PROCESS | 5266 | -------- Summary: end -------- 14:47:50 INFO - PROCESS | 5266 | 14:47:51 INFO - TEST-INFO | 5266: exit 0 14:47:51 INFO - TEST-OK | kraken | took 60070ms 14:47:51 INFO - SUITE-END | took 1172s 14:47:51 INFO - Completed test suite (00:19:33) 14:47:51 INFO - javascript benchmark 14:47:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3204.615446146463, 3208.1017315151757, 3234.191452136966, 3257.4950882613202, 3267.852946617402, 5590.301212348457, 5683.488486036256, 5692.943070569293, 5694.943050569493, 5707.9999999999945, 5635.943640563593, 5697.373288938224, 5708.914366284499, 5710.828675139742, 5712.657240565559, 4947.356843610343, 5151.150060240056, 5159.355080614923, 5218.112920803473, 5229.581633469314, 4969.080720066781, 5087.821926232583, 5131.3842338919385, 5138.332016837806, 5141.228815677641, 5100.056489549426, 5106.208537676647, 5117.590592752589, 5125.487451254882, 5176.430592634807, 5228.08508511009, 5239.345081864767, 5246.108161612535, 5248.212768084818, 5282.339707536577, 4965.90068198634, 5017.046761115413, 5067.873303167414, 5142.100132476839, 5153.304303918961, 5487.533559647435, 5491.890162196773, 5505.394406615269, 5506.531944784698, 5517.337919449652, 6034.064719968412, 6039.063945088518, 6041.456268935773, 6043.516518678495, 6046.062860256668, 5933.19901813254, 5957.434043765836, 5978.8505287367725, 6023.096535519663, 6056.394360563952, 5594.216809646653, 5639.238702775114, 5642.0, 5649.096144616841, 5679.403662615448, 5954.434328738763, 6092.817215483543, 6095.329513753483, 6104.664243466563, 6110.297315808739, 5877.5591830612875, 5894.2926848778425, 5913.172155898092, 5966.701664916737, 6005.039193728982, 6034.456898879122, 6039.124326972652, 6041.124037014608, 6042.879142417127, 6050.304215015241, 5835.241418615553, 5941.851453713692, 5991.910121348184, 5995.640261584319, 6014.789482368184, 5974.133750606138, 6020.909686354708, 6025.969870150621, 6078.574499784972, 6100.145979562864, 4639.350490931272, 4648.070385922829, 4648.535146485392, 4649.18639238132, 4680.789364478606, 3994.2610617035944, 4130.050088479667, 4144.79276036197, 4159.251334759711, 4174.666026717855, 5578.9163162552595, 5658.971705141448, 5694.829155125353, 5802.82591522255, 5810.970945145247, 6066.181065556182, 6086.391360863879, 6095.146679464879, 6100.176476175749, 6100.847478812976, 5741.253637027244, 5750.712464376765, 5752.108423194411, 5758.1362795581, 5758.280214973129, 5103.770330335144, 5204.609654275944, 5215.739213039333, 5230.476952304739, 5246.947530524722, 6327.145835312266, 6340.365963403623, 6343.555951083472, 6359.745610175633, 6361.745530178833, 6079.240094988127, 6085.421884920926, 6093.482054025458, 6121.938780612137, 6125.295591007091, 5989.221401217814, 6110.953680641605, 6119.685352982247, 6387.744490220339, 6397.104405383343, 5965.522758179249, 5975.462208401264, 5978.312494063064, 6006.0, 6016.7292471838, 6053.122297266959, 6064.636121832704, 6064.666443345482, 6066.636001839904, 6066.666333351708, 5714.685692286798, 5822.475977162076, 5853.64878107315, 5920.79277225295, 5930.318013428338, 5857.062869940789, 5897.469227769521, 5910.349861515316, 5913.46778789911, 5946.851328716643, 5808.970955145197, 5849.0, 5856.297244330537, 5856.824295271149, 5858.297004359504, 5702.173184888251, 5849.0, 5851.61964472308, 5901.616394934315, 5926.4666180044005, 5813.534917206529, 5815.79644712433, 5819.738111784895, 5830.912536312044, 5855.590108692521], "value": 5562.29147782164, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.9559783016272, 634.7461015593761, 648.8961766117422, 662.0730976632717, 670.1187937861713, 34658.480122798144, 44621.55378446214, 45856.77071614646, 45945.7702711486, 49698.0, 47551.28673069907, 47697.28454073192, 47891.760541197335, 47912.28131578029, 48047.0, 9212.585433655484, 9347.579358928831, 9348.205402540792, 9356.29827762917, 9368.859467107999, 8548.871766923501, 10877.510512026978, 11174.329540227573, 11181.944090279538, 11464.598739044135, 11342.489587968561, 11403.745587985357, 11424.143189260838, 11427.542898284059, 11431.714207144805, 11116.166287528427, 11174.161937854693, 11178.888211117866, 11182.217244792868, 11185.888141118567, 11067.446627668583, 11131.332120072822, 11135.387553684544, 11141.387223702694, 11147.609833655864, 44865.55134448646, 45088.3236751449, 45155.32267015997, 45165.322520162226, 45224.54775452236, 3855.306044911915, 4028.8589899353674, 4233.682473814445, 4277.550857159985, 4610.170169369512, 33912.49131263033, 33936.66063339385, 34407.311853763036, 34438.0, 34454.0, 4621.676482646216, 4672.883177920545, 4687.9531204687855, 4705.388299521074, 4729.763511824395, 352837.0, 353351.0, 353906.0, 354207.0, 354226.0, 61788.69105654533, 61917.380826192064, 62163.689181554706, 62190.68904655448, 62261.377386226464, 40113.0, 40764.0, 40907.7954610231, 42851.35722964158, 64630.67684661547, 15430.0, 15529.145896975775, 15555.22223888801, 15579.844201558066, 15588.844111558967, 10537.315074520286, 11323.37721425313, 11359.034482068952, 11366.318020918772, 11496.655100347003, 19646.0, 20289.49276268105, 20368.28710995108, 20380.184792608427, 20392.796072039386, 7594.81012974669, 7624.123225829101, 7685.884711729329, 7798.454108212371, 7814.726484573012, 1043.2592859070146, 1074.425182527344, 1099.312929419113, 1193.4689063366893, 1242.211195890616, 33126.503102453484, 33652.83173584165, 34031.0, 34225.31549368999, 34314.82842585771, 1178.3401295274673, 1201.501376928565, 1226.2336039975016, 1229.7110179107904, 1250.3623152192265, 16644.750328745078, 17594.82405175932, 17672.0, 17699.29202831898, 17737.29050837952, 604224.0, 611273.0, 611580.0, 611780.0, 611890.0, 64135.679321604024, 64596.677016614616, 64695.676521618036, 64754.352456475775, 64831.67584162049, 1303.1008604063165, 1365.1126767601138, 1402.705431859321, 1431.0698046270008, 1449.9855001450062, 31554.684453156093, 35406.11484712903, 35709.80680992554, 35774.8211258942, 35785.31386803569, 54672.72663636656, 55553.44446555483, 55584.44415555955, 55602.0, 55635.44364556303, 474513.0, 481577.0, 481695.0, 482063.0, 482228.0, 27255.182344529694, 29173.270668233465, 29468.852655736584, 30033.69966300309, 30525.01587396812, 50463.74768126136, 50688.23967640562, 50797.0, 50804.23793643025, 50814.49185508245, 43151.13697726091, 43180.13639727125, 43227.0, 44688.0, 47546.0, 44065.0, 44670.10659786851, 44994.0, 46278.53721462877, 46570.301445479025, 44380.21773185992, 44472.33291500695, 44671.10657786891, 45016.0, 45057.774711127546, 37947.0, 39838.0, 39873.20253594854, 40084.0, 40493.59506405016, 29285.41429171362, 29753.553696695, 29978.25054373571, 30007.249818754706, 30127.246818829706], "value": 21565.978401152086, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.9087200815819, 416.58965918570186, 421.87765547991086, 427.88447119277794, 432.7814453700881, 19460.41618751438, 20328.085236164352, 20990.89504552477, 21249.150033998623, 21409.785902140975, 19450.51373715658, 19929.800701992976, 20081.799182008177, 20144.294949676765, 20196.798032019713, 5396.406395296504, 5562.527185189264, 5605.691686957216, 5614.578906582003, 5614.831555053355, 5543.77824887004, 5614.410486898878, 5683.374828768853, 6005.549583781212, 6048.848778780522, 6015.879682406371, 6019.819405417845, 6020.066889632092, 6029.366916473751, 6031.24609423822, 5823.359430462646, 5986.940130598704, 5987.55093367997, 6033.5173186145, 6033.637981721067, 5442.6462279952075, 5454.699991500466, 5461.317335333083, 5462.126059830408, 5466.480684334982, 14458.349374278077, 14647.560573182822, 23385.947632356416, 23484.53030939372, 23927.16254931069, 2343.94140146496, 2433.7322894481595, 2441.719202291724, 2479.652848601197, 2488.7884529814987, 10392.688219353431, 10696.679099627023, 10714.517846696917, 10814.243002989793, 11435.714107147305, 1974.02285868496, 1978.5350442645968, 2002.7897070807646, 2030.9289174878809, 2064.632849233098, 89225.55387223023, 92256.53871730666, 92918.53540732253, 93751.53124234335, 95000.0, 11653.0, 11655.475503602189, 11719.648410547697, 11959.940200299117, 12100.152989290842, 16769.91615041917, 16880.240389182516, 16880.577985550215, 16905.32378704838, 16916.746248806277, 7475.364594009461, 7697.653605587762, 7769.572673503011, 7780.688772449039, 8010.719624813103, 7564.886526702104, 7602.049743782028, 7775.377969762406, 7778.766637000899, 7814.335781458526, 4951.0, 4979.3277907482025, 4989.725565093882, 5012.423571289275, 5082.796688132507, 2032.9898350508154, 2102.138123369411, 2108.9578208435746, 2116.9732679650356, 2125.7449106107365, 952.766572189804, 1004.5379125602159, 1020.8417695257099, 1022.2844009193594, 1023.7287118913493, 20112.0, 21018.291740034707, 21036.789632103788, 21234.362969110953, 21314.04086816066, 1022.6931920423843, 1034.3276870034538, 1039.3867618105203, 1068.1188019883627, 1075.6450371377427, 10528.210384221215, 11589.652310430532, 11624.535018599161, 11643.41782910851, 11646.184767066225, 504630.0, 532440.0, 535677.0, 537608.0, 567090.0, 71553.0, 90847.0, 100855.49572252092, 105891.4705426468, 107765.0, 1013.4172850611076, 1016.8525563793356, 1053.3574519543072, 1059.6662051453768, 1062.3997441445558, 18140.365087221882, 18528.44414667562, 18585.33147390059, 18725.90637046806, 19013.71479427782, 44231.55768442275, 45129.774351128035, 46277.30584041175, 46761.53238467708, 47242.29136563023, 59177.70411148089, 60415.0, 61639.38360616336, 67899.0, 69093.96359054517, 8797.164269394398, 9130.726078217664, 9171.312151588523, 9411.200047995722, 9443.0, 13226.272555009182, 13666.86333136656, 13877.583672489842, 13899.930500347433, 13998.790018149939, 12323.322217277777, 12340.3212823292, 12362.876371236172, 12364.938175309066, 12436.937815310866, 11050.33697978124, 11620.012298954684, 11740.941295293469, 11741.706457338294, 11851.940740296242, 11806.35065071395, 12001.0, 12251.816222756845, 12587.978885067701, 13012.154209976321, 9121.77195570116, 9179.082091790768, 9187.402818816756, 9345.953270233877, 9580.425174489554, 12043.819342710041, 12371.938140309241, 12466.065045121471, 12820.0, 12912.741745164856], "value": 9628.594460802256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.717890011448, 1502.7370210213214, 1643.4740882917463, 1779.5551112221945, 1830.4142674344216, 8967.596458159382, 9632.0, 9648.855267170999, 9651.662191823287, 9654.227661787058, 7961.6019199039965, 8185.631646575904, 8191.426600137993, 8386.706465273717, 9409.576569054392, 2317.8029867461287, 2427.987860060706, 2432.6715893354435, 2432.793212576933, 2433.4889673168655, 2475.2945410557904, 2487.404292458447, 2517.0812653381568, 2520.3573088862404, 2527.9241622751347, 2492.8379655322346, 2496.787773039294, 2510.472800711853, 2517.874106294687, 2527.519771243467, 2513.886875090607, 2521.760432758885, 2537.2261460254613, 2538.200466852945, 2544.3639090227443, 2436.281297017384, 2455.8649274289905, 2461.4707837814954, 2461.963070553943, 2463.408781892351, 9347.76630584234, 9368.20370268528, 9376.718698439057, 9387.249020078378, 9414.482203478807, 5484.890302193974, 5575.247341608912, 5623.212750214973, 5625.578081643893, 5626.774929002835, 3554.48459973305, 3666.77999320039, 3719.590845007047, 3758.229562939591, 3776.9433458498142, 1168.783775001619, 1171.2152857585438, 1171.619223752284, 1199.0886925936263, 1211.1582450197116, 13239.0, 13344.666383340396, 13422.798658020138, 13423.3959471824, 13453.730925381535, 4865.026994601094, 4936.0, 4995.750212489361, 5068.847934561969, 5115.87210319745, 11307.265027773168, 11315.83026254607, 11327.886721132683, 11331.716707082389, 11356.0, 5642.746076426571, 5661.0, 5670.518005969457, 5674.375818659944, 5679.943200568024, 4322.22200003996, 4357.651387888961, 5149.0, 5166.225066240094, 5205.089109405839, 4314.115606300701, 4378.562143785596, 4468.463784345834, 4472.597466228055, 4473.507914129443, 1364.7543442180304, 1388.9236092015037, 1412.4279666734783, 1430.5493769462794, 1440.4598275646633, 966.2656381150376, 1032.7573020340214, 1038.5430410619304, 1051.069803224152, 1075.2365820267698, 6415.166028416276, 6439.54923155384, 6471.0, 6604.960683573688, 6643.634600097041, 457.0447764172895, 458.3857630774778, 458.71101206239854, 470.94584122826313, 471.36130543114274, 4392.209402307615, 4420.292753159479, 4444.355568442557, 4532.093581283756, 4551.499335073139, 11511.884881151249, 11734.06127509797, 11809.23239989398, 11809.881901180877, 11838.0, 10637.9468102659, 11669.124815638861, 11680.240784348991, 11685.824712629317, 11692.47383867728, 8585.957070214734, 8872.201501864864, 8954.552272386354, 8968.865467018, 8979.640814367353, 6385.808425747235, 6450.677466126769, 6554.279029306867, 6646.867062658816, 6750.35871592198, 10704.197185210984, 10884.40135792539, 10936.343819370863, 11052.226344155832, 11068.059214966817, 9781.804363912825, 10691.625793097204, 11217.607383741857, 11264.324140551593, 11362.602308919148, 8254.793630159294, 8413.70552030676, 8674.956625217086, 8682.826343473222, 8683.131686831266, 9133.0, 9314.44113353201, 9375.484348360906, 9393.0, 9402.200812930703, 8107.1892810718455, 8129.349652027942, 8311.625976830936, 8462.492250464991, 8552.572371381282, 7918.406119541172, 8042.517448953082, 8047.356211502949, 8125.228103330411, 8288.627011784363, 7431.516951398142, 7487.962560187164, 7604.8479030417975, 7605.505642133465, 7612.7716168515035, 5467.863303417287, 5510.531604813636, 5527.8894422112135, 5571.331440227036, 5659.066254068197, 5334.093204155225, 5398.568114550748, 5486.396496385315, 5505.587080968863, 5598.3282006159525], "value": 4874.833250063688, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.8327561132851, 351.37455329513466, 352.8447483107434, 355.79008385052816, 357.71204180634595, 5523.143912693529, 5568.607848037986, 5586.888262234758, 5589.916151257735, 5594.468525490082, 5435.0162190800365, 5462.934727728095, 5527.53015993641, 5531.1426728857095, 5539.224508568794, 3821.464994900702, 4144.958550414487, 4312.1806856697285, 4317.618362124122, 4338.071801533968, 4274.397101087093, 4297.699161058727, 4311.482622085354, 4319.956800431987, 4348.434703488542, 4293.454356431682, 4303.7848107594655, 4306.956930430687, 4335.6965012449145, 4359.433273674418, 4097.815598298054, 4258.787060646986, 4301.5698430157045, 4362.760048197348, 4365.105153443552, 3769.792661403622, 3772.020104117744, 3778.678812300958, 3786.772793632391, 3814.5422549293985, 5402.702851343174, 5410.756515956791, 5428.294321738188, 5430.262316058863, 5431.212474191259, 4819.44576373718, 4926.45808961014, 4927.285543596195, 4932.950670493285, 4973.577245934064, 5712.200291959129, 5717.857053573653, 5719.571032172604, 5723.7963384097, 5758.942410575883, 4178.603032711918, 4198.8243291878325, 4199.328107502785, 4201.642860356873, 4203.5376108628025, 5628.887422251573, 5807.941920580783, 5849.61977471463, 5864.501517371071, 5873.765049398019, 4232.851850185229, 4236.2586547354085, 4239.258129827268, 4239.915201695949, 4244.85143019999, 4323.286657701507, 4336.761478118734, 4344.45694288214, 4344.522102568715, 4345.543717909574, 4821.493743156988, 4859.829905953275, 4881.561941969789, 4934.7285899275885, 4941.331680892278, 5688.9715551422805, 5692.345380281321, 5701.686407247641, 5727.255456790674, 5757.539396848242, 1453.128123126137, 1473.2265560580568, 1476.7194233095677, 1477.637978695205, 1484.5991582272725, 914.9451032938046, 950.740269143374, 956.3974695941512, 959.8464245720651, 969.2827307792184, 4894.902101957941, 4899.583535399531, 4906.411230652345, 4924.63065270099, 4929.408470983433, 5085.8728531787, 5165.380154381498, 5173.560247379015, 5178.249153872667, 5220.451852555427, 4605.769711514411, 4875.829345972874, 4899.853004409873, 4914.311996320518, 4932.0, 2737.630419893329, 2740.657417822772, 2745.4646343962736, 2775.5428400090113, 2787.958180627292, 5273.947260527346, 5341.86645333862, 5342.412334643186, 5383.623146379794, 5401.648892821954, 5265.7103859287345, 5308.23030660552, 5542.722863856791, 5655.802046928337, 5987.341392446828, 5910.852228694317, 6078.0882867569335, 6082.057281121433, 6094.634321940698, 6099.512039036955, 5917.201177841109, 5931.1992880960515, 5934.436228558453, 5949.167116603592, 5958.165856779968, 5499.202615620793, 5762.913556296743, 5856.736446859816, 5867.823965281048, 5929.851753706192, 6098.908516372347, 6112.602680825732, 6125.081237814185, 6126.846828829315, 6138.416850399383, 4082.5100987880455, 4298.527162012114, 4325.307950727868, 4341.60925516705, 4342.435483387139, 5820.283016510224, 5826.533877289722, 5848.912266315923, 5857.794977175949, 5869.178315035983, 5722.054501469521, 5733.913991290051, 5747.252857128547, 5768.913466297925, 5771.4517120875125, 5610.775568977195, 5613.607047506635, 5695.943040569541, 5718.771249149988, 5739.253896993364, 5623.971880140573, 5645.71771411436, 5674.4892959633835, 5680.194269839135, 5688.061469857427, 5629.802956896653, 5742.339630942495, 5748.137779333133, 5794.18881356602, 5801.767929282782, 5606.542299002207, 5613.24221230145, 5621.634593751393, 5622.5501959842295, 5654.971725141348], "value": 4310.726440110891, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.5522080024786, 827.5655280977483, 872.2803686958259, 880.225401646551, 887.5473508510665, 16029.438969636065, 16788.664226715475, 16822.57943551412, 16895.577610559725, 16913.91543042286, 15638.13990230537, 15985.760213596805, 16004.919975400107, 16120.355185792583, 16121.435749748762, 728.0353531570677, 852.045708806138, 870.7692461497708, 876.3996662286354, 876.5617191404298, 857.9656813727444, 859.1365677494109, 859.6647307550061, 860.6342304520585, 860.8149247911698, 919.31511024287, 922.6816748221886, 924.0574613893824, 924.5839372282467, 925.6158694141923, 915.217489046862, 917.289100946765, 917.8577320515365, 918.0314767919842, 918.0773322810566, 678.6369292428527, 679.4564348521212, 680.0547239337276, 680.4760334542375, 680.5814424129175, 14749.557513274618, 14774.778378324334, 14797.038192517559, 14797.704045919128, 14804.70390592193, 15575.766363504557, 15728.842711572966, 15774.921125394414, 16214.027158370418, 16549.427804358565, 12827.615171544869, 12982.870171298355, 13024.153430027018, 13222.735545289137, 13279.734405311936, 3971.4439978403047, 3978.8209530571194, 3999.1801680655394, 4115.053537686349, 4121.196366708493, 79881.60059199667, 79885.0, 83656.0, 83823.5808820952, 84000.15999839985, 11306.265092768943, 11334.129868571757, 11451.541938322374, 11619.825702614467, 11661.300321980709, 22106.33680989541, 22231.0, 22282.554348913254, 22282.777172228394, 22294.77705222927, 8696.21734043939, 8818.735437936872, 8874.334424918157, 8876.0, 9362.391444556082, 4615.976920115445, 4756.6432517561325, 4781.115493633689, 4905.2151655734915, 4959.156943319645, 3860.1700634363747, 3922.607739226112, 4010.8796736097966, 4012.297847876668, 4015.5181378234797, 659.5646873063755, 661.8411581220472, 725.0863911471583, 748.3078152708723, 762.8931949527071, 615.8583525789094, 748.5882764479624, 756.8032311599023, 760.5892817878283, 765.1239330966013, 9707.417554946725, 9779.755506112404, 9955.800883982425, 10060.23765677007, 10221.693349199535, 594.4560726934893, 772.5326177662433, 774.0788461730523, 778.4200770426069, 797.3461761355633, 4450.977745111319, 4874.95125048745, 4882.316475693406, 4903.754812259444, 4921.680090794087, 118079.0, 118263.0, 121416.39291803485, 123106.0, 123363.0, 67906.66046669734, 100554.0, 103155.0, 103281.0, 107665.0, 93631.0, 95479.0, 95493.5225323855, 96297.5185124084, 96830.5158474203, 8752.304487067271, 8809.471431714117, 8967.865482017905, 9046.773830654023, 9374.906250937676, 56367.43632563786, 56571.71714141403, 56738.148927765295, 57980.0, 58986.705066474395, 77794.2220577787, 78427.60786196032, 82499.0, 83502.0, 84625.0, 11296.943515282372, 11633.88366116328, 11655.358955257543, 11730.94134529356, 11892.762144756884, 12497.37513124322, 12567.308798016196, 12881.935590321988, 12974.091813573337, 13055.608331750063, 12108.273503589813, 12192.75614487723, 12218.450169742206, 12327.137100402886, 12492.875071249535, 11746.17776755619, 11979.28124312544, 12017.278963262232, 12155.878441215475, 12483.12618116723, 11313.830292545454, 11393.829092563785, 12002.339871307162, 12200.389980501117, 12370.505179792708, 9841.212702983887, 9947.353422027545, 9970.451625160686, 9997.0, 10115.645952391631, 10023.69928902134, 10238.385696858213, 10393.532291047073, 10400.27198096126, 10429.322094063862], "value": 7738.654880318425, "unit": "score"}], "name": "dromaeo_css", "value": 7567.766823015168, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 119.0, 111.0, 110.0, 110.0, 111.0, 109.0, 110.0, 109.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 148.0, 171.0, 119.0, 141.0, 141.0, 118.0, 177.0, 121.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 160.0, 161.0, 161.0, 160.0, 162.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 185.0, 76.0, 76.0, 74.0, 74.0, 167.0, 76.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 155.0, 83.0, 82.0, 84.0, 144.0, 84.0, 83.0, 146.0], "value": 102.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 53.0, 52.0, 52.0, 52.0, 58.0, 52.0, 54.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 71.0, 71.0, 79.0, 73.0, 72.0, 73.0, 82.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 116.0, 118.0, 117.0, 113.0, 117.0, 116.0, 115.0, 162.0, 120.0], "value": 121.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 157.0, 152.0, 152.0, 153.0, 150.0, 147.0, 152.0, 152.0, 152.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 64.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1488.3, "alertThreshold": 2.0}]} 14:47:51 INFO - Return code: 0 14:47:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:47:51 INFO - # TBPL SUCCESS # 14:47:51 INFO - Running post-action listener: _resource_record_post_action 14:47:51 INFO - [mozharness: 2016-09-20 21:47:51.788852Z] Finished run-tests step (success) 14:47:51 INFO - Running post-run listener: _resource_record_post_run 14:47:51 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 470880256; Read time: 0; Write time: 386776 14:47:51 INFO - TinderboxPrint: CPU usage
12.9% 14:47:51 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:47:51 INFO - TinderboxPrint: I/O write bytes / time
470,880,256 / 386,776 14:47:51 INFO - TinderboxPrint: CPU idle
8,248.9 (87.0%) 14:47:51 INFO - TinderboxPrint: CPU user
1,182.1 (12.5%) 14:47:51 INFO - TinderboxPrint: Swap in / out
0 / 0 14:47:51 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 243478528; Read time: 0; Write time: 271976 14:47:51 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 227401728; Read time: 0; Write time: 114800 14:47:52 INFO - Running post-run listener: _upload_blobber_files 14:47:52 INFO - Blob upload gear active. 14:47:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:47:52 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:47:52 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:47:52 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:47:52 INFO - (blobuploader) - INFO - Open directory for files ... 14:47:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:47:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:47:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:47:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:47:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:47:53 INFO - (blobuploader) - INFO - Done attempting. 14:47:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:47:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:47:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:47:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:47:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:47:54 INFO - (blobuploader) - INFO - Done attempting. 14:47:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:47:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:47:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:47:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:47:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:47:57 INFO - (blobuploader) - INFO - Done attempting. 14:47:57 INFO - (blobuploader) - INFO - Iteration through files over. 14:47:57 INFO - Return code: 0 14:47:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:47:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:47:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/85af32d24e58ed07d9bf4da308ca3bb692ffe562b8c219807b56cc0f07742f6f8dea3648482f3800f7c24037c60a9cecdb301721f3bf565dd65dddbc7282d9b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2cad83815838d0a64ee6e68b0c61c8151ee9add5d278d15581a04af637184d3427fdc5a244de983b8a6c03304b8d9958f23f59356dce1746876ef3e04776f55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6862db2717d78a2850d3fc741f0ffaed881bcfd05ee9d981c5ce14162d13c949eba6e1c621563bc1bdb038c3fbd0935265e6e65ce776c5e04b4c3770bcd57641"} 14:47:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:47:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:47:57 INFO - Contents: 14:47:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/85af32d24e58ed07d9bf4da308ca3bb692ffe562b8c219807b56cc0f07742f6f8dea3648482f3800f7c24037c60a9cecdb301721f3bf565dd65dddbc7282d9b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2cad83815838d0a64ee6e68b0c61c8151ee9add5d278d15581a04af637184d3427fdc5a244de983b8a6c03304b8d9958f23f59356dce1746876ef3e04776f55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6862db2717d78a2850d3fc741f0ffaed881bcfd05ee9d981c5ce14162d13c949eba6e1c621563bc1bdb038c3fbd0935265e6e65ce776c5e04b4c3770bcd57641"} 14:47:57 INFO - Running post-run listener: copy_logs_to_upload_dir 14:47:57 INFO - Copying logs to upload dir... 14:47:57 INFO - mkdir: /builds/slave/test/build/upload/logs 14:47:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1220.409183 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 20 secs) (at 2016-09-20 14:47:57.708401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:47:57.712573) ========= 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=7de08804244eb36560b44971000001c3-1474406854.980459-2058220414 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/85af32d24e58ed07d9bf4da308ca3bb692ffe562b8c219807b56cc0f07742f6f8dea3648482f3800f7c24037c60a9cecdb301721f3bf565dd65dddbc7282d9b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2cad83815838d0a64ee6e68b0c61c8151ee9add5d278d15581a04af637184d3427fdc5a244de983b8a6c03304b8d9958f23f59356dce1746876ef3e04776f55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6862db2717d78a2850d3fc741f0ffaed881bcfd05ee9d981c5ce14162d13c949eba6e1c621563bc1bdb038c3fbd0935265e6e65ce776c5e04b4c3770bcd57641"} 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.011335 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474388716/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/85af32d24e58ed07d9bf4da308ca3bb692ffe562b8c219807b56cc0f07742f6f8dea3648482f3800f7c24037c60a9cecdb301721f3bf565dd65dddbc7282d9b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2cad83815838d0a64ee6e68b0c61c8151ee9add5d278d15581a04af637184d3427fdc5a244de983b8a6c03304b8d9958f23f59356dce1746876ef3e04776f55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6862db2717d78a2850d3fc741f0ffaed881bcfd05ee9d981c5ce14162d13c949eba6e1c621563bc1bdb038c3fbd0935265e6e65ce776c5e04b4c3770bcd57641"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:47:57.739099) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:47:57.739493) ========= 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=7de08804244eb36560b44971000001c3-1474406854.980459-2058220414 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004377 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:47:57.824221) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:47:57.824620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:47:57.824963) ========= ========= Total master_lag: 0.19 =========