builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1469579685.9 results: success (0) buildid: 20160726173350 builduid: cd64b1a887bc421ba38bae396962d59e revision: bac182c6a35717e7e3d63f837713262da8726b71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:45.900177) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:45.900731) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:45.901035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:45.916039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:45.916451) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 17:34:46-- 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% 41.2M=0s 2016-07-26 17:34:46 (41.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079636 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.011895) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.012431) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018921 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.062984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.063392) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bac182c6a35717e7e3d63f837713262da8726b71 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev bac182c6a35717e7e3d63f837713262da8726b71 --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=5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 17:34:46,928 truncating revision to first 12 chars 2016-07-26 17:34:46,928 Setting DEBUG logging. 2016-07-26 17:34:46,928 attempt 1/10 2016-07-26 17:34:46,928 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bac182c6a357?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 17:34:47,496 unpacking tar archive at: fx-team-bac182c6a357/testing/mozharness/ program finished with exit code 0 elapsedTime=0.889377 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.967171) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.967698) ========= script_repo_revision: bac182c6a35717e7e3d63f837713262da8726b71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.968191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.968521) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 17:34:46.979918) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-07-26 17:34:46.980310) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:34:47 INFO - MultiFileLogger online at 20160726 17:34:47 in /builds/slave/test 17:34:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:34:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:34:47 INFO - {'append_to_log': False, 17:34:47 INFO - 'base_work_dir': '/builds/slave/test', 17:34:47 INFO - 'blob_upload_branch': 'Fx-Team', 17:34:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:34:47 INFO - 'branch': 'Fx-Team', 17:34:47 INFO - 'buildbot_json_path': 'buildprops.json', 17:34:47 INFO - 'config_files': ('talos/linux_config.py',), 17:34:47 INFO - 'default_actions': ('clobber', 17:34:47 INFO - 'read-buildbot-config', 17:34:47 INFO - 'download-and-extract', 17:34:47 INFO - 'populate-webroot', 17:34:47 INFO - 'create-virtualenv', 17:34:47 INFO - 'install', 17:34:47 INFO - 'run-tests'), 17:34:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:34:47 INFO - 'download_minidump_stackwalk': True, 17:34:47 INFO - 'download_symbols': 'ondemand', 17:34:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:34:47 INFO - 'tooltool.py': '/tools/tooltool.py', 17:34:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:34:47 INFO - '/tools/misc-python/virtualenv.py')}, 17:34:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:34:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:34:47 INFO - 'installer_path': 'installer.exe', 17:34:47 INFO - 'log_level': 'info', 17:34:47 INFO - 'log_name': 'talos', 17:34:47 INFO - 'log_to_console': True, 17:34:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:34:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:34:47 INFO - 'opt_config_files': (), 17:34:47 INFO - 'pip_index': False, 17:34:47 INFO - 'sps_profile': False, 17:34:47 INFO - 'sps_profile_interval': 0, 17:34:47 INFO - 'suite': 'dromaeojs', 17:34:47 INFO - 'system_bits': '32', 17:34:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:34:47 INFO - 'title': 'talos-linux64-ix-051', 17:34:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:34:47 INFO - 'use_talos_json': True, 17:34:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:34:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:34:47 INFO - 'work_dir': 'build'} 17:34:47 INFO - [mozharness: 2016-07-27 00:34:47.938822Z] Running clobber step. 17:34:47 INFO - Running pre-action listener: _resource_record_pre_action 17:34:47 INFO - Running main action method: clobber 17:34:47 INFO - rmtree: /builds/slave/test/build 17:34:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:34:48 INFO - Running post-action listener: _resource_record_post_action 17:34:48 INFO - [mozharness: 2016-07-27 00:34:48.955826Z] Finished clobber step (success) 17:34:48 INFO - [mozharness: 2016-07-27 00:34:48.955927Z] Running read-buildbot-config step. 17:34:48 INFO - Running pre-action listener: _resource_record_pre_action 17:34:48 INFO - Running main action method: read_buildbot_config 17:34:48 INFO - Using buildbot properties: 17:34:48 INFO - { 17:34:48 INFO - "project": "", 17:34:48 INFO - "product": "firefox", 17:34:48 INFO - "script_repo_revision": "production", 17:34:48 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:34:48 INFO - "repository": "", 17:34:48 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:34:48 INFO - "buildid": "20160726173350", 17:34:48 INFO - "stage_platform": "linux64-pgo", 17:34:48 INFO - "basedir": "/builds/slave/test", 17:34:48 INFO - "buildnumber": 1367, 17:34:48 INFO - "platform": "ubuntu64_hw", 17:34:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:34:48 INFO - "slavebuilddir": "test", 17:34:48 INFO - "branch": "fx-team", 17:34:48 INFO - "revision": "bac182c6a35717e7e3d63f837713262da8726b71", 17:34:48 INFO - "slavename": "talos-linux64-ix-051", 17:34:48 INFO - "builduid": "cd64b1a887bc421ba38bae396962d59e", 17:34:48 INFO - "repo_path": "integration/fx-team" 17:34:48 INFO - } 17:34:48 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 17:34:48 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.test_packages.json. 17:34:48 INFO - Running post-action listener: _resource_record_post_action 17:34:48 INFO - [mozharness: 2016-07-27 00:34:48.958746Z] Finished read-buildbot-config step (success) 17:34:48 INFO - [mozharness: 2016-07-27 00:34:48.958836Z] Running download-and-extract step. 17:34:48 INFO - Running pre-action listener: _resource_record_pre_action 17:34:48 INFO - Running main action method: download_and_extract 17:34:48 INFO - mkdir: /builds/slave/test/build/tests 17:34: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')]} 17:34:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:34:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:34:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:34:48 INFO - Downloaded 1361 bytes. 17:34:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:34:48 INFO - Using the following test package requirements: 17:34:48 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 17:34:48 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:34:48 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:34:48 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'jsshell-linux-x86_64.zip'], 17:34:48 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:34:48 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 17:34:48 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:34:48 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:34:48 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:34:48 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:34:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:34:48 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:34: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')]} 17:34:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:34:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:34:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:34:49 INFO - Downloaded 24313303 bytes. 17:34:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:34:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:34:50 INFO - Return code: 0 17:34:50 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:34: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')]} 17:34:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:34:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:34:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:34:51 INFO - Downloaded 24313303 bytes. 17:34:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:34:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:34:52 INFO - Return code: 0 17:34:52 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')]} 17:34:52 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 17:34:52 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 17:34:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:34:52 INFO - Downloaded 14413725 bytes. 17:34:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:34:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:34:53 INFO - Return code: 0 17:34: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')]} 17:34:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:34:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:34:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:34:53 INFO - Downloaded 60919304 bytes. 17:34:53 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:34:53 INFO - mkdir: /builds/slave/test/properties 17:34:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:34:53 INFO - Writing to file /builds/slave/test/properties/build_url 17:34:53 INFO - Contents: 17:34:53 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:34:54 INFO - Running post-action listener: _resource_record_post_action 17:34:54 INFO - Running post-action listener: set_extra_try_arguments 17:34:54 INFO - [mozharness: 2016-07-27 00:34:54.070912Z] Finished download-and-extract step (success) 17:34:54 INFO - [mozharness: 2016-07-27 00:34:54.071146Z] Running populate-webroot step. 17:34:54 INFO - Running pre-action listener: _resource_record_pre_action 17:34:54 INFO - Running main action method: populate_webroot 17:34:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:34:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:34:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:34:54 INFO - '--tppagecycles', 17:34:54 INFO - '7'], 17:34:54 INFO - 'tests': ['tsvgm']}}, 17:34:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:34:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:34:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:34:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:34:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['damp', 'tps']}, 17:34:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'tests': ['damp', 'tps']}, 17:34:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['dromaeo_dom']}, 17:34:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:34:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['basic_compositor_video']}, 17:34:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:34:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['a11yr', 17:34:54 INFO - 'ts_paint', 17:34:54 INFO - 'tpaint', 17:34:54 INFO - 'sessionrestore', 17:34:54 INFO - 'sessionrestore_no_auto_restore', 17:34:54 INFO - 'tabpaint']}, 17:34:54 INFO - 'other-e10s': {'tests': ['a11yr', 17:34:54 INFO - 'ts_paint', 17:34:54 INFO - 'tpaint', 17:34:54 INFO - 'sessionrestore', 17:34:54 INFO - 'sessionrestore_no_auto_restore', 17:34:54 INFO - 'tabpaint']}, 17:34:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:34:54 INFO - 'ts_paint', 17:34:54 INFO - 'tpaint', 17:34:54 INFO - 'sessionrestore', 17:34:54 INFO - 'sessionrestore_no_auto_restore', 17:34:54 INFO - 'tabpaint']}, 17:34:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:34:54 INFO - 'ts_paint', 17:34:54 INFO - 'tpaint', 17:34:54 INFO - 'sessionrestore', 17:34:54 INFO - 'sessionrestore_no_auto_restore', 17:34:54 INFO - 'tabpaint']}, 17:34:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['a11yr', 17:34:54 INFO - 'ts_paint', 17:34:54 INFO - 'tpaint', 17:34:54 INFO - 'sessionrestore', 17:34:54 INFO - 'sessionrestore_no_auto_restore', 17:34:54 INFO - 'tabpaint']}, 17:34:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['a11yr', 17:34:54 INFO - 'ts_paint', 17:34:54 INFO - 'tpaint', 17:34:54 INFO - 'sessionrestore', 17:34:54 INFO - 'sessionrestore_no_auto_restore', 17:34:54 INFO - 'tabpaint']}, 17:34:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['tsvgx', 17:34:54 INFO - 'tsvgr_opacity', 17:34:54 INFO - 'tart', 17:34:54 INFO - 'tscrollx', 17:34:54 INFO - 'cart']}, 17:34:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:34:54 INFO - 'tsvgr_opacity', 17:34:54 INFO - 'tart', 17:34:54 INFO - 'tscrollx', 17:34:54 INFO - 'cart']}, 17:34:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'talos_options': ['--disable-e10s'], 17:34:54 INFO - 'tests': ['tp5o']}, 17:34:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'tests': ['tp5o']}, 17:34:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'talos_options': ['--disable-e10s', 17:34:54 INFO - '--xperf_path', 17:34:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:54 INFO - 'tests': ['tp5n']}, 17:34:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:54 INFO - 'talos_options': ['--xperf_path', 17:34:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:54 INFO - 'tests': ['tp5n']}}, 17:34:54 INFO - 'talos.zip': {'path': '', 17:34:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:34:54 INFO - Running post-action listener: _resource_record_post_action 17:34:54 INFO - [mozharness: 2016-07-27 00:34:54.091100Z] Finished populate-webroot step (success) 17:34:54 INFO - [mozharness: 2016-07-27 00:34:54.091189Z] Running create-virtualenv step. 17:34:54 INFO - Running pre-action listener: _resource_record_pre_action 17:34:54 INFO - Running main action method: create_virtualenv 17:34:54 INFO - Creating virtualenv /builds/slave/test/build/venv 17:34:54 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 17:34:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:34:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:34:54 INFO - Using real prefix '/usr' 17:34:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:34:54 INFO - Installing distribute.............................................................................................................................................................................................done. 17:34:56 INFO - Installing pip.................done. 17:34:56 INFO - Return code: 0 17:34:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:56 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:34:56 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 17:34:56 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 17:34:56 INFO - Using env: {'DISPLAY': ':0', 17:34:56 INFO - 'HOME': '/home/cltbld', 17:34:56 INFO - 'LANG': 'en_US.UTF-8', 17:34:56 INFO - 'LANGUAGE': 'en_US:en', 17:34:56 INFO - 'LOGNAME': 'cltbld', 17:34:56 INFO - 'MAIL': '/var/mail/cltbld', 17:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:56 INFO - 'MOZ_NO_REMOTE': '1', 17:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:34:56 INFO - 'NO_EM_RESTART': '1', 17:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:56 INFO - 'PWD': '/builds/slave/test', 17:34:56 INFO - 'SHELL': '/bin/bash', 17:34:56 INFO - 'SHLVL': '1', 17:34:56 INFO - 'TERM': 'linux', 17:34:56 INFO - 'TMOUT': '86400', 17:34:56 INFO - 'USER': 'cltbld', 17:34:56 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:56 INFO - '_': '/tools/buildbot/bin/python'} 17:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:56 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 17:34:56 INFO - Cleaning up... 17:34:56 INFO - Return code: 0 17:34:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:56 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:34:56 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 17:34:56 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 17:34:56 INFO - Using env: {'DISPLAY': ':0', 17:34:56 INFO - 'HOME': '/home/cltbld', 17:34:56 INFO - 'LANG': 'en_US.UTF-8', 17:34:56 INFO - 'LANGUAGE': 'en_US:en', 17:34:56 INFO - 'LOGNAME': 'cltbld', 17:34:56 INFO - 'MAIL': '/var/mail/cltbld', 17:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:56 INFO - 'MOZ_NO_REMOTE': '1', 17:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:34:56 INFO - 'NO_EM_RESTART': '1', 17:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:56 INFO - 'PWD': '/builds/slave/test', 17:34:56 INFO - 'SHELL': '/bin/bash', 17:34:56 INFO - 'SHLVL': '1', 17:34:56 INFO - 'TERM': 'linux', 17:34:56 INFO - 'TMOUT': '86400', 17:34:56 INFO - 'USER': 'cltbld', 17:34:56 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:56 INFO - '_': '/tools/buildbot/bin/python'} 17:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:57 INFO - Downloading/unpacking psutil>=3.1.1 17:34:57 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 17:34:57 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 17:34:57 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 17:34:57 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 17:34:57 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 17:34:57 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 17:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:34:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:59 INFO - Installing collected packages: psutil 17:34:59 INFO - Running setup.py install for psutil 17:34:59 INFO - building 'psutil._psutil_linux' extension 17:34:59 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 17:34:59 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 17:34:59 INFO - building 'psutil._psutil_posix' extension 17:34:59 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 17:34:59 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 17:34:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:59 INFO - Successfully installed psutil 17:34:59 INFO - Cleaning up... 17:34:59 INFO - Return code: 0 17:34:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:34:59 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')]} 17:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:59 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')]} 17:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:59 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:34:59 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 17:34:59 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 17:34:59 INFO - Using env: {'DISPLAY': ':0', 17:34:59 INFO - 'HOME': '/home/cltbld', 17:34:59 INFO - 'LANG': 'en_US.UTF-8', 17:34:59 INFO - 'LANGUAGE': 'en_US:en', 17:34:59 INFO - 'LOGNAME': 'cltbld', 17:34:59 INFO - 'MAIL': '/var/mail/cltbld', 17:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:59 INFO - 'MOZ_NO_REMOTE': '1', 17:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:34:59 INFO - 'NO_EM_RESTART': '1', 17:34:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:59 INFO - 'PWD': '/builds/slave/test', 17:34:59 INFO - 'SHELL': '/bin/bash', 17:34:59 INFO - 'SHLVL': '1', 17:34:59 INFO - 'TERM': 'linux', 17:34:59 INFO - 'TMOUT': '86400', 17:34:59 INFO - 'USER': 'cltbld', 17:34:59 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:59 INFO - '_': '/tools/buildbot/bin/python'} 17:34:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:34:59 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 17:34:59 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 17:34:59 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 17:34:59 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 17:34:59 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 17:34:59 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 17:35:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:35:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:35:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:35:01 INFO - Installing collected packages: mozsystemmonitor 17:35:01 INFO - Running setup.py install for mozsystemmonitor 17:35:01 INFO - Successfully installed mozsystemmonitor 17:35:01 INFO - Cleaning up... 17:35:02 INFO - Return code: 0 17:35:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:35:02 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')]} 17:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:02 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')]} 17:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:02 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35:02 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 17:35:02 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 17:35:02 INFO - Using env: {'DISPLAY': ':0', 17:35:02 INFO - 'HOME': '/home/cltbld', 17:35:02 INFO - 'LANG': 'en_US.UTF-8', 17:35:02 INFO - 'LANGUAGE': 'en_US:en', 17:35:02 INFO - 'LOGNAME': 'cltbld', 17:35:02 INFO - 'MAIL': '/var/mail/cltbld', 17:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:02 INFO - 'MOZ_NO_REMOTE': '1', 17:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:02 INFO - 'NO_EM_RESTART': '1', 17:35:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:02 INFO - 'PWD': '/builds/slave/test', 17:35:02 INFO - 'SHELL': '/bin/bash', 17:35:02 INFO - 'SHLVL': '1', 17:35:02 INFO - 'TERM': 'linux', 17:35:02 INFO - 'TMOUT': '86400', 17:35:02 INFO - 'USER': 'cltbld', 17:35:02 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:02 INFO - '_': '/tools/buildbot/bin/python'} 17:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:02 INFO - Downloading/unpacking jsonschema==2.5.1 17:35:02 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 17:35:02 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 17:35:02 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 17:35:02 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 17:35:02 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 17:35:02 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 17:35:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:35:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:04 INFO - Downloading functools32-3.2.3-2.tar.gz 17:35:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:35:04 INFO - warning: no files found matching '*.txt' 17:35:04 INFO - no previously-included directories found matching 'build' 17:35:04 INFO - no previously-included directories found matching 'dist' 17:35:04 INFO - no previously-included directories found matching '.git*' 17:35:04 INFO - Installing collected packages: jsonschema, functools32 17:35:04 INFO - Running setup.py install for functools32 17:35:04 INFO - warning: no files found matching '*.txt' 17:35:04 INFO - no previously-included directories found matching 'build' 17:35:04 INFO - no previously-included directories found matching 'dist' 17:35:04 INFO - no previously-included directories found matching '.git*' 17:35:04 INFO - Successfully installed jsonschema functools32 17:35:04 INFO - Cleaning up... 17:35:04 INFO - Return code: 0 17:35:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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', '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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:35: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 functools32==3.2.3-2 17:35:04 INFO - Using env: {'DISPLAY': ':0', 17:35:04 INFO - 'HOME': '/home/cltbld', 17:35:04 INFO - 'LANG': 'en_US.UTF-8', 17:35:04 INFO - 'LANGUAGE': 'en_US:en', 17:35:04 INFO - 'LOGNAME': 'cltbld', 17:35:04 INFO - 'MAIL': '/var/mail/cltbld', 17:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:04 INFO - 'MOZ_NO_REMOTE': '1', 17:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:04 INFO - 'NO_EM_RESTART': '1', 17:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:04 INFO - 'PWD': '/builds/slave/test', 17:35:04 INFO - 'SHELL': '/bin/bash', 17:35:04 INFO - 'SHLVL': '1', 17:35:04 INFO - 'TERM': 'linux', 17:35:04 INFO - 'TMOUT': '86400', 17:35:04 INFO - 'USER': 'cltbld', 17:35:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:04 INFO - '_': '/tools/buildbot/bin/python'} 17:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:35:04 INFO - Cleaning up... 17:35:04 INFO - Return code: 0 17:35:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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', '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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:35: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 blobuploader==1.2.4 17:35:04 INFO - Using env: {'DISPLAY': ':0', 17:35:04 INFO - 'HOME': '/home/cltbld', 17:35:04 INFO - 'LANG': 'en_US.UTF-8', 17:35:04 INFO - 'LANGUAGE': 'en_US:en', 17:35:04 INFO - 'LOGNAME': 'cltbld', 17:35:04 INFO - 'MAIL': '/var/mail/cltbld', 17:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:04 INFO - 'MOZ_NO_REMOTE': '1', 17:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:04 INFO - 'NO_EM_RESTART': '1', 17:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:04 INFO - 'PWD': '/builds/slave/test', 17:35:04 INFO - 'SHELL': '/bin/bash', 17:35:04 INFO - 'SHLVL': '1', 17:35:04 INFO - 'TERM': 'linux', 17:35:04 INFO - 'TMOUT': '86400', 17:35:04 INFO - 'USER': 'cltbld', 17:35:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:04 INFO - '_': '/tools/buildbot/bin/python'} 17:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:05 INFO - Downloading/unpacking blobuploader==1.2.4 17:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:06 INFO - Downloading blobuploader-1.2.4.tar.gz 17:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:35:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:35:06 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 17:35:06 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 17:35:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:35:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:07 INFO - Downloading docopt-0.6.1.tar.gz 17:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:35:07 INFO - Installing collected packages: blobuploader, requests, docopt 17:35:07 INFO - Running setup.py install for blobuploader 17:35:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:35:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:35:07 INFO - Running setup.py install for requests 17:35:08 INFO - Running setup.py install for docopt 17:35:08 INFO - Successfully installed blobuploader requests docopt 17:35:08 INFO - Cleaning up... 17:35:08 INFO - Return code: 0 17:35:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:08 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')]} 17:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:08 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')]} 17:35:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:08 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35:08 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 17:35:08 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 17:35:08 INFO - Using env: {'DISPLAY': ':0', 17:35:08 INFO - 'HOME': '/home/cltbld', 17:35:08 INFO - 'LANG': 'en_US.UTF-8', 17:35:08 INFO - 'LANGUAGE': 'en_US:en', 17:35:08 INFO - 'LOGNAME': 'cltbld', 17:35:08 INFO - 'MAIL': '/var/mail/cltbld', 17:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:08 INFO - 'MOZ_NO_REMOTE': '1', 17:35:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:08 INFO - 'NO_EM_RESTART': '1', 17:35:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:08 INFO - 'PWD': '/builds/slave/test', 17:35:08 INFO - 'SHELL': '/bin/bash', 17:35:08 INFO - 'SHLVL': '1', 17:35:08 INFO - 'TERM': 'linux', 17:35:08 INFO - 'TMOUT': '86400', 17:35:08 INFO - 'USER': 'cltbld', 17:35:08 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:08 INFO - '_': '/tools/buildbot/bin/python'} 17:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:08 INFO - Running setup.py (path:/tmp/pip-LGd0S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:08 INFO - Running setup.py (path:/tmp/pip-Q7y4qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:08 INFO - Running setup.py (path:/tmp/pip-1hvugB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:08 INFO - Running setup.py (path:/tmp/pip-EQSI1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:08 INFO - Running setup.py (path:/tmp/pip-cVpP6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:08 INFO - Running setup.py (path:/tmp/pip-9ZhOLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:08 INFO - Running setup.py (path:/tmp/pip-zXr7k6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:09 INFO - Running setup.py (path:/tmp/pip-njqask-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:09 INFO - Running setup.py (path:/tmp/pip-66t7Gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:09 INFO - Running setup.py (path:/tmp/pip-gPbZQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:09 INFO - Running setup.py (path:/tmp/pip-NfV9aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:09 INFO - Running setup.py (path:/tmp/pip-ffVYjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:09 INFO - Running setup.py (path:/tmp/pip-vaL1tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:09 INFO - Running setup.py (path:/tmp/pip-Ja5Zh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:09 INFO - Running setup.py (path:/tmp/pip-fTzrIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:09 INFO - Running setup.py (path:/tmp/pip-Eyo2oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:09 INFO - Running setup.py (path:/tmp/pip-jazwop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:35:09 INFO - Running setup.py install for manifestparser 17:35:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:35:09 INFO - Running setup.py install for mozcrash 17:35:09 INFO - Running setup.py install for mozdebug 17:35:10 INFO - Running setup.py install for mozdevice 17:35:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for mozfile 17:35:10 INFO - Running setup.py install for mozhttpd 17:35:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for mozinfo 17:35:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for mozInstall 17:35:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for mozleak 17:35:10 INFO - Running setup.py install for mozlog 17:35:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for moznetwork 17:35:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for mozprocess 17:35:10 INFO - Running setup.py install for mozprofile 17:35:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for mozrunner 17:35:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for mozscreenshot 17:35:11 INFO - Running setup.py install for moztest 17:35:11 INFO - Running setup.py install for mozversion 17:35:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:35:11 INFO - Cleaning up... 17:35:11 INFO - Return code: 0 17:35:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:11 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35:11 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 17:35:11 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 17:35:11 INFO - Using env: {'DISPLAY': ':0', 17:35:11 INFO - 'HOME': '/home/cltbld', 17:35:11 INFO - 'LANG': 'en_US.UTF-8', 17:35:11 INFO - 'LANGUAGE': 'en_US:en', 17:35:11 INFO - 'LOGNAME': 'cltbld', 17:35:11 INFO - 'MAIL': '/var/mail/cltbld', 17:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:11 INFO - 'MOZ_NO_REMOTE': '1', 17:35:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:11 INFO - 'NO_EM_RESTART': '1', 17:35:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:11 INFO - 'PWD': '/builds/slave/test', 17:35:11 INFO - 'SHELL': '/bin/bash', 17:35:11 INFO - 'SHLVL': '1', 17:35:11 INFO - 'TERM': 'linux', 17:35:11 INFO - 'TMOUT': '86400', 17:35:11 INFO - 'USER': 'cltbld', 17:35:11 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:11 INFO - '_': '/tools/buildbot/bin/python'} 17:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:11 INFO - Running setup.py (path:/tmp/pip-djj5IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:11 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)) 17:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:11 INFO - Running setup.py (path:/tmp/pip-sy2HHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:11 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)) 17:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:11 INFO - Running setup.py (path:/tmp/pip-Iqsmyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:11 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)) 17:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:11 INFO - Running setup.py (path:/tmp/pip-tzeLeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:12 INFO - Running setup.py (path:/tmp/pip-0X_MZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:12 INFO - Running setup.py (path:/tmp/pip-ryycur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:12 INFO - Running setup.py (path:/tmp/pip-QMlLyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:12 INFO - Running setup.py (path:/tmp/pip-RkvfnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:12 INFO - Running setup.py (path:/tmp/pip-Nncqjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:12 INFO - Running setup.py (path:/tmp/pip-hT43My-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:12 INFO - Running setup.py (path:/tmp/pip-Z3Gdcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:12 INFO - Running setup.py (path:/tmp/pip-LMmn4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:12 INFO - Running setup.py (path:/tmp/pip-T2A3yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:12 INFO - Running setup.py (path:/tmp/pip-JRPpWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:12 INFO - Running setup.py (path:/tmp/pip-GFFKxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:12 INFO - Running setup.py (path:/tmp/pip-QSCKnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:12 INFO - Running setup.py (path:/tmp/pip-d1St24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:12 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)) 17:35:12 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)) 17:35:12 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)) 17:35:12 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)) 17:35:12 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)) 17:35:12 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)) 17:35:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:14 INFO - Downloading blessings-1.6.tar.gz 17:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:35:14 INFO - Installing collected packages: blessings 17:35:14 INFO - Running setup.py install for blessings 17:35:15 INFO - Successfully installed blessings 17:35:15 INFO - Cleaning up... 17:35:15 INFO - Return code: 0 17:35:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:35:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:35:15 INFO - Reading from file tmpfile_stdout 17:35:15 INFO - Current package versions: 17:35:15 INFO - argparse == 1.2.1 17:35:15 INFO - blessings == 1.6 17:35:15 INFO - blobuploader == 1.2.4 17:35:15 INFO - docopt == 0.6.1 17:35:15 INFO - functools32 == 3.2.3-2 17:35:15 INFO - jsonschema == 2.5.1 17:35:15 INFO - manifestparser == 1.1 17:35:15 INFO - mozInstall == 1.12 17:35:15 INFO - mozcrash == 0.17 17:35:15 INFO - mozdebug == 0.1 17:35:15 INFO - mozdevice == 0.48 17:35:15 INFO - mozfile == 1.2 17:35:15 INFO - mozhttpd == 0.7 17:35:15 INFO - mozinfo == 0.9 17:35:15 INFO - mozleak == 0.1 17:35:15 INFO - mozlog == 3.3 17:35:15 INFO - moznetwork == 0.27 17:35:15 INFO - mozprocess == 0.23 17:35:15 INFO - mozprofile == 0.28 17:35:15 INFO - mozrunner == 6.12 17:35:15 INFO - mozscreenshot == 0.1 17:35:15 INFO - mozsystemmonitor == 0.3 17:35:15 INFO - moztest == 0.7 17:35:15 INFO - mozversion == 1.4 17:35:15 INFO - psutil == 3.1.1 17:35:15 INFO - requests == 1.2.3 17:35:15 INFO - wsgiref == 0.1.2 17:35:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:15 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')]} 17:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:15 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')]} 17:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:15 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35:15 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 17:35:15 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 17:35:15 INFO - Using env: {'DISPLAY': ':0', 17:35:15 INFO - 'HOME': '/home/cltbld', 17:35:15 INFO - 'LANG': 'en_US.UTF-8', 17:35:15 INFO - 'LANGUAGE': 'en_US:en', 17:35:15 INFO - 'LOGNAME': 'cltbld', 17:35:15 INFO - 'MAIL': '/var/mail/cltbld', 17:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:15 INFO - 'MOZ_NO_REMOTE': '1', 17:35:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:15 INFO - 'NO_EM_RESTART': '1', 17:35:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:15 INFO - 'PWD': '/builds/slave/test', 17:35:15 INFO - 'SHELL': '/bin/bash', 17:35:15 INFO - 'SHLVL': '1', 17:35:15 INFO - 'TERM': 'linux', 17:35:15 INFO - 'TMOUT': '86400', 17:35:15 INFO - 'USER': 'cltbld', 17:35:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:15 INFO - '_': '/tools/buildbot/bin/python'} 17:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 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)) 17:35:15 INFO - Cleaning up... 17:35:15 INFO - Return code: 0 17:35:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:35:15 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')]} 17:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:15 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')]} 17:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:15 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 0x19f4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', '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 17:35:15 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 17:35:15 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 17:35:15 INFO - Using env: {'DISPLAY': ':0', 17:35:15 INFO - 'HOME': '/home/cltbld', 17:35:15 INFO - 'LANG': 'en_US.UTF-8', 17:35:15 INFO - 'LANGUAGE': 'en_US:en', 17:35:15 INFO - 'LOGNAME': 'cltbld', 17:35:15 INFO - 'MAIL': '/var/mail/cltbld', 17:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:15 INFO - 'MOZ_NO_REMOTE': '1', 17:35:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:15 INFO - 'NO_EM_RESTART': '1', 17:35:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:15 INFO - 'PWD': '/builds/slave/test', 17:35:15 INFO - 'SHELL': '/bin/bash', 17:35:15 INFO - 'SHLVL': '1', 17:35:15 INFO - 'TERM': 'linux', 17:35:15 INFO - 'TMOUT': '86400', 17:35:15 INFO - 'USER': 'cltbld', 17:35:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:15 INFO - '_': '/tools/buildbot/bin/python'} 17:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:35:15 INFO - Cleaning up... 17:35:15 INFO - Return code: 0 17:35:15 INFO - Running post-action listener: _resource_record_post_action 17:35:15 INFO - Running post-action listener: _start_resource_monitoring 17:35:15 INFO - Starting resource monitoring. 17:35:15 INFO - [mozharness: 2016-07-27 00:35:15.840698Z] Finished create-virtualenv step (success) 17:35:15 INFO - [mozharness: 2016-07-27 00:35:15.841136Z] Running install step. 17:35:15 INFO - Running pre-action listener: _resource_record_pre_action 17:35:15 INFO - Running main action method: install 17:35:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:35:16 INFO - Reading from file tmpfile_stdout 17:35:16 INFO - Detecting whether we're running mozinstall >=1.0... 17:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:35:16 INFO - Reading from file tmpfile_stdout 17:35:16 INFO - Output received: 17:35:16 INFO - Usage: mozinstall [options] installer 17:35:16 INFO - Options: 17:35:16 INFO - -h, --help show this help message and exit 17:35:16 INFO - -d DEST, --destination=DEST 17:35:16 INFO - Directory to install application into. [default: 17:35:16 INFO - "/builds/slave/test"] 17:35:16 INFO - --app=APP Application being installed. [default: firefox] 17:35:16 INFO - mkdir: /builds/slave/test/build/application 17:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:35:33 INFO - Reading from file tmpfile_stdout 17:35:33 INFO - Output received: 17:35:33 INFO - /builds/slave/test/build/application/firefox/firefox 17:35:33 INFO - Running post-action listener: _resource_record_post_action 17:35:33 INFO - [mozharness: 2016-07-27 00:35:33.397604Z] Finished install step (success) 17:35:33 INFO - [mozharness: 2016-07-27 00:35:33.397882Z] Running run-tests step. 17:35:33 INFO - Running pre-action listener: _resource_record_pre_action 17:35:33 INFO - Running main action method: run_tests 17:35:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:35:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:35:33 INFO - Python 2.7.3 17:35:33 INFO - Return code: 0 17:35:33 INFO - grabbing minidump binary from tooltool 17:35:33 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')]} 17:35:33 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 0x1cb33b0>, '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 0x1cb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:35:33 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 17:35:33 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 17:35:33 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 17:35:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:35:33 INFO - Return code: 0 17:35:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:35:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:35:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:35:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:35:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:35:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:35:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 17:35:33 INFO - Using env: {'DISPLAY': ':0', 17:35:33 INFO - 'HOME': '/home/cltbld', 17:35:33 INFO - 'LANG': 'en_US.UTF-8', 17:35:33 INFO - 'LANGUAGE': 'en_US:en', 17:35:33 INFO - 'LOGNAME': 'cltbld', 17:35:33 INFO - 'MAIL': '/var/mail/cltbld', 17:35:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:35:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:33 INFO - 'MOZ_NO_REMOTE': '1', 17:35:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:35:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:33 INFO - 'NO_EM_RESTART': '1', 17:35:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:33 INFO - 'PWD': '/builds/slave/test', 17:35:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:35:33 INFO - 'SHELL': '/bin/bash', 17:35:33 INFO - 'SHLVL': '1', 17:35:33 INFO - 'TERM': 'linux', 17:35:33 INFO - 'TMOUT': '86400', 17:35:33 INFO - 'USER': 'cltbld', 17:35:33 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112', 17:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:33 INFO - '_': '/tools/buildbot/bin/python'} 17:35:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:35:33 INFO - mozversion application_buildid: 20160726143010 17:35:33 INFO - mozversion application_changeset: bac182c6a35717e7e3d63f837713262da8726b71 17:35:33 INFO - mozversion application_display_name: Nightly 17:35:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:35:33 INFO - mozversion application_name: Firefox 17:35:33 INFO - mozversion application_remotingname: firefox 17:35:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:35:33 INFO - mozversion application_vendor: Mozilla 17:35:33 INFO - mozversion application_version: 50.0a1 17:35:33 INFO - mozversion platform_buildid: 20160726143010 17:35:33 INFO - mozversion platform_changeset: bac182c6a35717e7e3d63f837713262da8726b71 17:35:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:35:33 INFO - mozversion platform_version: 50.0a1 17:35:33 INFO - using testdate: 1469579733 17:35:33 INFO - actual date: 1469579733 17:35:33 INFO - starting webserver on 'localhost:33667' 17:35:33 INFO - SUITE-START | Running 2 tests 17:35:33 INFO - TEST-START | dromaeo_css 17:35:33 INFO - operating with platform_type : linux_ 17:35:33 INFO - Initialising browser for dromaeo_css test... 17:35:33 INFO - TEST-INFO | started process 24118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprl3hIv/profile http://localhost:33667/getInfo.html) 17:35:36 INFO - PROCESS | 24118 | JavaScript error: , line 0: uncaught exception: undefined 17:35:37 INFO - PROCESS | 24118 | __metrics Screen width/height:1600/1200 17:35:37 INFO - PROCESS | 24118 | colorDepth:24 17:35:37 INFO - PROCESS | 24118 | Browser inner width/height: 1024/697 17:35:37 INFO - PROCESS | 24118 | __metrics 17:35:38 INFO - PROCESS | 24118 | 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" 17:35:38 INFO - PROCESS | 24118 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:39 INFO - TEST-INFO | 24118: exit 0 17:35:39 INFO - Browser initialized. 17:35:39 INFO - Running cycle 1/1 for dromaeo_css test... 17:35:39 INFO - TEST-INFO | started process 24200 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprl3hIv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:51 INFO - PROCESS | 24200 | RSS: Main: 158228480 17:35:51 INFO - PROCESS | 24200 | 17:36:42 INFO - PROCESS | 24200 | 1469579802546 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] 17:38:50 INFO - PROCESS | 24200 | Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33667/tests/dromaeo/cssquery-ext.html) 17:38:51 INFO - PROCESS | 24200 | RSS: Main: 267677696 17:38:51 INFO - PROCESS | 24200 | 17:41:53 INFO - PROCESS | 24200 | Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-ext.html (next: http://localhost:33667/tests/dromaeo/cssquery-jquery.html) 17:41:54 INFO - PROCESS | 24200 | RSS: Main: 242937856 17:41:54 INFO - PROCESS | 24200 | 17:44:56 INFO - PROCESS | 24200 | Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33667/tests/dromaeo/cssquery-mootools.html) 17:44:57 INFO - PROCESS | 24200 | RSS: Main: 221614080 17:44:57 INFO - PROCESS | 24200 | 17:47:59 INFO - PROCESS | 24200 | Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33667/tests/dromaeo/cssquery-prototype.html) 17:47:59 INFO - PROCESS | 24200 | RSS: Main: 223911936 17:47:59 INFO - PROCESS | 24200 | 17:51:02 INFO - PROCESS | 24200 | Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33667/tests/dromaeo/cssquery-yui.html) 17:51:02 INFO - PROCESS | 24200 | RSS: Main: 186208256 17:51:02 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-yui.html (next: http://localhost:33667/tests/dromaeo/cssquery-dojo.html) 17:54:06 INFO - PROCESS | 24200 | RSS: Main: 275972096 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | __start_tp_report 17:54:06 INFO - PROCESS | 24200 | _x_x_mozilla_page_load 17:54:06 INFO - PROCESS | 24200 | _x_x_mozilla_page_load_details 17:54:06 INFO - PROCESS | 24200 | |i|pagename|runs| 17:54:06 INFO - PROCESS | 24200 | |0;dojo.html;3034.408290383376;3045.1930238486802;3063.5864158338622;3081.7534597232197;3101.6898310168986;5194.584433245341;5231.973840130799;5266.368035835695;5325;5338.225957236198;5100.540951314381;5196.324477817879;5257.658252213603;5271.868203294919;5303.098473259545;4708.199606066968;4768.7138771673635;4795.808167673289;4803;4980.626453016021;4875.268709693539;4894.853154405374;4926.679765815211;4944.283078953549;4945.9258111128365;4713.198756211452;4720.055988802236;4785.90428191436;4820.30105634681;4895.192293271606;4705.1530724469585;4707.293905914106;4710.31700403443;4716.410448693913;4829.468758436569;4753.429588449374;4757.286407038937;4767.8569642910425;4769.070031343889;4779.498152693952;5142.048720986592;5215.687058776485;5273.736313184326;5291.947080529184;5294.735263236823;5655.37790843011;5703;5710.714464276811;5715.342735585419;5718.42815718429;5689.260396148474;5706.1440783882335;5744.8851022979725;5759.136129580555;5764.07774756037;5204;5296.152615581488;5350.839474815801;5353.437889021636;5433.8369848904595;5901.468867801918;5916.852078697981;5928.110783382441;5929.110633404937;5933.2880054393745;5699.772009119589;5703.315602127688;5706.600537962303;5706.9714651426475;5713.6857472838565;5314.73426328682;5625.240592520039;5648.604597678205;5655.604107712503;5745.712714364265;5657.236273103161;5662.716864156776;5666.235058267163;5668.744906479218;5708.571857110734;5635.3237611486065;5681.57388195887;5698.059820129715;5701.486866182064;5713.885722285531;4188.727732697365;4291.785410729451;4299.548547402478;4334.371516130206;4400.955990440055;3867.168558759824;3877.4959255297194;3898.278818418545;3908.6482216600643;3910.8435662573747;5393.892122157535;5404.972975135099;5408.3509978802795;5414.160805075219;5441.047816632074;5599.832005039855;5656.85857853557;5697.401772813878;5698.430156984268;5717.285339332583;5413.918791218135;5414.07960646691;5415.864603384947;5417.295751552273;5425.593080518976;4929.975350123226;4944.53026962438;4950.554450099508;4952.90094198114;4998.375203099613;5694.715264236772;5716.371199168088;5719.656820590694;5770.740316685759;5908.084246941731;5611.6352437091455;5616.634918730433;5617.578681598815;5617.747201375866;5650.943490565041;5767.884642307215;5770.249867517364;5774.855628609151;5778.56660750437;5779.971100144472;5464.672119672832;5489.231507589021;5552.444755524577;5577.163425486209;5591.524720398649;5672.035753921926;5681.460261275172;5712.571557133315;5716.085426331767;5728.942710572841;5526.640768350045;5579.358373787067;5579.581531385081;5581.720913954205;5583;5428.321459817523;5442.591805614516;5447.291852059207;5449.64577302474;5566.638168519034;5450.727463626724;5451.018816613047;5451.127819548853;5457.099578569491;5488.506034456918;5499.835004949858;5542.695151766691;5590.105583106683;5597.160425936142;5600.411956744483;5330.120530112488;5330.733463326741;5362.892742145214;5386.676799392049;5401.3248343957 17:54:06 INFO - PROCESS | 24200 | |1;ext.html;588.7438964050639;602.3795490644636;607.4411541381926;616.1374076293187;627.4478458956117;29645.11064668058;30148.095557133292;32574.67425325746;32701.67298327016;32701.67298327016;44250.114997700024;45293.32060019094;45508.772456137754;48488.27267590989;48622.513774862244;8990.595423205956;9126.954365228197;9128.58921348541;9129.087091290885;9129.95435022824;8998.19016288534;9067.455952642862;9069.093090690943;9096.181343679067;9100.681476148337;9084.137006984325;9156.633734650639;9161.221296189831;9163.175314221719;9166.816663666723;9102.954485227565;9136.451812891248;9148.588313525874;9149.771255718593;9167.587458564381;9008.459492430475;9051.547422628842;9061.68284110053;9070.138336857988;9074.86387704185;42907;43107.35338969918;43192.13615727699;43230.56769432297;43292.350614740804;3534.91162720934;3687.727733931816;3711.7030637548933;3799.4490798834013;4199.559046300125;32774;33280.16799580005;33310.50034249488;33551;34574.30851382958;3887.7278590498677;4095.5699651536456;4138.275801734684;4197.307444271692;4253.766042867641;349642;354229;354581;355180;357398;58791;59120.704396478606;59149.112763308585;59173.40826591679;59224.703876480344;62260;62387.6880615594;62531.687341563;62549.68725156436;62569;15624.531264062096;15738.92130539363;15765.68468630621;15823;15841.920790395974;12076.516939322504;12134.393280335948;12146.878531214752;12150.149489535825;12153;15521.379144834305;16984.83015169857;16998.235079421207;17089.401870934707;17201.827981720275;5990.472429530442;6092.45167934888;6103.481204097613;6236.656983865931;6387;709.1239054066064;1084.5499117865998;1123.1127409346689;1123.3821398231103;1141.851559297286;31748.523772143435;33567;33624.66375336264;33781.83109084439;34121.65878341185;1097.3854641400842;1109.1016276815806;1166.55087791201;1173.865882108203;1229.6003798765437;17492.737608935877;17536;17574.384896528816;17668.646627067643;17695;544887;553613;554215;555126;555538;58890;59036.40963590395;59120;59199;59288.70355648194;1209.504103317653;1325.1651459580596;1358.117223804535;1358.44303835427;1364.4951367993906;30725.231869202555;34142.658573413944;34397.31205375829;34667;34790.82604586961;51843.22235166551;53290.73354633357;53299.733501332244;53320.46679533155;53340.46659533355;470273;475078;476228;476269;476385;43194.35208471813;44471.33293000543;44478.332825007004;47612.38131902779;48854.801533930346;49601.25598116103;50344.74827625985;50458.24312635387;50514.242286365;50853.237201442746;39591;43239;44093.33859992167;44699.55300446954;45925;43397.04713287846;44771.55228447674;45951;46904;47380.52619473899;41642.375364368956;42138.57861421469;42322.576774231864;42480.78759606182;43200.56799431965;36291.81854090712;39057.41413878851;39358.42566297316;39516.80241598774;39551;29135.85432072826;29513.114606561838;29591.408171836872;29856.85071574628;29982.550261745655 17:54:06 INFO - PROCESS | 24200 | |2;jquery.html;365.0599705757671;368.54792119050956;379.94870692456516;385.9652631263186;406.3498402555911;16036.839631603694;17958.91020544897;19693.901530492327;20077.698834517494;20632.27787027454;18497.90751046246;19001.61996760064;19002.33491827783;19456;19634.312799052037;5717.971410142944;5929.495992840613;5938.554608404365;5941.257342832146;6145.661988590626;6523.510736694743;6668.733250669968;6676.599404035749;6678.165229346332;6680.031395447657;6432.195975503062;6462.450691691212;6531.542792004561;6565.179352580927;6632.137822083122;6315.905261421082;6557.639329836857;6588.8352791180605;6590.967045164792;6595.769148079794;5601.943980560224;5630.5495560355075;5635.774569017234;5636.351819540764;5642.266505354278;23275;23596;23856.284311470685;23872.403189920216;23935.401614959766;2070.171931227521;2218.8779617121054;2504.060977133575;2574.8068894832963;2835.3620435402077;9933.80132397348;10160;10172.59309627614;10225.642102526375;10532.05211530966;1926.2969016308946;1953.736245606839;1954.8631595788302;1993.4617653233545;2000;81710.59144704299;84671.15328846694;84896.57551712263;85791;86136;10634.574617015232;10715.517801698943;10797.784044319069;11030;11189.72025699364;15177.013494122846;15194;15195.164265965477;15211.619709507351;15213.695726085416;7378.852422951511;7516.774496764996;7692.807679807937;7808.843823123506;7810.726624568227;7165.92834071663;7171.354578087997;7303.7443689471675;7392.63236301286;7414.518056326321;4650.627949764011;4694.554017368345;4698.718076915396;4716.858494245178;4776.450708168536;1904.7523821903342;1917.1947781931344;1951.658459769563;1951.9121639526256;1962.5093726568357;966.183574879226;982.9754256143653;990.7919336939324;1023.150784848574;1048.3500229857536;19662.91853948018;20171.59656806885;20205.797942020392;20239.291624793063;20490.18039278412;930.2093220762298;965.9178969787647;970.3450171134456;987.9555420006117;1011.9576835859077;9162.67930622425;10498.73753156162;10549.367037977747;11158.1631377647;11188.307858406706;439767;461564;507092;521701;522968;61093;62033;63089.684551576946;99903.00581965105;101424;1001.6711146584221;1036.3988886445993;1047.8847326794046;1058.952347144365;1062.654637242899;17796.288148473915;17933.282668693628;18598.163082661584;18635.44093677192;18871.339503117324;33179.50230746589;42737.786311068245;43309;44375.55624443714;44909.10181796411;55560.72219638876;58240.4175958235;59159.40840591539;60091;60245.69877150762;6730.629815360006;7664.811954147116;8666.306695464222;8750.693725719824;8819.867701984347;11768.646605640119;12304.892559669674;13307.600771976857;13325.33373331349;13631.795523066963;11526.193166478266;11715.648530544097;11744.94127529357;11771.293722376684;12164.330961797186;8254.83490330202;8661.870071949052;11429.885701142883;11806.763864722485;11887.227330223508;11761.764764704829;11911.940440298089;12149.453274602842;12230.205036672587;12547.560835370718;8384.455010424303;8449.366297527587;8572.4427912188;8625.611847467004;8748.956255218938;9703.6118555257;9867.371883639405;11221.71945701364;11513.827292590786;11973.102017348909 17:54:06 INFO - PROCESS | 24200 | |3;mootools.html;1323.6624660711518;1450.6300893272216;1522.383434708943;1633.175246500518;1785.134209908195;8031.237032481912;8339.791505212374;8433.578321083944;8657.69698060568;8663.740087797376;8431.95784021079;8511.148885111486;8524.275436587897;8547.65809367626;8581.184787445183;2289.6794448777187;2314.456102815837;2315.617923042704;2315.78000089992;2318.8840557972117;2277.9886100569556;2325.9651105233434;2334.8248881333884;2337.1586228957563;2337.3689103941924;2268.8071513921336;2274.1358283852114;2300.7469178390365;2306.6655334976417;2309.1109922679807;2158.460384903774;2189.211883721859;2204.9669254961186;2217.9464754241685;2219.1234462387347;2219.9223027194134;2249.415152060476;2250.2124256510256;2252.662100684894;2256.435891027243;8567.400281980264;8631.568421578895;8667.566621668955;8691.130886911258;8697.69558065471;4889.633277504166;5038.445770965191;5060.2409638554145;5066.69599824008;5069.087564238435;3123.9219019524467;3290.8683652653867;3341.3150304187584;3348.8493017814258;3354.077628652116;1058.7035630023606;1068.9412082335468;1079.228351728518;1087.1465899269035;1092.3718861654502;11956.282623042558;11966.64100076999;12004.639860804189;12013.87986120145;12086.576969806103;4067.206894655573;4237.703360764778;4266.104118135165;4335.479742430928;4347;9419.15227629503;9452.338336316392;9480.431174129575;9483;9493.620255189853;4983.426905905867;4995.550400463903;5000.274960130833;5001.549860512571;5012.223105418593;4212.49450065994;4550.954490455119;4681.040386720714;4710.929336059962;4748.762561871893;3513.4378499440477;3563.7327200460068;3624.2932628137787;3651.543557055368;3698.260347930425;1015.9238057145743;1020.5611587017654;1033.7105610429091;1038;1050.8896565860628;878.7319867440365;949.7388218239901;965.6909788867636;981.1218959031769;984.601236499219;5905.409459054146;5978.581499295007;6061.212042434456;6120.17377654011;6180.907286390708;407.10233934175074;415.7193894121456;416.95621959693796;418.04893866453455;982.6266018912768;4077.388391741203;4143.81352839123;4149.439825623563;4186.246475634415;4189.120284740238;10560.366378017343;11007.119430445546;11019.173561982883;11021.283616564897;11027.83458248127;10128.797424051625;10782.568697252022;10810.135189184846;10840.78318433627;10864.130869530418;7800.258975397328;7994.360451163893;8135.633896474673;8139.837203255902;8153.266206041603;6115.205023346936;6151.96924015377;6161.383861613803;6174.44430001302;6365.108884756045;9646.324757266924;9870.950645246729;9916.851247231441;9947;9976.501174941079;10115.848262276219;10121.69634910954;10140.54367553447;10143.188544916242;10143.543540540546;6782.979908124987;7410.814729631802;7558.58427786477;8315.708950186714;8480.703175388831;7763.844723105619;8371.790705232417;8381.916180838114;8392.076871544248;8408.705695300636;7258.3104605062435;7267.382272506896;7280.344768970818;7371.262873712586;7458.291462311072;6990.965045174741;7058.470614704021;7079.221285658677;7097.680604372919;7118.750843720445;6847.828804279933;6874.725010999504;6955.965220173866;6959.686814093479;6995.845685461842;5127.051495473349;5128.384593848762;5186.948130518646;5234.528892399702;5336.306280183551;5160.329157209539;5213.947860521346;5402.297701298963;5486.177073439016;5554.527865131347 17:54:06 INFO - PROCESS | 24200 | |4;prototype.html;322.80631621027374;323.8477915379771;336.07411581094095;338.08547877990037;338.78995023085685;5270.262163297137;5286.334804536573;5314.468553144684;5331.413544510101;5393.9190912136255;4994.827250146189;5138.66598671087;5151.587872970163;5199.402068762093;5332.653377530458;4007.6994225433064;4074.816633251496;4098.938515922264;4112.547619761824;4149.543550209474;4144.191882582898;4156.625903668669;4159.459270294872;4161.625453709165;4165.83336666533;4080.795960201993;4111.136661301115;4115.876523704279;4122.546519882811;4134.338505839067;4099.487564054493;4119.237940980928;4120.711550191488;4125.731827431237;4149.668026557883;3689.096171438015;3691.2248427830186;3699.1676872703697;3705.370087085175;3721.1999420124803;5090.211017292326;5172.146595811687;5214.8696282592855;5217.869553261161;5220.216967454912;4714.952850471485;4716.858494245143;4718.419329524579;4720.197566413718;4747.881302967419;5454.59090568205;5470.015397228497;5471.124620060771;5471.945280547184;5486.807961721321;3786.6024067473068;4019.537753158395;4037.8895803653936;4061.1674606705556;4070.776107314126;5613.045782217033;5639.2668953035845;5648.435156484319;5676.375598684142;5683.0338842396895;3961.7821019844187;3988.6210809973004;3989.4268197013344;4003.2393845169327;4014.136960553495;4013.5986401360215;4029.597040295947;4034.636882680573;4050.124411667749;4073.103917138225;4516.277395616686;4583.175028494903;4591.931121033187;4640.79116439761;4652.116097941381;5463.289772329572;5475.561955043587;5486.91769623456;5489.204065410493;5533.474319939599;1329.5109477385197;1400.9719805603831;1406.2898236390558;1410.3864818803854;1413.4416905322546;870.2907130688469;879.4898958603994;893.7095443980733;928.3176865004217;937.7093101138669;4784.234522476457;4796.2805579162705;4799.808007679654;4808.591269742041;4812.302216178635;5045.621578381636;5052.942969503471;5076.086304465158;5135.332406787168;5144.4855514449;4647.855079664316;4702.459217190067;4725.5746982771025;4755.262934245197;4785.449673287547;2686.341846247682;2686.6641669791275;2688.623592697024;2688.7042425333198;2719.741624545665;5089.923651145235;5132.5124113209185;5171.612129090333;5279.894402111936;5285.806390347962;5387.595930305243;5767;5786.42135786418;5801.7389217485315;5806.274215723035;5832.358440571534;5863.7947671832135;5864.9413505864395;5868.589198756132;5929.169916211647;5548.167774833807;5627.943720562742;5669.376368599368;5720.11338242573;5734.741936612779;5599.636023658449;5608.803691870765;5614.512154279201;5621.437856214509;5629.662220266797;5786.479216870502;5790.623609465371;5800.709964501673;5814.592978491465;5881.117832325185;3875.6511913927884;4034.556198818069;4067.288224560631;4067.3898915162963;4092.181563687334;5612.7193640317;5647.20939068539;5672.376038635829;5673.886522269614;5712.428757124254;5361.973190134025;5558.082916318924;5571.331440227199;5573.637713548606;5580.832575022756;5534.861628459321;5545.916811247754;5555.916661250165;5564.304461942257;5566.526845218202;5252.369715634149;5311.548518375981;5442.619016668795;5502.559795216293;5505.614606977473;5519.503244707996;5536.307961504812;5555.72221388937;5574.91637625444;5577.9442205577425;5418.320320700595;5430.456954304538;5433.56531477473;5450.400455949922;5485.50630443262 17:54:06 INFO - PROCESS | 24200 | |5;yui.html;731.8389954210073;810.3962547901814;820.6307161777202;831.5260301628073;838.3120527222794;15682;16588;16614.667706645876;16654.66690666186;16671.1664416779;15243;15337.923310383432;15406.460773872917;15418;15423;646.5894157210158;790.7074382478492;808.2846680687575;814.698561532234;815.5514467043132;789.6999140326668;804.7706403674954;811.1604489353521;825.8761185822115;827.1108558299821;870.9940019277713;871.2724874729577;873.9825203495926;874.2699845628907;878.3587980774039;673.0812441017976;872.0973792125152;873.2533683700472;873.3319010956592;873.3668090634271;609.0742072050502;618.8638499530141;626.6052386996163;634.0489266100849;635.2726128582793;14696;14700.411983520646;14736.115833050051;14738.705225895423;14780.556583302518;14212;14328;14899.702005959929;15031.098134111988;15100.395984160621;12074.08328154133;12096.455659495345;12327.75344493114;12402.441890114966;12809.231446113263;4411.977940110311;4446.688731788744;4459.687821852472;4644;4672.929906051412;77930.22069779286;78102.60948695277;78111;79136.60431697863;79177.60411197964;11451;11629.941850290694;11703.824442633368;11873.228240164535;12354.320512371725;21677.748337749465;21741;21756.89121554382;21767.455813604785;21789.564208715736;8637.870431943526;8659.48043117415;8662.350323725746;8685.522296273639;8885.600147993226;4649.976750116228;4667.253239481734;4724.590458866003;4949.950500495021;5014.824481143142;3456.4296891013205;3546.4680297955515;3559.43049112146;3561.661642143992;3563.2160924596546;597.4503456819737;601.485729701108;605.2615808713363;616.0451300484232;624.7407325959766;653.8724948635065;661.3089321658782;667.9499037572104;671.3655595462338;678.2437582095928;9207.815843683224;9471.301955076131;9530.857037144448;9621.326507144573;9795.951020244853;579.4466284698103;615.1572345886165;621.1645337021698;647.3300134360913;661.2792056658266;4567.680262381601;4920.335754673145;4943.887625284282;4966.038414826124;4981.651284410056;111335.44332278287;114848;115229;118258.40870795591;118720;100065;100605;100751.49624251979;100795.49602251942;104127.4793626027;78982.21017789979;84087.5795621018;90804.09195908089;90832;90984;10175;10190.592376304865;10193.796124077626;10248.797992823205;10434.947825260826;48031;48479;52757.681057972324;54181;54343.184852226455;52514;63002.68498657477;73972;75145.24854751382;77205.2279477198;12701.491940322283;12769.361531923552;13557.525486607921;14000.649983750487;14063.085899416505;14598.781018284522;14725.484608038667;14899.47851825181;15072.472463464164;15360.23198840076;14426.206558639376;14457.85542144565;14488.420463181355;14748.33632486562;14898.25508724581;14446.494372696905;14511.564653060424;14974.160206718174;14987.775183372476;15011.549653510412;14678;14814.925925370304;14982.850171498581;15092.471763488225;15265.746659497832;11470.254433461798;11562.653120406401;11879.881201188224;12019.519219231483;12249.816252756395;10041.698749037541;11405.543778248777;11474.885251147381;11639.47622356979;11755.294682319089 17:54:06 INFO - PROCESS | 24200 | __end_tp_report 17:54:06 INFO - PROCESS | 24200 | __start_cc_report 17:54:06 INFO - PROCESS | 24200 | _x_x_mozilla_cycle_collect,1013 17:54:06 INFO - PROCESS | 24200 | __end_cc_report 17:54:06 INFO - PROCESS | 24200 | __startTimestamp1469580846322__endTimestamp 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | ------- Summary: start ------- 17:54:06 INFO - PROCESS | 24200 | Number of tests: 6 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | [#0] dojo.html Cycles:165 Average:5261.89 Median:5442.59 stddev:599.49 (11.0%) stddev-sans-first:575.30 17:54:06 INFO - PROCESS | 24200 | Values: 3034.4 3045.2 3063.6 3081.8 3101.7 5194.6 5232.0 5266.4 5325.0 5338.2 5100.5 5196.3 5257.7 5271.9 5303.1 4708.2 4768.7 4795.8 4803.0 4980.6 4875.3 4894.9 4926.7 4944.3 4945.9 4713.2 4720.1 4785.9 4820.3 4895.2 4705.2 4707.3 4710.3 4716.4 4829.5 4753.4 4757.3 4767.9 4769.1 4779.5 5142.0 5215.7 5273.7 5291.9 5294.7 5655.4 5703.0 5710.7 5715.3 5718.4 5689.3 5706.1 5744.9 5759.1 5764.1 5204.0 5296.2 5350.8 5353.4 5433.8 5901.5 5916.9 5928.1 5929.1 5933.3 5699.8 5703.3 5706.6 5707.0 5713.7 5314.7 5625.2 5648.6 5655.6 5745.7 5657.2 5662.7 5666.2 5668.7 5708.6 5635.3 5681.6 5698.1 5701.5 5713.9 4188.7 4291.8 4299.5 4334.4 4401.0 3867.2 3877.5 3898.3 3908.6 3910.8 5393.9 5405.0 5408.4 5414.2 5441.0 5599.8 5656.9 5697.4 5698.4 5717.3 5413.9 5414.1 5415.9 5417.3 5425.6 4930.0 4944.5 4950.6 4952.9 4998.4 5694.7 5716.4 5719.7 5770.7 5908.1 5611.6 5616.6 5617.6 5617.7 5650.9 5767.9 5770.2 5774.9 5778.6 5780.0 5464.7 5489.2 5552.4 5577.2 5591.5 5672.0 5681.5 5712.6 5716.1 5728.9 5526.6 5579.4 5579.6 5581.7 5583.0 5428.3 5442.6 5447.3 5449.6 5566.6 5450.7 5451.0 5451.1 5457.1 5488.5 5499.8 5542.7 5590.1 5597.2 5600.4 5330.1 5330.7 5362.9 5386.7 5401.3 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | [#1] ext.html Cycles:180 Average:62752.30 Median:43265.68 stddev:124062.68 (286.7%) stddev-sans-first:124322.92 17:54:06 INFO - PROCESS | 24200 | Values: 588.7 602.4 607.4 616.1 627.4 29645.1 30148.1 32574.7 32701.7 32701.7 44250.1 45293.3 45508.8 48488.3 48622.5 8990.6 9127.0 9128.6 9129.1 9130.0 8998.2 9067.5 9069.1 9096.2 9100.7 9084.1 9156.6 9161.2 9163.2 9166.8 9103.0 9136.5 9148.6 9149.8 9167.6 9008.5 9051.5 9061.7 9070.1 9074.9 42907.0 43107.4 43192.1 43230.6 43292.4 3534.9 3687.7 3711.7 3799.4 4199.6 32774.0 33280.2 33310.5 33551.0 34574.3 3887.7 4095.6 4138.3 4197.3 4253.8 349642.0 354229.0 354581.0 355180.0 357398.0 58791.0 59120.7 59149.1 59173.4 59224.7 62260.0 62387.7 62531.7 62549.7 62569.0 15624.5 15738.9 15765.7 15823.0 15841.9 12076.5 12134.4 12146.9 12150.1 12153.0 15521.4 16984.8 16998.2 17089.4 17201.8 5990.5 6092.5 6103.5 6236.7 6387.0 709.1 1084.5 1123.1 1123.4 1141.9 31748.5 33567.0 33624.7 33781.8 34121.7 1097.4 1109.1 1166.6 1173.9 1229.6 17492.7 17536.0 17574.4 17668.6 17695.0 544887.0 553613.0 554215.0 555126.0 555538.0 58890.0 59036.4 59120.0 59199.0 59288.7 1209.5 1325.2 1358.1 1358.4 1364.5 30725.2 34142.7 34397.3 34667.0 34790.8 51843.2 53290.7 53299.7 53320.5 53340.5 470273.0 475078.0 476228.0 476269.0 476385.0 43194.4 44471.3 44478.3 47612.4 48854.8 49601.3 50344.7 50458.2 50514.2 50853.2 39591.0 43239.0 44093.3 44699.6 45925.0 43397.0 44771.6 45951.0 46904.0 47380.5 41642.4 42138.6 42322.6 42480.8 43200.6 36291.8 39057.4 39358.4 39516.8 39551.0 29135.9 29513.1 29591.4 29856.9 29982.6 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | [#2] jquery.html Cycles:180 Average:28764.10 Median:374.25 stddev:80944.37 (21628.5%) stddev-sans-first:81143.35 17:54:06 INFO - PROCESS | 24200 | Values: 365.1 368.5 379.9 386.0 406.3 16036.8 17958.9 19693.9 20077.7 20632.3 18497.9 19001.6 19002.3 19456.0 19634.3 5718.0 5929.5 5938.6 5941.3 6145.7 6523.5 6668.7 6676.6 6678.2 6680.0 6432.2 6462.5 6531.5 6565.2 6632.1 6315.9 6557.6 6588.8 6591.0 6595.8 5601.9 5630.5 5635.8 5636.4 5642.3 23275.0 23596.0 23856.3 23872.4 23935.4 2070.2 2218.9 2504.1 2574.8 2835.4 9933.8 10160.0 10172.6 10225.6 10532.1 1926.3 1953.7 1954.9 1993.5 2000.0 81710.6 84671.2 84896.6 85791.0 86136.0 10634.6 10715.5 10797.8 11030.0 11189.7 15177.0 15194.0 15195.2 15211.6 15213.7 7378.9 7516.8 7692.8 7808.8 7810.7 7165.9 7171.4 7303.7 7392.6 7414.5 4650.6 4694.6 4698.7 4716.9 4776.5 1904.8 1917.2 1951.7 1951.9 1962.5 966.2 983.0 990.8 1023.2 1048.4 19662.9 20171.6 20205.8 20239.3 20490.2 930.2 965.9 970.3 988.0 1012.0 9162.7 10498.7 10549.4 11158.2 11188.3 439767.0 461564.0 507092.0 521701.0 522968.0 61093.0 62033.0 63089.7 99903.0 101424.0 1001.7 1036.4 1047.9 1059.0 1062.7 17796.3 17933.3 18598.2 18635.4 18871.3 33179.5 42737.8 43309.0 44375.6 44909.1 55560.7 58240.4 59159.4 60091.0 60245.7 6730.6 7664.8 8666.3 8750.7 8819.9 11768.6 12304.9 13307.6 13325.3 13631.8 11526.2 11715.6 11744.9 11771.3 12164.3 8254.8 8661.9 11429.9 11806.8 11887.2 11761.8 11911.9 12149.5 12230.2 12547.6 8384.5 8449.4 8572.4 8625.6 8749.0 9703.6 9867.4 11221.7 11513.8 11973.1 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | [#3] mootools.html Cycles:180 Average:5645.59 Median:4989.49 stddev:3241.58 (65.0%) stddev-sans-first:3234.40 17:54:06 INFO - PROCESS | 24200 | Values: 1323.7 1450.6 1522.4 1633.2 1785.1 8031.2 8339.8 8433.6 8657.7 8663.7 8432.0 8511.1 8524.3 8547.7 8581.2 2289.7 2314.5 2315.6 2315.8 2318.9 2278.0 2326.0 2334.8 2337.2 2337.4 2268.8 2274.1 2300.7 2306.7 2309.1 2158.5 2189.2 2205.0 2217.9 2219.1 2219.9 2249.4 2250.2 2252.7 2256.4 8567.4 8631.6 8667.6 8691.1 8697.7 4889.6 5038.4 5060.2 5066.7 5069.1 3123.9 3290.9 3341.3 3348.8 3354.1 1058.7 1068.9 1079.2 1087.1 1092.4 11956.3 11966.6 12004.6 12013.9 12086.6 4067.2 4237.7 4266.1 4335.5 4347.0 9419.2 9452.3 9480.4 9483.0 9493.6 4983.4 4995.6 5000.3 5001.5 5012.2 4212.5 4551.0 4681.0 4710.9 4748.8 3513.4 3563.7 3624.3 3651.5 3698.3 1015.9 1020.6 1033.7 1038.0 1050.9 878.7 949.7 965.7 981.1 984.6 5905.4 5978.6 6061.2 6120.2 6180.9 407.1 415.7 417.0 418.0 982.6 4077.4 4143.8 4149.4 4186.2 4189.1 10560.4 11007.1 11019.2 11021.3 11027.8 10128.8 10782.6 10810.1 10840.8 10864.1 7800.3 7994.4 8135.6 8139.8 8153.3 6115.2 6152.0 6161.4 6174.4 6365.1 9646.3 9871.0 9916.9 9947.0 9976.5 10115.8 10121.7 10140.5 10143.2 10143.5 6783.0 7410.8 7558.6 8315.7 8480.7 7763.8 8371.8 8381.9 8392.1 8408.7 7258.3 7267.4 7280.3 7371.3 7458.3 6991.0 7058.5 7079.2 7097.7 7118.8 6847.8 6874.7 6956.0 6959.7 6995.8 5127.1 5128.4 5186.9 5234.5 5336.3 5160.3 5213.9 5402.3 5486.2 5554.5 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | [#4] prototype.html Cycles:180 Average:4577.22 Median:5161.60 stddev:1349.02 (26.1%) stddev-sans-first:1314.46 17:54:06 INFO - PROCESS | 24200 | Values: 322.8 323.8 336.1 338.1 338.8 5270.3 5286.3 5314.5 5331.4 5393.9 4994.8 5138.7 5151.6 5199.4 5332.7 4007.7 4074.8 4098.9 4112.5 4149.5 4144.2 4156.6 4159.5 4161.6 4165.8 4080.8 4111.1 4115.9 4122.5 4134.3 4099.5 4119.2 4120.7 4125.7 4149.7 3689.1 3691.2 3699.2 3705.4 3721.2 5090.2 5172.1 5214.9 5217.9 5220.2 4715.0 4716.9 4718.4 4720.2 4747.9 5454.6 5470.0 5471.1 5471.9 5486.8 3786.6 4019.5 4037.9 4061.2 4070.8 5613.0 5639.3 5648.4 5676.4 5683.0 3961.8 3988.6 3989.4 4003.2 4014.1 4013.6 4029.6 4034.6 4050.1 4073.1 4516.3 4583.2 4591.9 4640.8 4652.1 5463.3 5475.6 5486.9 5489.2 5533.5 1329.5 1401.0 1406.3 1410.4 1413.4 870.3 879.5 893.7 928.3 937.7 4784.2 4796.3 4799.8 4808.6 4812.3 5045.6 5052.9 5076.1 5135.3 5144.5 4647.9 4702.5 4725.6 4755.3 4785.4 2686.3 2686.7 2688.6 2688.7 2719.7 5089.9 5132.5 5171.6 5279.9 5285.8 5387.6 5767.0 5786.4 5801.7 5806.3 5832.4 5863.8 5864.9 5868.6 5929.2 5548.2 5627.9 5669.4 5720.1 5734.7 5599.6 5608.8 5614.5 5621.4 5629.7 5786.5 5790.6 5800.7 5814.6 5881.1 3875.7 4034.6 4067.3 4067.4 4092.2 5612.7 5647.2 5672.4 5673.9 5712.4 5362.0 5558.1 5571.3 5573.6 5580.8 5534.9 5545.9 5555.9 5564.3 5566.5 5252.4 5311.5 5442.6 5502.6 5505.6 5519.5 5536.3 5555.7 5574.9 5577.9 5418.3 5430.5 5433.6 5450.4 5485.5 17:54:06 INFO - PROCESS | 24200 | 17:54:06 INFO - PROCESS | 24200 | [#5] yui.html Cycles:180 Average:21063.89 Median:4429.33 stddev:30021.68 (677.8%) stddev-sans-first:30067.08 17:54:06 INFO - PROCESS | 24200 | Values: 731.8 810.4 820.6 831.5 838.3 15682.0 16588.0 16614.7 16654.7 16671.2 15243.0 15337.9 15406.5 15418.0 15423.0 646.6 790.7 808.3 814.7 815.6 789.7 804.8 811.2 825.9 827.1 871.0 871.3 874.0 874.3 878.4 673.1 872.1 873.3 873.3 873.4 609.1 618.9 626.6 634.0 635.3 14696.0 14700.4 14736.1 14738.7 14780.6 14212.0 14328.0 14899.7 15031.1 15100.4 12074.1 12096.5 12327.8 12402.4 12809.2 4412.0 4446.7 4459.7 4644.0 4672.9 77930.2 78102.6 78111.0 79136.6 79177.6 11451.0 11629.9 11703.8 11873.2 12354.3 21677.7 21741.0 21756.9 21767.5 21789.6 8637.9 8659.5 8662.4 8685.5 8885.6 4650.0 4667.3 4724.6 4950.0 5014.8 3456.4 3546.5 3559.4 3561.7 3563.2 597.5 601.5 605.3 616.0 624.7 653.9 661.3 667.9 671.4 678.2 9207.8 9471.3 9530.9 9621.3 9796.0 579.4 615.2 621.2 647.3 661.3 4567.7 4920.3 4943.9 4966.0 4981.7 111335.4 114848.0 115229.0 118258.4 118720.0 100065.0 100605.0 100751.5 100795.5 104127.5 78982.2 84087.6 90804.1 90832.0 90984.0 10175.0 10190.6 10193.8 10248.8 10434.9 48031.0 48479.0 52757.7 54181.0 54343.2 52514.0 63002.7 73972.0 75145.2 77205.2 12701.5 12769.4 13557.5 14000.6 14063.1 14598.8 14725.5 14899.5 15072.5 15360.2 14426.2 14457.9 14488.4 14748.3 14898.3 14446.5 14511.6 14974.2 14987.8 15011.5 14678.0 14814.9 14982.9 15092.5 15265.7 11470.3 11562.7 11879.9 12019.5 12249.8 10041.7 11405.5 11474.9 11639.5 11755.3 17:54:06 INFO - PROCESS | 24200 | -------- Summary: end -------- 17:54:06 INFO - PROCESS | 24200 | 17:54:07 INFO - TEST-INFO | 24200: exit 0 17:54:07 INFO - TEST-OK | dromaeo_css | took 1113375ms 17:54:07 INFO - TEST-START | kraken 17:54:07 INFO - operating with platform_type : linux_ 17:54:07 INFO - Initialising browser for kraken test... 17:54:07 INFO - TEST-INFO | started process 24385 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYOeg3I/profile http://localhost:33667/getInfo.html) 17:54:10 INFO - PROCESS | 24385 | JavaScript error: , line 0: uncaught exception: undefined 17:54:10 INFO - PROCESS | 24385 | __metrics Screen width/height:1600/1200 17:54:10 INFO - PROCESS | 24385 | colorDepth:24 17:54:10 INFO - PROCESS | 24385 | Browser inner width/height: 1024/697 17:54:10 INFO - PROCESS | 24385 | __metrics 17:54:11 INFO - PROCESS | 24385 | 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" 17:54:11 INFO - PROCESS | 24385 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:54:12 INFO - TEST-INFO | 24385: exit 0 17:54:12 INFO - Browser initialized. 17:54:12 INFO - Running cycle 1/1 for kraken test... 17:54:12 INFO - TEST-INFO | started process 24465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYOeg3I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:54:24 INFO - PROCESS | 24465 | RSS: Main: 157605888 17:54:24 INFO - PROCESS | 24465 | 17:54:26 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-beat-detection) 17:54:27 INFO - PROCESS | 24465 | RSS: Main: 214474752 17:54:27 INFO - PROCESS | 24465 | 17:54:30 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-dft) 17:54:31 INFO - PROCESS | 24465 | RSS: Main: 193732608 17:54:31 INFO - PROCESS | 24465 | 17:54:34 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-fft) 17:54:34 INFO - PROCESS | 24465 | RSS: Main: 270102528 17:54:34 INFO - PROCESS | 24465 | 17:54:36 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-oscillator) 17:54:37 INFO - PROCESS | 24465 | RSS: Main: 181047296 17:54:37 INFO - PROCESS | 24465 | 17:54:39 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33667/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:54:40 INFO - PROCESS | 24465 | RSS: Main: 185544704 17:54:40 INFO - PROCESS | 24465 | 17:54:43 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33667/tests/kraken/driver.html?testName=imaging-darkroom) 17:54:43 INFO - PROCESS | 24465 | RSS: Main: 220303360 17:54:43 INFO - PROCESS | 24465 | 17:54:47 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33667/tests/kraken/driver.html?testName=imaging-desaturate) 17:54:47 INFO - PROCESS | 24465 | RSS: Main: 220647424 17:54:47 INFO - PROCESS | 24465 | 17:54:50 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33667/tests/kraken/driver.html?testName=json-parse-financial) 17:54:51 INFO - PROCESS | 24465 | RSS: Main: 221077504 17:54:51 INFO - PROCESS | 24465 | 17:54:52 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33667/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:54:53 INFO - PROCESS | 24465 | RSS: Main: 185733120 17:54:53 INFO - PROCESS | 24465 | 17:54:55 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:54:55 INFO - PROCESS | 24465 | RSS: Main: 189931520 17:54:55 INFO - PROCESS | 24465 | 17:54:57 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:54:58 INFO - PROCESS | 24465 | RSS: Main: 195563520 17:54:58 INFO - PROCESS | 24465 | 17:55:00 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:55:01 INFO - PROCESS | 24465 | RSS: Main: 188727296 17:55:01 INFO - PROCESS | 24465 | 17:55:04 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:55:04 INFO - PROCESS | 24465 | RSS: Main: 193683456 17:55:04 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33667/tests/kraken/driver.html?testName=ai-astar) 17:55:06 INFO - PROCESS | 24465 | RSS: Main: 244633600 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | __start_tp_report 17:55:06 INFO - PROCESS | 24465 | _x_x_mozilla_page_load 17:55:06 INFO - PROCESS | 24465 | _x_x_mozilla_page_load_details 17:55:06 INFO - PROCESS | 24465 | |i|pagename|runs| 17:55:06 INFO - PROCESS | 24465 | |0;ai-astar;101;110;110;110;112;110;109;110;109;110 17:55:06 INFO - PROCESS | 24465 | |1;audio-beat-detection;142;116;117;189;117;120;144;118;152;118 17:55:06 INFO - PROCESS | 24465 | |2;audio-dft;161;162;160;161;160;159;160;160;161;160 17:55:06 INFO - PROCESS | 24465 | |3;audio-fft;75;75;74;123;77;76;75;74;100;75 17:55:06 INFO - PROCESS | 24465 | |4;audio-oscillator;85;84;123;85;85;83;119;84;85;122 17:55:06 INFO - PROCESS | 24465 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;116;117;116 17:55:06 INFO - PROCESS | 24465 | |6;imaging-darkroom;146;146;146;145;146;146;145;145;145;147 17:55:06 INFO - PROCESS | 24465 | |7;imaging-desaturate;94;95;95;95;95;94;96;95;95;95 17:55:06 INFO - PROCESS | 24465 | |8;json-parse-financial;54;53;56;54;53;55;53;53;53;52 17:55:06 INFO - PROCESS | 24465 | |9;json-stringify-tinderbox;62;61;62;62;62;62;61;62;62;62 17:55:06 INFO - PROCESS | 24465 | |10;stanford-crypto-aes;76;76;76;79;75;76;76;76;85;79 17:55:06 INFO - PROCESS | 24465 | |11;stanford-crypto-ccm;121;126;137;127;123;128;119;119;165;120 17:55:06 INFO - PROCESS | 24465 | |12;stanford-crypto-pbkdf2;156;157;156;159;156;157;153;157;154;154 17:55:06 INFO - PROCESS | 24465 | |13;stanford-crypto-sha256-iterative;65;62;63;62;63;65;64;63;64;61 17:55:06 INFO - PROCESS | 24465 | __end_tp_report 17:55:06 INFO - PROCESS | 24465 | __start_cc_report 17:55:06 INFO - PROCESS | 24465 | _x_x_mozilla_cycle_collect,1733 17:55:06 INFO - PROCESS | 24465 | __end_cc_report 17:55:06 INFO - PROCESS | 24465 | __startTimestamp1469580906693__endTimestamp 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | ------- Summary: start ------- 17:55:06 INFO - PROCESS | 24465 | Number of tests: 14 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:2.96 (2.7%) stddev-sans-first:0.87 17:55:06 INFO - PROCESS | 24465 | Values: 101.0 110.0 110.0 110.0 112.0 110.0 109.0 110.0 109.0 110.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:131.00 stddev:23.85 (18.2%) stddev-sans-first:25.08 17:55:06 INFO - PROCESS | 24465 | Values: 142.0 116.0 117.0 189.0 117.0 120.0 144.0 118.0 152.0 118.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 17:55:06 INFO - PROCESS | 24465 | Values: 161.0 162.0 160.0 161.0 160.0 159.0 160.0 160.0 161.0 160.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:16.29 (21.7%) stddev-sans-first:17.06 17:55:06 INFO - PROCESS | 24465 | Values: 75.0 75.0 74.0 123.0 77.0 76.0 75.0 74.0 100.0 75.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:84.50 stddev:17.87 (21.1%) stddev-sans-first:18.54 17:55:06 INFO - PROCESS | 24465 | Values: 85.0 84.0 123.0 85.0 85.0 83.0 119.0 84.0 85.0 122.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:55:06 INFO - PROCESS | 24465 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 17:55:06 INFO - PROCESS | 24465 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 147.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 17:55:06 INFO - PROCESS | 24465 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 95.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.50 stddev:1.17 (2.2%) stddev-sans-first:1.24 17:55:06 INFO - PROCESS | 24465 | Values: 54.0 53.0 56.0 54.0 53.0 55.0 53.0 53.0 53.0 52.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 17:55:06 INFO - PROCESS | 24465 | Values: 62.0 61.0 62.0 62.0 62.0 62.0 61.0 62.0 62.0 62.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#10] stanford-crypto-aes Cycles:10 Average:77.40 Median:76.00 stddev:2.99 (3.9%) stddev-sans-first:3.13 17:55:06 INFO - PROCESS | 24465 | Values: 76.0 76.0 76.0 79.0 75.0 76.0 76.0 76.0 85.0 79.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:126.50 stddev:13.95 (11.0%) stddev-sans-first:14.53 17:55:06 INFO - PROCESS | 24465 | Values: 121.0 126.0 137.0 127.0 123.0 128.0 119.0 119.0 165.0 120.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.90 Median:156.50 stddev:1.79 (1.1%) stddev-sans-first:1.90 17:55:06 INFO - PROCESS | 24465 | Values: 156.0 157.0 156.0 159.0 156.0 157.0 153.0 157.0 154.0 154.0 17:55:06 INFO - PROCESS | 24465 | 17:55:06 INFO - PROCESS | 24465 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:1.32 (2.1%) stddev-sans-first:1.22 17:55:06 INFO - PROCESS | 24465 | Values: 65.0 62.0 63.0 62.0 63.0 65.0 64.0 63.0 64.0 61.0 17:55:06 INFO - PROCESS | 24465 | -------- Summary: end -------- 17:55:06 INFO - PROCESS | 24465 | 17:55:07 INFO - TEST-INFO | 24465: exit 0 17:55:07 INFO - TEST-OK | kraken | took 60667ms 17:55:07 INFO - SUITE-END | took 1174s 17:55:07 INFO - Completed test suite (00:19:34) 17:55:07 INFO - javascript benchmark 17:55:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3034.408290383376, 3045.1930238486802, 3063.5864158338622, 3081.7534597232197, 3101.6898310168986, 5194.584433245341, 5231.973840130799, 5266.368035835695, 5325.0, 5338.225957236198, 5100.540951314381, 5196.324477817879, 5257.658252213603, 5271.868203294919, 5303.098473259545, 4708.199606066968, 4768.7138771673635, 4795.808167673289, 4803.0, 4980.626453016021, 4875.268709693539, 4894.853154405374, 4926.679765815211, 4944.283078953549, 4945.9258111128365, 4713.198756211452, 4720.055988802236, 4785.90428191436, 4820.30105634681, 4895.192293271606, 4705.1530724469585, 4707.293905914106, 4710.31700403443, 4716.410448693913, 4829.468758436569, 4753.429588449374, 4757.286407038937, 4767.8569642910425, 4769.070031343889, 4779.498152693952, 5142.048720986592, 5215.687058776485, 5273.736313184326, 5291.947080529184, 5294.735263236823, 5655.37790843011, 5703.0, 5710.714464276811, 5715.342735585419, 5718.42815718429, 5689.260396148474, 5706.1440783882335, 5744.8851022979725, 5759.136129580555, 5764.07774756037, 5204.0, 5296.152615581488, 5350.839474815801, 5353.437889021636, 5433.8369848904595, 5901.468867801918, 5916.852078697981, 5928.110783382441, 5929.110633404937, 5933.2880054393745, 5699.772009119589, 5703.315602127688, 5706.600537962303, 5706.9714651426475, 5713.6857472838565, 5314.73426328682, 5625.240592520039, 5648.604597678205, 5655.604107712503, 5745.712714364265, 5657.236273103161, 5662.716864156776, 5666.235058267163, 5668.744906479218, 5708.571857110734, 5635.3237611486065, 5681.57388195887, 5698.059820129715, 5701.486866182064, 5713.885722285531, 4188.727732697365, 4291.785410729451, 4299.548547402478, 4334.371516130206, 4400.955990440055, 3867.168558759824, 3877.4959255297194, 3898.278818418545, 3908.6482216600643, 3910.8435662573747, 5393.892122157535, 5404.972975135099, 5408.3509978802795, 5414.160805075219, 5441.047816632074, 5599.832005039855, 5656.85857853557, 5697.401772813878, 5698.430156984268, 5717.285339332583, 5413.918791218135, 5414.07960646691, 5415.864603384947, 5417.295751552273, 5425.593080518976, 4929.975350123226, 4944.53026962438, 4950.554450099508, 4952.90094198114, 4998.375203099613, 5694.715264236772, 5716.371199168088, 5719.656820590694, 5770.740316685759, 5908.084246941731, 5611.6352437091455, 5616.634918730433, 5617.578681598815, 5617.747201375866, 5650.943490565041, 5767.884642307215, 5770.249867517364, 5774.855628609151, 5778.56660750437, 5779.971100144472, 5464.672119672832, 5489.231507589021, 5552.444755524577, 5577.163425486209, 5591.524720398649, 5672.035753921926, 5681.460261275172, 5712.571557133315, 5716.085426331767, 5728.942710572841, 5526.640768350045, 5579.358373787067, 5579.581531385081, 5581.720913954205, 5583.0, 5428.321459817523, 5442.591805614516, 5447.291852059207, 5449.64577302474, 5566.638168519034, 5450.727463626724, 5451.018816613047, 5451.127819548853, 5457.099578569491, 5488.506034456918, 5499.835004949858, 5542.695151766691, 5590.105583106683, 5597.160425936142, 5600.411956744483, 5330.120530112488, 5330.733463326741, 5362.892742145214, 5386.676799392049, 5401.3248343957], "value": 5221.339891104092, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.7438964050639, 602.3795490644636, 607.4411541381926, 616.1374076293187, 627.4478458956117, 29645.11064668058, 30148.095557133292, 32574.67425325746, 32701.67298327016, 32701.67298327016, 44250.114997700024, 45293.32060019094, 45508.772456137754, 48488.27267590989, 48622.513774862244, 8990.595423205956, 9126.954365228197, 9128.58921348541, 9129.087091290885, 9129.95435022824, 8998.19016288534, 9067.455952642862, 9069.093090690943, 9096.181343679067, 9100.681476148337, 9084.137006984325, 9156.633734650639, 9161.221296189831, 9163.175314221719, 9166.816663666723, 9102.954485227565, 9136.451812891248, 9148.588313525874, 9149.771255718593, 9167.587458564381, 9008.459492430475, 9051.547422628842, 9061.68284110053, 9070.138336857988, 9074.86387704185, 42907.0, 43107.35338969918, 43192.13615727699, 43230.56769432297, 43292.350614740804, 3534.91162720934, 3687.727733931816, 3711.7030637548933, 3799.4490798834013, 4199.559046300125, 32774.0, 33280.16799580005, 33310.50034249488, 33551.0, 34574.30851382958, 3887.7278590498677, 4095.5699651536456, 4138.275801734684, 4197.307444271692, 4253.766042867641, 349642.0, 354229.0, 354581.0, 355180.0, 357398.0, 58791.0, 59120.704396478606, 59149.112763308585, 59173.40826591679, 59224.703876480344, 62260.0, 62387.6880615594, 62531.687341563, 62549.68725156436, 62569.0, 15624.531264062096, 15738.92130539363, 15765.68468630621, 15823.0, 15841.920790395974, 12076.516939322504, 12134.393280335948, 12146.878531214752, 12150.149489535825, 12153.0, 15521.379144834305, 16984.83015169857, 16998.235079421207, 17089.401870934707, 17201.827981720275, 5990.472429530442, 6092.45167934888, 6103.481204097613, 6236.656983865931, 6387.0, 709.1239054066064, 1084.5499117865998, 1123.1127409346689, 1123.3821398231103, 1141.851559297286, 31748.523772143435, 33567.0, 33624.66375336264, 33781.83109084439, 34121.65878341185, 1097.3854641400842, 1109.1016276815806, 1166.55087791201, 1173.865882108203, 1229.6003798765437, 17492.737608935877, 17536.0, 17574.384896528816, 17668.646627067643, 17695.0, 544887.0, 553613.0, 554215.0, 555126.0, 555538.0, 58890.0, 59036.40963590395, 59120.0, 59199.0, 59288.70355648194, 1209.504103317653, 1325.1651459580596, 1358.117223804535, 1358.44303835427, 1364.4951367993906, 30725.231869202555, 34142.658573413944, 34397.31205375829, 34667.0, 34790.82604586961, 51843.22235166551, 53290.73354633357, 53299.733501332244, 53320.46679533155, 53340.46659533355, 470273.0, 475078.0, 476228.0, 476269.0, 476385.0, 43194.35208471813, 44471.33293000543, 44478.332825007004, 47612.38131902779, 48854.801533930346, 49601.25598116103, 50344.74827625985, 50458.24312635387, 50514.242286365, 50853.237201442746, 39591.0, 43239.0, 44093.33859992167, 44699.55300446954, 45925.0, 43397.04713287846, 44771.55228447674, 45951.0, 46904.0, 47380.52619473899, 41642.375364368956, 42138.57861421469, 42322.576774231864, 42480.78759606182, 43200.56799431965, 36291.81854090712, 39057.41413878851, 39358.42566297316, 39516.80241598774, 39551.0, 29135.85432072826, 29513.114606561838, 29591.408171836872, 29856.85071574628, 29982.550261745655], "value": 20562.852238922827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.0599705757671, 368.54792119050956, 379.94870692456516, 385.9652631263186, 406.3498402555911, 16036.839631603694, 17958.91020544897, 19693.901530492327, 20077.698834517494, 20632.27787027454, 18497.90751046246, 19001.61996760064, 19002.33491827783, 19456.0, 19634.312799052037, 5717.971410142944, 5929.495992840613, 5938.554608404365, 5941.257342832146, 6145.661988590626, 6523.510736694743, 6668.733250669968, 6676.599404035749, 6678.165229346332, 6680.031395447657, 6432.195975503062, 6462.450691691212, 6531.542792004561, 6565.179352580927, 6632.137822083122, 6315.905261421082, 6557.639329836857, 6588.8352791180605, 6590.967045164792, 6595.769148079794, 5601.943980560224, 5630.5495560355075, 5635.774569017234, 5636.351819540764, 5642.266505354278, 23275.0, 23596.0, 23856.284311470685, 23872.403189920216, 23935.401614959766, 2070.171931227521, 2218.8779617121054, 2504.060977133575, 2574.8068894832963, 2835.3620435402077, 9933.80132397348, 10160.0, 10172.59309627614, 10225.642102526375, 10532.05211530966, 1926.2969016308946, 1953.736245606839, 1954.8631595788302, 1993.4617653233545, 2000.0, 81710.59144704299, 84671.15328846694, 84896.57551712263, 85791.0, 86136.0, 10634.574617015232, 10715.517801698943, 10797.784044319069, 11030.0, 11189.72025699364, 15177.013494122846, 15194.0, 15195.164265965477, 15211.619709507351, 15213.695726085416, 7378.852422951511, 7516.774496764996, 7692.807679807937, 7808.843823123506, 7810.726624568227, 7165.92834071663, 7171.354578087997, 7303.7443689471675, 7392.63236301286, 7414.518056326321, 4650.627949764011, 4694.554017368345, 4698.718076915396, 4716.858494245178, 4776.450708168536, 1904.7523821903342, 1917.1947781931344, 1951.658459769563, 1951.9121639526256, 1962.5093726568357, 966.183574879226, 982.9754256143653, 990.7919336939324, 1023.150784848574, 1048.3500229857536, 19662.91853948018, 20171.59656806885, 20205.797942020392, 20239.291624793063, 20490.18039278412, 930.2093220762298, 965.9178969787647, 970.3450171134456, 987.9555420006117, 1011.9576835859077, 9162.67930622425, 10498.73753156162, 10549.367037977747, 11158.1631377647, 11188.307858406706, 439767.0, 461564.0, 507092.0, 521701.0, 522968.0, 61093.0, 62033.0, 63089.684551576946, 99903.00581965105, 101424.0, 1001.6711146584221, 1036.3988886445993, 1047.8847326794046, 1058.952347144365, 1062.654637242899, 17796.288148473915, 17933.282668693628, 18598.163082661584, 18635.44093677192, 18871.339503117324, 33179.50230746589, 42737.786311068245, 43309.0, 44375.55624443714, 44909.10181796411, 55560.72219638876, 58240.4175958235, 59159.40840591539, 60091.0, 60245.69877150762, 6730.629815360006, 7664.811954147116, 8666.306695464222, 8750.693725719824, 8819.867701984347, 11768.646605640119, 12304.892559669674, 13307.600771976857, 13325.33373331349, 13631.795523066963, 11526.193166478266, 11715.648530544097, 11744.94127529357, 11771.293722376684, 12164.330961797186, 8254.83490330202, 8661.870071949052, 11429.885701142883, 11806.763864722485, 11887.227330223508, 11761.764764704829, 11911.940440298089, 12149.453274602842, 12230.205036672587, 12547.560835370718, 8384.455010424303, 8449.366297527587, 8572.4427912188, 8625.611847467004, 8748.956255218938, 9703.6118555257, 9867.371883639405, 11221.71945701364, 11513.827292590786, 11973.102017348909], "value": 9236.569093883056, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.6624660711518, 1450.6300893272216, 1522.383434708943, 1633.175246500518, 1785.134209908195, 8031.237032481912, 8339.791505212374, 8433.578321083944, 8657.69698060568, 8663.740087797376, 8431.95784021079, 8511.148885111486, 8524.275436587897, 8547.65809367626, 8581.184787445183, 2289.6794448777187, 2314.456102815837, 2315.617923042704, 2315.78000089992, 2318.8840557972117, 2277.9886100569556, 2325.9651105233434, 2334.8248881333884, 2337.1586228957563, 2337.3689103941924, 2268.8071513921336, 2274.1358283852114, 2300.7469178390365, 2306.6655334976417, 2309.1109922679807, 2158.460384903774, 2189.211883721859, 2204.9669254961186, 2217.9464754241685, 2219.1234462387347, 2219.9223027194134, 2249.415152060476, 2250.2124256510256, 2252.662100684894, 2256.435891027243, 8567.400281980264, 8631.568421578895, 8667.566621668955, 8691.130886911258, 8697.69558065471, 4889.633277504166, 5038.445770965191, 5060.2409638554145, 5066.69599824008, 5069.087564238435, 3123.9219019524467, 3290.8683652653867, 3341.3150304187584, 3348.8493017814258, 3354.077628652116, 1058.7035630023606, 1068.9412082335468, 1079.228351728518, 1087.1465899269035, 1092.3718861654502, 11956.282623042558, 11966.64100076999, 12004.639860804189, 12013.87986120145, 12086.576969806103, 4067.206894655573, 4237.703360764778, 4266.104118135165, 4335.479742430928, 4347.0, 9419.15227629503, 9452.338336316392, 9480.431174129575, 9483.0, 9493.620255189853, 4983.426905905867, 4995.550400463903, 5000.274960130833, 5001.549860512571, 5012.223105418593, 4212.49450065994, 4550.954490455119, 4681.040386720714, 4710.929336059962, 4748.762561871893, 3513.4378499440477, 3563.7327200460068, 3624.2932628137787, 3651.543557055368, 3698.260347930425, 1015.9238057145743, 1020.5611587017654, 1033.7105610429091, 1038.0, 1050.8896565860628, 878.7319867440365, 949.7388218239901, 965.6909788867636, 981.1218959031769, 984.601236499219, 5905.409459054146, 5978.581499295007, 6061.212042434456, 6120.17377654011, 6180.907286390708, 407.10233934175074, 415.7193894121456, 416.95621959693796, 418.04893866453455, 982.6266018912768, 4077.388391741203, 4143.81352839123, 4149.439825623563, 4186.246475634415, 4189.120284740238, 10560.366378017343, 11007.119430445546, 11019.173561982883, 11021.283616564897, 11027.83458248127, 10128.797424051625, 10782.568697252022, 10810.135189184846, 10840.78318433627, 10864.130869530418, 7800.258975397328, 7994.360451163893, 8135.633896474673, 8139.837203255902, 8153.266206041603, 6115.205023346936, 6151.96924015377, 6161.383861613803, 6174.44430001302, 6365.108884756045, 9646.324757266924, 9870.950645246729, 9916.851247231441, 9947.0, 9976.501174941079, 10115.848262276219, 10121.69634910954, 10140.54367553447, 10143.188544916242, 10143.543540540546, 6782.979908124987, 7410.814729631802, 7558.58427786477, 8315.708950186714, 8480.703175388831, 7763.844723105619, 8371.790705232417, 8381.916180838114, 8392.076871544248, 8408.705695300636, 7258.3104605062435, 7267.382272506896, 7280.344768970818, 7371.262873712586, 7458.291462311072, 6990.965045174741, 7058.470614704021, 7079.221285658677, 7097.680604372919, 7118.750843720445, 6847.828804279933, 6874.725010999504, 6955.965220173866, 6959.686814093479, 6995.845685461842, 5127.051495473349, 5128.384593848762, 5186.948130518646, 5234.528892399702, 5336.306280183551, 5160.329157209539, 5213.947860521346, 5402.297701298963, 5486.177073439016, 5554.527865131347], "value": 4439.134045973297, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.80631621027374, 323.8477915379771, 336.07411581094095, 338.08547877990037, 338.78995023085685, 5270.262163297137, 5286.334804536573, 5314.468553144684, 5331.413544510101, 5393.9190912136255, 4994.827250146189, 5138.66598671087, 5151.587872970163, 5199.402068762093, 5332.653377530458, 4007.6994225433064, 4074.816633251496, 4098.938515922264, 4112.547619761824, 4149.543550209474, 4144.191882582898, 4156.625903668669, 4159.459270294872, 4161.625453709165, 4165.83336666533, 4080.795960201993, 4111.136661301115, 4115.876523704279, 4122.546519882811, 4134.338505839067, 4099.487564054493, 4119.237940980928, 4120.711550191488, 4125.731827431237, 4149.668026557883, 3689.096171438015, 3691.2248427830186, 3699.1676872703697, 3705.370087085175, 3721.1999420124803, 5090.211017292326, 5172.146595811687, 5214.8696282592855, 5217.869553261161, 5220.216967454912, 4714.952850471485, 4716.858494245143, 4718.419329524579, 4720.197566413718, 4747.881302967419, 5454.59090568205, 5470.015397228497, 5471.124620060771, 5471.945280547184, 5486.807961721321, 3786.6024067473068, 4019.537753158395, 4037.8895803653936, 4061.1674606705556, 4070.776107314126, 5613.045782217033, 5639.2668953035845, 5648.435156484319, 5676.375598684142, 5683.0338842396895, 3961.7821019844187, 3988.6210809973004, 3989.4268197013344, 4003.2393845169327, 4014.136960553495, 4013.5986401360215, 4029.597040295947, 4034.636882680573, 4050.124411667749, 4073.103917138225, 4516.277395616686, 4583.175028494903, 4591.931121033187, 4640.79116439761, 4652.116097941381, 5463.289772329572, 5475.561955043587, 5486.91769623456, 5489.204065410493, 5533.474319939599, 1329.5109477385197, 1400.9719805603831, 1406.2898236390558, 1410.3864818803854, 1413.4416905322546, 870.2907130688469, 879.4898958603994, 893.7095443980733, 928.3176865004217, 937.7093101138669, 4784.234522476457, 4796.2805579162705, 4799.808007679654, 4808.591269742041, 4812.302216178635, 5045.621578381636, 5052.942969503471, 5076.086304465158, 5135.332406787168, 5144.4855514449, 4647.855079664316, 4702.459217190067, 4725.5746982771025, 4755.262934245197, 4785.449673287547, 2686.341846247682, 2686.6641669791275, 2688.623592697024, 2688.7042425333198, 2719.741624545665, 5089.923651145235, 5132.5124113209185, 5171.612129090333, 5279.894402111936, 5285.806390347962, 5387.595930305243, 5767.0, 5786.42135786418, 5801.7389217485315, 5806.274215723035, 5832.358440571534, 5863.7947671832135, 5864.9413505864395, 5868.589198756132, 5929.169916211647, 5548.167774833807, 5627.943720562742, 5669.376368599368, 5720.11338242573, 5734.741936612779, 5599.636023658449, 5608.803691870765, 5614.512154279201, 5621.437856214509, 5629.662220266797, 5786.479216870502, 5790.623609465371, 5800.709964501673, 5814.592978491465, 5881.117832325185, 3875.6511913927884, 4034.556198818069, 4067.288224560631, 4067.3898915162963, 4092.181563687334, 5612.7193640317, 5647.20939068539, 5672.376038635829, 5673.886522269614, 5712.428757124254, 5361.973190134025, 5558.082916318924, 5571.331440227199, 5573.637713548606, 5580.832575022756, 5534.861628459321, 5545.916811247754, 5555.916661250165, 5564.304461942257, 5566.526845218202, 5252.369715634149, 5311.548518375981, 5442.619016668795, 5502.559795216293, 5505.614606977473, 5519.503244707996, 5536.307961504812, 5555.72221388937, 5574.91637625444, 5577.9442205577425, 5418.320320700595, 5430.456954304538, 5433.56531477473, 5450.400455949922, 5485.50630443262], "value": 4146.57966266133, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.8389954210073, 810.3962547901814, 820.6307161777202, 831.5260301628073, 838.3120527222794, 15682.0, 16588.0, 16614.667706645876, 16654.66690666186, 16671.1664416779, 15243.0, 15337.923310383432, 15406.460773872917, 15418.0, 15423.0, 646.5894157210158, 790.7074382478492, 808.2846680687575, 814.698561532234, 815.5514467043132, 789.6999140326668, 804.7706403674954, 811.1604489353521, 825.8761185822115, 827.1108558299821, 870.9940019277713, 871.2724874729577, 873.9825203495926, 874.2699845628907, 878.3587980774039, 673.0812441017976, 872.0973792125152, 873.2533683700472, 873.3319010956592, 873.3668090634271, 609.0742072050502, 618.8638499530141, 626.6052386996163, 634.0489266100849, 635.2726128582793, 14696.0, 14700.411983520646, 14736.115833050051, 14738.705225895423, 14780.556583302518, 14212.0, 14328.0, 14899.702005959929, 15031.098134111988, 15100.395984160621, 12074.08328154133, 12096.455659495345, 12327.75344493114, 12402.441890114966, 12809.231446113263, 4411.977940110311, 4446.688731788744, 4459.687821852472, 4644.0, 4672.929906051412, 77930.22069779286, 78102.60948695277, 78111.0, 79136.60431697863, 79177.60411197964, 11451.0, 11629.941850290694, 11703.824442633368, 11873.228240164535, 12354.320512371725, 21677.748337749465, 21741.0, 21756.89121554382, 21767.455813604785, 21789.564208715736, 8637.870431943526, 8659.48043117415, 8662.350323725746, 8685.522296273639, 8885.600147993226, 4649.976750116228, 4667.253239481734, 4724.590458866003, 4949.950500495021, 5014.824481143142, 3456.4296891013205, 3546.4680297955515, 3559.43049112146, 3561.661642143992, 3563.2160924596546, 597.4503456819737, 601.485729701108, 605.2615808713363, 616.0451300484232, 624.7407325959766, 653.8724948635065, 661.3089321658782, 667.9499037572104, 671.3655595462338, 678.2437582095928, 9207.815843683224, 9471.301955076131, 9530.857037144448, 9621.326507144573, 9795.951020244853, 579.4466284698103, 615.1572345886165, 621.1645337021698, 647.3300134360913, 661.2792056658266, 4567.680262381601, 4920.335754673145, 4943.887625284282, 4966.038414826124, 4981.651284410056, 111335.44332278287, 114848.0, 115229.0, 118258.40870795591, 118720.0, 100065.0, 100605.0, 100751.49624251979, 100795.49602251942, 104127.4793626027, 78982.21017789979, 84087.5795621018, 90804.09195908089, 90832.0, 90984.0, 10175.0, 10190.592376304865, 10193.796124077626, 10248.797992823205, 10434.947825260826, 48031.0, 48479.0, 52757.681057972324, 54181.0, 54343.184852226455, 52514.0, 63002.68498657477, 73972.0, 75145.24854751382, 77205.2279477198, 12701.491940322283, 12769.361531923552, 13557.525486607921, 14000.649983750487, 14063.085899416505, 14598.781018284522, 14725.484608038667, 14899.47851825181, 15072.472463464164, 15360.23198840076, 14426.206558639376, 14457.85542144565, 14488.420463181355, 14748.33632486562, 14898.25508724581, 14446.494372696905, 14511.564653060424, 14974.160206718174, 14987.775183372476, 15011.549653510412, 14678.0, 14814.925925370304, 14982.850171498581, 15092.471763488225, 15265.746659497832, 11470.254433461798, 11562.653120406401, 11879.881201188224, 12019.519219231483, 12249.816252756395, 10041.698749037541, 11405.543778248777, 11474.885251147381, 11639.47622356979, 11755.294682319089], "value": 7693.952367032427, "unit": "score"}], "name": "dromaeo_css", "value": 7209.86464782345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 112.0, 110.0, 109.0, 110.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 117.0, 189.0, 117.0, 120.0, 144.0, 118.0, 152.0, 118.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 159.0, 160.0, 160.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 123.0, 77.0, 76.0, 75.0, 74.0, 100.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 123.0, 85.0, 85.0, 83.0, 119.0, 84.0, 85.0, 122.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 56.0, 54.0, 53.0, 55.0, 53.0, 53.0, 53.0, 52.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 79.0, 75.0, 76.0, 76.0, 76.0, 85.0, 79.0], "value": 77.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 126.0, 137.0, 127.0, 123.0, 128.0, 119.0, 119.0, 165.0, 120.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 156.0, 159.0, 156.0, 157.0, 153.0, 157.0, 154.0, 154.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 62.0, 63.0, 65.0, 64.0, 63.0, 64.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1478.3000000000002, "alertThreshold": 2.0}]} 17:55:07 INFO - Return code: 0 17:55:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:55:07 INFO - # TBPL SUCCESS # 17:55:07 INFO - Running post-action listener: _resource_record_post_action 17:55:07 INFO - [mozharness: 2016-07-27 00:55:07.976245Z] Finished run-tests step (success) 17:55:07 INFO - Running post-run listener: _resource_record_post_run 17:55:08 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 559919104; Read time: 8; Write time: 442788 17:55:08 INFO - TinderboxPrint: CPU usage
13.0% 17:55:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:55:08 INFO - TinderboxPrint: I/O write bytes / time
559,919,104 / 442,788 17:55:08 INFO - TinderboxPrint: CPU idle
8,273.0 (87.0%) 17:55:08 INFO - TinderboxPrint: CPU user
1,195.9 (12.6%) 17:55:08 INFO - TinderboxPrint: Swap in / out
0 / 0 17:55:08 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 260833280; Read time: 0; Write time: 240468 17:55:08 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 291860480; Read time: 0; Write time: 199580 17:55:08 INFO - Running post-run listener: _upload_blobber_files 17:55:08 INFO - Blob upload gear active. 17:55:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:55:08 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 17:55:08 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:55:08 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:55:09 INFO - (blobuploader) - INFO - Open directory for files ... 17:55:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:55:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:55:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:09 INFO - (blobuploader) - INFO - Done attempting. 17:55:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:55:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:55:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:10 INFO - (blobuploader) - INFO - Done attempting. 17:55:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:55:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:55:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:14 INFO - (blobuploader) - INFO - Done attempting. 17:55:14 INFO - (blobuploader) - INFO - Iteration through files over. 17:55:14 INFO - Return code: 0 17:55:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:55:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:55:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9aed81f7a7696fdb441d190f121bc4a235712dfdbc26bc98048f30ed6b9d7b87c92f8a7225398794fb06084b0497e07b6e1431e4c3925b3af994e248db17b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab63e6df60fb634b1fee893969b92bf026157fd40177c737a4d83392390f5254ef8a6a9502ec7d77079fa6a6099b6900041341a960ea3eaf0242022d690a8984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b0cac21466b2b6851333054967214444f9fbf7a5a71f8d5691ca269efba28b9cb88aafb13708e159c88adec6b0ca476a0dd93779ea48ec4f1703af8d0b561ee"} 17:55:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:55:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:55:14 INFO - Contents: 17:55:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9aed81f7a7696fdb441d190f121bc4a235712dfdbc26bc98048f30ed6b9d7b87c92f8a7225398794fb06084b0497e07b6e1431e4c3925b3af994e248db17b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab63e6df60fb634b1fee893969b92bf026157fd40177c737a4d83392390f5254ef8a6a9502ec7d77079fa6a6099b6900041341a960ea3eaf0242022d690a8984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b0cac21466b2b6851333054967214444f9fbf7a5a71f8d5691ca269efba28b9cb88aafb13708e159c88adec6b0ca476a0dd93779ea48ec4f1703af8d0b561ee"} 17:55:14 INFO - Running post-run listener: copy_logs_to_upload_dir 17:55:14 INFO - Copying logs to upload dir... 17:55:14 INFO - mkdir: /builds/slave/test/build/upload/logs 17:55:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1226.367845 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-07-26 17:55:13.357821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 17:55:13.362116) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9aed81f7a7696fdb441d190f121bc4a235712dfdbc26bc98048f30ed6b9d7b87c92f8a7225398794fb06084b0497e07b6e1431e4c3925b3af994e248db17b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab63e6df60fb634b1fee893969b92bf026157fd40177c737a4d83392390f5254ef8a6a9502ec7d77079fa6a6099b6900041341a960ea3eaf0242022d690a8984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b0cac21466b2b6851333054967214444f9fbf7a5a71f8d5691ca269efba28b9cb88aafb13708e159c88adec6b0ca476a0dd93779ea48ec4f1703af8d0b561ee"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011432 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469568610/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9aed81f7a7696fdb441d190f121bc4a235712dfdbc26bc98048f30ed6b9d7b87c92f8a7225398794fb06084b0497e07b6e1431e4c3925b3af994e248db17b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab63e6df60fb634b1fee893969b92bf026157fd40177c737a4d83392390f5254ef8a6a9502ec7d77079fa6a6099b6900041341a960ea3eaf0242022d690a8984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b0cac21466b2b6851333054967214444f9fbf7a5a71f8d5691ca269efba28b9cb88aafb13708e159c88adec6b0ca476a0dd93779ea48ec4f1703af8d0b561ee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 17:55:13.389598) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:55:13.390020) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469579686.16158-111707112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 17:55:13.473284) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 17:55:13.473831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 17:55:13.474278) ========= ========= Total master_lag: 0.17 =========