builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1472290184.88 results: success (0) buildid: 20160827004713 builduid: 45ef404c581e40a3a2fc9fa671d67b5c revision: 087a40f5eaa909ceea0d41f0357c50ee3df47a4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:44.882671) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:44.883136) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:44.883461) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:44.898263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:44.898588) ========= 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=920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-27 02:29:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.4M=0s 2016-08-27 02:29:45 (89.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088620 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.004981) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.005334) ========= 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=920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019030 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.058952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.059368) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 087a40f5eaa909ceea0d41f0357c50ee3df47a4d --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 087a40f5eaa909ceea0d41f0357c50ee3df47a4d --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=920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-27 02:29:46,089 truncating revision to first 12 chars 2016-08-27 02:29:46,089 Setting DEBUG logging. 2016-08-27 02:29:46,089 attempt 1/10 2016-08-27 02:29:46,090 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/087a40f5eaa9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-27 02:29:46,381 unpacking tar archive at: fx-team-087a40f5eaa9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.678073 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.753241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.753608) ========= script_repo_revision: 087a40f5eaa909ceea0d41f0357c50ee3df47a4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.754064) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.754367) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 02:29:45.765084) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-27 02:29:45.765383) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:29:46 INFO - MultiFileLogger online at 20160827 02:29:46 in /builds/slave/test 02:29:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 02:29:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:29:46 INFO - {'append_to_log': False, 02:29:46 INFO - 'base_work_dir': '/builds/slave/test', 02:29:46 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 02:29:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:29:46 INFO - 'branch': 'Fx-Team-Non-PGO', 02:29:46 INFO - 'buildbot_json_path': 'buildprops.json', 02:29:46 INFO - 'config_files': ('talos/linux_config.py',), 02:29:46 INFO - 'default_actions': ('clobber', 02:29:46 INFO - 'read-buildbot-config', 02:29:46 INFO - 'download-and-extract', 02:29:46 INFO - 'populate-webroot', 02:29:46 INFO - 'create-virtualenv', 02:29:46 INFO - 'install', 02:29:46 INFO - 'run-tests'), 02:29:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:29:46 INFO - 'download_minidump_stackwalk': True, 02:29:46 INFO - 'download_symbols': 'ondemand', 02:29:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:29:46 INFO - 'tooltool.py': '/tools/tooltool.py', 02:29:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:29:46 INFO - '/tools/misc-python/virtualenv.py')}, 02:29:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:29:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:29:46 INFO - 'installer_path': 'installer.exe', 02:29:46 INFO - 'log_level': 'info', 02:29:46 INFO - 'log_name': 'talos', 02:29:46 INFO - 'log_to_console': True, 02:29:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:29:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:29:46 INFO - 'opt_config_files': (), 02:29:46 INFO - 'pip_index': False, 02:29:46 INFO - 'sps_profile': False, 02:29:46 INFO - 'sps_profile_interval': 0, 02:29:46 INFO - 'suite': 'dromaeojs', 02:29:46 INFO - 'system_bits': '32', 02:29:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:29:46 INFO - 'title': 'talos-linux64-ix-053', 02:29:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:29:46 INFO - 'use_talos_json': True, 02:29:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:29:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:29:46 INFO - 'work_dir': 'build'} 02:29:46 INFO - [mozharness: 2016-08-27 09:29:46.887062Z] Running clobber step. 02:29:46 INFO - Running pre-action listener: _resource_record_pre_action 02:29:46 INFO - Running main action method: clobber 02:29:46 INFO - rmtree: /builds/slave/test/build 02:29:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:29:47 INFO - Running post-action listener: _resource_record_post_action 02:29:47 INFO - [mozharness: 2016-08-27 09:29:47.366771Z] Finished clobber step (success) 02:29:47 INFO - [mozharness: 2016-08-27 09:29:47.366873Z] Running read-buildbot-config step. 02:29:47 INFO - Running pre-action listener: _resource_record_pre_action 02:29:47 INFO - Running main action method: read_buildbot_config 02:29:47 INFO - Using buildbot properties: 02:29:47 INFO - { 02:29:47 INFO - "project": "", 02:29:47 INFO - "product": "firefox", 02:29:47 INFO - "script_repo_revision": "production", 02:29:47 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:29:47 INFO - "repository": "", 02:29:47 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 02:29:47 INFO - "buildid": "20160827004713", 02:29:47 INFO - "pgo_build": "False", 02:29:47 INFO - "basedir": "/builds/slave/test", 02:29:47 INFO - "buildnumber": 3259, 02:29:47 INFO - "slavename": "talos-linux64-ix-053", 02:29:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:29:47 INFO - "platform": "ubuntu64_hw", 02:29:47 INFO - "scheduler": "tests-fx-team-linux64-talos", 02:29:47 INFO - "branch": "fx-team", 02:29:47 INFO - "repo_path": "integration/fx-team", 02:29:47 INFO - "revision": "087a40f5eaa909ceea0d41f0357c50ee3df47a4d", 02:29:47 INFO - "stage_platform": "linux64", 02:29:47 INFO - "builduid": "45ef404c581e40a3a2fc9fa671d67b5c", 02:29:47 INFO - "slavebuilddir": "test" 02:29:47 INFO - } 02:29:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 02:29:47 INFO - Running post-action listener: _resource_record_post_action 02:29:47 INFO - [mozharness: 2016-08-27 09:29:47.369310Z] Finished read-buildbot-config step (success) 02:29:47 INFO - [mozharness: 2016-08-27 09:29:47.369399Z] Running download-and-extract step. 02:29:47 INFO - Running pre-action listener: _resource_record_pre_action 02:29:47 INFO - Running main action method: download_and_extract 02:29:47 INFO - mkdir: /builds/slave/test/build/tests 02:29:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:47 INFO - trying https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:29:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:29:47 INFO - Downloaded 1361 bytes. 02:29:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:29:47 INFO - Using the following test package requirements: 02:29:47 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:29:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:29:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:29:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'jsshell-linux-x86_64.zip'], 02:29:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:29:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:29:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:29:47 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:29:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:29:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:29:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:29:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:47 INFO - trying https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:29:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:29:48 INFO - Downloaded 20304670 bytes. 02:29:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 02:29:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:53 INFO - trying https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:29:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:29:53 INFO - Downloaded 20304670 bytes. 02:29:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 02:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:58 INFO - trying https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:29:58 INFO - Downloading https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:29:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:29:59 INFO - Downloaded 14413998 bytes. 02:29:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 02:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:59 INFO - trying https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:29:59 INFO - Downloading https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:29:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:30:00 INFO - Downloaded 55858857 bytes. 02:30:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:30:00 INFO - mkdir: /builds/slave/test/properties 02:30:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:30:00 INFO - Writing to file /builds/slave/test/properties/build_url 02:30:00 INFO - Contents: 02:30:00 INFO - build_url:https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:30:01 INFO - Running post-action listener: _resource_record_post_action 02:30:01 INFO - Running post-action listener: set_extra_try_arguments 02:30:01 INFO - [mozharness: 2016-08-27 09:30:01.554914Z] Finished download-and-extract step (success) 02:30:01 INFO - [mozharness: 2016-08-27 09:30:01.555142Z] Running populate-webroot step. 02:30:01 INFO - Running pre-action listener: _resource_record_pre_action 02:30:01 INFO - Running main action method: populate_webroot 02:30:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:30:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:30:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:30:01 INFO - '--tppagecycles', 02:30:01 INFO - '7'], 02:30:01 INFO - 'tests': ['tsvgm']}}, 02:30:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:30:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:30:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:30:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:30:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['damp', 'tps']}, 02:30:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'tests': ['damp', 'tps']}, 02:30:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['dromaeo_dom']}, 02:30:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:30:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['basic_compositor_video']}, 02:30:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:30:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['a11yr', 02:30:01 INFO - 'ts_paint', 02:30:01 INFO - 'tpaint', 02:30:01 INFO - 'sessionrestore', 02:30:01 INFO - 'sessionrestore_no_auto_restore', 02:30:01 INFO - 'tabpaint']}, 02:30:01 INFO - 'other-e10s': {'tests': ['a11yr', 02:30:01 INFO - 'ts_paint', 02:30:01 INFO - 'tpaint', 02:30:01 INFO - 'sessionrestore', 02:30:01 INFO - 'sessionrestore_no_auto_restore', 02:30:01 INFO - 'tabpaint']}, 02:30:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:30:01 INFO - 'ts_paint', 02:30:01 INFO - 'tpaint', 02:30:01 INFO - 'sessionrestore', 02:30:01 INFO - 'sessionrestore_no_auto_restore', 02:30:01 INFO - 'tabpaint']}, 02:30:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:30:01 INFO - 'ts_paint', 02:30:01 INFO - 'tpaint', 02:30:01 INFO - 'sessionrestore', 02:30:01 INFO - 'sessionrestore_no_auto_restore', 02:30:01 INFO - 'tabpaint']}, 02:30:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['a11yr', 02:30:01 INFO - 'ts_paint', 02:30:01 INFO - 'tpaint', 02:30:01 INFO - 'sessionrestore', 02:30:01 INFO - 'sessionrestore_no_auto_restore', 02:30:01 INFO - 'tabpaint']}, 02:30:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['a11yr', 02:30:01 INFO - 'ts_paint', 02:30:01 INFO - 'tpaint', 02:30:01 INFO - 'sessionrestore', 02:30:01 INFO - 'sessionrestore_no_auto_restore', 02:30:01 INFO - 'tabpaint']}, 02:30:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['tsvgx', 02:30:01 INFO - 'tsvgr_opacity', 02:30:01 INFO - 'tart', 02:30:01 INFO - 'tscrollx', 02:30:01 INFO - 'cart']}, 02:30:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:30:01 INFO - 'tsvgr_opacity', 02:30:01 INFO - 'tart', 02:30:01 INFO - 'tscrollx', 02:30:01 INFO - 'cart']}, 02:30:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'talos_options': ['--disable-e10s'], 02:30:01 INFO - 'tests': ['tp5o']}, 02:30:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'tests': ['tp5o']}, 02:30:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'talos_options': ['--disable-e10s', 02:30:01 INFO - '--xperf_path', 02:30:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:01 INFO - 'tests': ['tp5n']}, 02:30:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:01 INFO - 'talos_options': ['--xperf_path', 02:30:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:01 INFO - 'tests': ['tp5n']}}, 02:30:01 INFO - 'talos.zip': {'path': '', 02:30:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:30:01 INFO - Running post-action listener: _resource_record_post_action 02:30:01 INFO - [mozharness: 2016-08-27 09:30:01.572734Z] Finished populate-webroot step (success) 02:30:01 INFO - [mozharness: 2016-08-27 09:30:01.572823Z] Running create-virtualenv step. 02:30:01 INFO - Running pre-action listener: _resource_record_pre_action 02:30:01 INFO - Running main action method: create_virtualenv 02:30:01 INFO - Creating virtualenv /builds/slave/test/build/venv 02:30:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:30:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:30:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:30:01 INFO - Using real prefix '/usr' 02:30:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:30:02 INFO - Installing distribute.............................................................................................................................................................................................done. 02:30:04 INFO - Installing pip.................done. 02:30:04 INFO - Return code: 0 02:30:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:30:04 INFO - Using env: {'DISPLAY': ':0', 02:30:04 INFO - 'HOME': '/home/cltbld', 02:30:04 INFO - 'LANG': 'en_US.UTF-8', 02:30:04 INFO - 'LANGUAGE': 'en_US:en', 02:30:04 INFO - 'LOGNAME': 'cltbld', 02:30:04 INFO - 'MAIL': '/var/mail/cltbld', 02:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:04 INFO - 'MOZ_NO_REMOTE': '1', 02:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:04 INFO - 'NO_EM_RESTART': '1', 02:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:04 INFO - 'PWD': '/builds/slave/test', 02:30:04 INFO - 'SHELL': '/bin/bash', 02:30:04 INFO - 'SHLVL': '1', 02:30:04 INFO - 'TERM': 'linux', 02:30:04 INFO - 'TMOUT': '86400', 02:30:04 INFO - 'USER': 'cltbld', 02:30:04 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:04 INFO - '_': '/tools/buildbot/bin/python'} 02:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:30:04 INFO - Cleaning up... 02:30:04 INFO - Return code: 0 02:30:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:30:04 INFO - Using env: {'DISPLAY': ':0', 02:30:04 INFO - 'HOME': '/home/cltbld', 02:30:04 INFO - 'LANG': 'en_US.UTF-8', 02:30:04 INFO - 'LANGUAGE': 'en_US:en', 02:30:04 INFO - 'LOGNAME': 'cltbld', 02:30:04 INFO - 'MAIL': '/var/mail/cltbld', 02:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:04 INFO - 'MOZ_NO_REMOTE': '1', 02:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:04 INFO - 'NO_EM_RESTART': '1', 02:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:04 INFO - 'PWD': '/builds/slave/test', 02:30:04 INFO - 'SHELL': '/bin/bash', 02:30:04 INFO - 'SHLVL': '1', 02:30:04 INFO - 'TERM': 'linux', 02:30:04 INFO - 'TMOUT': '86400', 02:30:04 INFO - 'USER': 'cltbld', 02:30:04 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:04 INFO - '_': '/tools/buildbot/bin/python'} 02:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:04 INFO - Downloading/unpacking psutil>=3.1.1 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:30:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:30:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:30:06 INFO - Installing collected packages: psutil 02:30:06 INFO - Running setup.py install for psutil 02:30:06 INFO - building 'psutil._psutil_linux' extension 02:30:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:30:06 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 02:30:06 INFO - building 'psutil._psutil_posix' extension 02:30:06 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 02:30:06 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 02:30:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:30:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:30:07 INFO - Successfully installed psutil 02:30:07 INFO - Cleaning up... 02:30:07 INFO - Return code: 0 02:30:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:30:07 INFO - Using env: {'DISPLAY': ':0', 02:30:07 INFO - 'HOME': '/home/cltbld', 02:30:07 INFO - 'LANG': 'en_US.UTF-8', 02:30:07 INFO - 'LANGUAGE': 'en_US:en', 02:30:07 INFO - 'LOGNAME': 'cltbld', 02:30:07 INFO - 'MAIL': '/var/mail/cltbld', 02:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:07 INFO - 'MOZ_NO_REMOTE': '1', 02:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:07 INFO - 'NO_EM_RESTART': '1', 02:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:07 INFO - 'PWD': '/builds/slave/test', 02:30:07 INFO - 'SHELL': '/bin/bash', 02:30:07 INFO - 'SHLVL': '1', 02:30:07 INFO - 'TERM': 'linux', 02:30:07 INFO - 'TMOUT': '86400', 02:30:07 INFO - 'USER': 'cltbld', 02:30:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:07 INFO - '_': '/tools/buildbot/bin/python'} 02:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:30:09 INFO - Installing collected packages: mozsystemmonitor 02:30:09 INFO - Running setup.py install for mozsystemmonitor 02:30:09 INFO - Successfully installed mozsystemmonitor 02:30:09 INFO - Cleaning up... 02:30:09 INFO - Return code: 0 02:30:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:30:09 INFO - Using env: {'DISPLAY': ':0', 02:30:09 INFO - 'HOME': '/home/cltbld', 02:30:09 INFO - 'LANG': 'en_US.UTF-8', 02:30:09 INFO - 'LANGUAGE': 'en_US:en', 02:30:09 INFO - 'LOGNAME': 'cltbld', 02:30:09 INFO - 'MAIL': '/var/mail/cltbld', 02:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:09 INFO - 'MOZ_NO_REMOTE': '1', 02:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:09 INFO - 'NO_EM_RESTART': '1', 02:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:09 INFO - 'PWD': '/builds/slave/test', 02:30:09 INFO - 'SHELL': '/bin/bash', 02:30:09 INFO - 'SHLVL': '1', 02:30:09 INFO - 'TERM': 'linux', 02:30:09 INFO - 'TMOUT': '86400', 02:30:09 INFO - 'USER': 'cltbld', 02:30:09 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:09 INFO - '_': '/tools/buildbot/bin/python'} 02:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:09 INFO - Downloading/unpacking jsonschema==2.5.1 02:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:30:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:11 INFO - Downloading functools32-3.2.3-2.tar.gz 02:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:30:11 INFO - warning: no files found matching '*.txt' 02:30:11 INFO - no previously-included directories found matching 'build' 02:30:11 INFO - no previously-included directories found matching 'dist' 02:30:11 INFO - no previously-included directories found matching '.git*' 02:30:11 INFO - Installing collected packages: jsonschema, functools32 02:30:11 INFO - Running setup.py install for functools32 02:30:11 INFO - warning: no files found matching '*.txt' 02:30:11 INFO - no previously-included directories found matching 'build' 02:30:11 INFO - no previously-included directories found matching 'dist' 02:30:11 INFO - no previously-included directories found matching '.git*' 02:30:11 INFO - Successfully installed jsonschema functools32 02:30:11 INFO - Cleaning up... 02:30:11 INFO - Return code: 0 02:30:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:30:11 INFO - Using env: {'DISPLAY': ':0', 02:30:11 INFO - 'HOME': '/home/cltbld', 02:30:11 INFO - 'LANG': 'en_US.UTF-8', 02:30:11 INFO - 'LANGUAGE': 'en_US:en', 02:30:11 INFO - 'LOGNAME': 'cltbld', 02:30:11 INFO - 'MAIL': '/var/mail/cltbld', 02:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:11 INFO - 'MOZ_NO_REMOTE': '1', 02:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:11 INFO - 'NO_EM_RESTART': '1', 02:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:11 INFO - 'PWD': '/builds/slave/test', 02:30:11 INFO - 'SHELL': '/bin/bash', 02:30:11 INFO - 'SHLVL': '1', 02:30:11 INFO - 'TERM': 'linux', 02:30:12 INFO - 'TMOUT': '86400', 02:30:12 INFO - 'USER': 'cltbld', 02:30:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:12 INFO - '_': '/tools/buildbot/bin/python'} 02:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:30:12 INFO - Cleaning up... 02:30:12 INFO - Return code: 0 02:30:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:30:12 INFO - Using env: {'DISPLAY': ':0', 02:30:12 INFO - 'HOME': '/home/cltbld', 02:30:12 INFO - 'LANG': 'en_US.UTF-8', 02:30:12 INFO - 'LANGUAGE': 'en_US:en', 02:30:12 INFO - 'LOGNAME': 'cltbld', 02:30:12 INFO - 'MAIL': '/var/mail/cltbld', 02:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:12 INFO - 'MOZ_NO_REMOTE': '1', 02:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:12 INFO - 'NO_EM_RESTART': '1', 02:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:12 INFO - 'PWD': '/builds/slave/test', 02:30:12 INFO - 'SHELL': '/bin/bash', 02:30:12 INFO - 'SHLVL': '1', 02:30:12 INFO - 'TERM': 'linux', 02:30:12 INFO - 'TMOUT': '86400', 02:30:12 INFO - 'USER': 'cltbld', 02:30:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:12 INFO - '_': '/tools/buildbot/bin/python'} 02:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:12 INFO - Downloading/unpacking blobuploader==1.2.4 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30: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 02:30:14 INFO - Downloading blobuploader-1.2.4.tar.gz 02:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:30:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:30:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:14 INFO - Downloading docopt-0.6.1.tar.gz 02:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:30:15 INFO - Installing collected packages: blobuploader, requests, docopt 02:30:15 INFO - Running setup.py install for blobuploader 02:30:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:30:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:30:15 INFO - Running setup.py install for requests 02:30:15 INFO - Running setup.py install for docopt 02:30:15 INFO - Successfully installed blobuploader requests docopt 02:30:15 INFO - Cleaning up... 02:30:15 INFO - Return code: 0 02:30:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:15 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 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:15 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 02:30:15 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 02:30:15 INFO - Using env: {'DISPLAY': ':0', 02:30:15 INFO - 'HOME': '/home/cltbld', 02:30:15 INFO - 'LANG': 'en_US.UTF-8', 02:30:15 INFO - 'LANGUAGE': 'en_US:en', 02:30:15 INFO - 'LOGNAME': 'cltbld', 02:30:15 INFO - 'MAIL': '/var/mail/cltbld', 02:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:15 INFO - 'MOZ_NO_REMOTE': '1', 02:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:15 INFO - 'NO_EM_RESTART': '1', 02:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:15 INFO - 'PWD': '/builds/slave/test', 02:30:15 INFO - 'SHELL': '/bin/bash', 02:30:15 INFO - 'SHLVL': '1', 02:30:15 INFO - 'TERM': 'linux', 02:30:15 INFO - 'TMOUT': '86400', 02:30:15 INFO - 'USER': 'cltbld', 02:30:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:15 INFO - '_': '/tools/buildbot/bin/python'} 02:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:30:15 INFO - Running setup.py (path:/tmp/pip-iNeur4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:30:15 INFO - Running setup.py (path:/tmp/pip-V252t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:30:16 INFO - Running setup.py (path:/tmp/pip-f6mzJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:30:16 INFO - Running setup.py (path:/tmp/pip-Wg9htZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:30:16 INFO - Running setup.py (path:/tmp/pip-ClbZwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:30:16 INFO - Running setup.py (path:/tmp/pip-uPSijw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:30:16 INFO - Running setup.py (path:/tmp/pip-MTDXwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:30:16 INFO - Running setup.py (path:/tmp/pip-tpLu2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:30:16 INFO - Running setup.py (path:/tmp/pip-VAXq2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:30:16 INFO - Running setup.py (path:/tmp/pip-oenhAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:30:16 INFO - Running setup.py (path:/tmp/pip-4JFGer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:30:16 INFO - Running setup.py (path:/tmp/pip-sQjJuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:30:16 INFO - Running setup.py (path:/tmp/pip-sso3d7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:30:16 INFO - Running setup.py (path:/tmp/pip-P9M2v5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:16 INFO - Running setup.py (path:/tmp/pip-soO5Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:30:16 INFO - Running setup.py (path:/tmp/pip-PsxGur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:30:17 INFO - Running setup.py (path:/tmp/pip-aTAgQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:30:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:30:17 INFO - Running setup.py install for manifestparser 02:30:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Running setup.py install for mozcrash 02:30:17 INFO - Running setup.py install for mozdebug 02:30:17 INFO - Running setup.py install for mozdevice 02:30:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Running setup.py install for mozfile 02:30:17 INFO - Running setup.py install for mozhttpd 02:30:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Running setup.py install for mozinfo 02:30:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Running setup.py install for mozInstall 02:30:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:30:17 INFO - Running setup.py install for mozleak 02:30:17 INFO - Running setup.py install for mozlog 02:30:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Running setup.py install for moznetwork 02:30:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Running setup.py install for mozprocess 02:30:18 INFO - Running setup.py install for mozprofile 02:30:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Running setup.py install for mozrunner 02:30:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Running setup.py install for mozscreenshot 02:30:18 INFO - Running setup.py install for moztest 02:30:18 INFO - Running setup.py install for mozversion 02:30:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:30:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:30:18 INFO - Cleaning up... 02:30:18 INFO - Return code: 0 02:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:18 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 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:18 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 02:30:18 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 02:30:18 INFO - Using env: {'DISPLAY': ':0', 02:30:18 INFO - 'HOME': '/home/cltbld', 02:30:18 INFO - 'LANG': 'en_US.UTF-8', 02:30:18 INFO - 'LANGUAGE': 'en_US:en', 02:30:18 INFO - 'LOGNAME': 'cltbld', 02:30:18 INFO - 'MAIL': '/var/mail/cltbld', 02:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:18 INFO - 'MOZ_NO_REMOTE': '1', 02:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:18 INFO - 'NO_EM_RESTART': '1', 02:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:18 INFO - 'PWD': '/builds/slave/test', 02:30:18 INFO - 'SHELL': '/bin/bash', 02:30:18 INFO - 'SHLVL': '1', 02:30:18 INFO - 'TERM': 'linux', 02:30:18 INFO - 'TMOUT': '86400', 02:30:18 INFO - 'USER': 'cltbld', 02:30:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:18 INFO - '_': '/tools/buildbot/bin/python'} 02:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:30:19 INFO - Running setup.py (path:/tmp/pip-ZrYMxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:30:19 INFO - Running setup.py (path:/tmp/pip-KZ_SSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:30:19 INFO - Running setup.py (path:/tmp/pip-S7XIa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:30:19 INFO - Running setup.py (path:/tmp/pip-XjXHV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:30:19 INFO - Running setup.py (path:/tmp/pip-_eKKWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:30:19 INFO - Running setup.py (path:/tmp/pip-2ioUIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:30:19 INFO - Running setup.py (path:/tmp/pip-K6oiBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:30:19 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)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:30:19 INFO - Running setup.py (path:/tmp/pip-P3vuxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:30:19 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)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:30:19 INFO - Running setup.py (path:/tmp/pip-w1Pqph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:30:19 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)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:30:19 INFO - Running setup.py (path:/tmp/pip-FB4WyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:30:19 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)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:30:19 INFO - Running setup.py (path:/tmp/pip-qcNHFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:30:19 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)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:30:19 INFO - Running setup.py (path:/tmp/pip-GN7x3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:30:19 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)) 02:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:30:19 INFO - Running setup.py (path:/tmp/pip-fu8ucA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:30:20 INFO - Running setup.py (path:/tmp/pip-epZmVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:20 INFO - Running setup.py (path:/tmp/pip-e9ssSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:30:20 INFO - Running setup.py (path:/tmp/pip-jUeVwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:30:20 INFO - Running setup.py (path:/tmp/pip-9Bk689-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:30:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:22 INFO - Downloading blessings-1.6.tar.gz 02:30:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:30:22 INFO - Installing collected packages: blessings 02:30:22 INFO - Running setup.py install for blessings 02:30:22 INFO - Successfully installed blessings 02:30:22 INFO - Cleaning up... 02:30:22 INFO - Return code: 0 02:30:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:30:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:22 INFO - Reading from file tmpfile_stdout 02:30:22 INFO - Current package versions: 02:30:22 INFO - argparse == 1.2.1 02:30:22 INFO - blessings == 1.6 02:30:22 INFO - blobuploader == 1.2.4 02:30:22 INFO - docopt == 0.6.1 02:30:22 INFO - functools32 == 3.2.3-2 02:30:22 INFO - jsonschema == 2.5.1 02:30:22 INFO - manifestparser == 1.1 02:30:22 INFO - mozInstall == 1.12 02:30:22 INFO - mozcrash == 0.17 02:30:22 INFO - mozdebug == 0.1 02:30:22 INFO - mozdevice == 0.48 02:30:22 INFO - mozfile == 1.2 02:30:22 INFO - mozhttpd == 0.7 02:30:22 INFO - mozinfo == 0.9 02:30:22 INFO - mozleak == 0.1 02:30:22 INFO - mozlog == 3.3 02:30:22 INFO - moznetwork == 0.27 02:30:22 INFO - mozprocess == 0.23 02:30:22 INFO - mozprofile == 0.28 02:30:22 INFO - mozrunner == 6.12 02:30:22 INFO - mozscreenshot == 0.1 02:30:22 INFO - mozsystemmonitor == 0.3 02:30:22 INFO - moztest == 0.7 02:30:22 INFO - mozversion == 1.4 02:30:22 INFO - psutil == 3.1.1 02:30:22 INFO - requests == 1.2.3 02:30:22 INFO - wsgiref == 0.1.2 02:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 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 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:22 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 02:30:22 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 02:30:22 INFO - Using env: {'DISPLAY': ':0', 02:30:22 INFO - 'HOME': '/home/cltbld', 02:30:22 INFO - 'LANG': 'en_US.UTF-8', 02:30:22 INFO - 'LANGUAGE': 'en_US:en', 02:30:22 INFO - 'LOGNAME': 'cltbld', 02:30:22 INFO - 'MAIL': '/var/mail/cltbld', 02:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:22 INFO - 'MOZ_NO_REMOTE': '1', 02:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:22 INFO - 'NO_EM_RESTART': '1', 02:30:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:22 INFO - 'PWD': '/builds/slave/test', 02:30:22 INFO - 'SHELL': '/bin/bash', 02:30:22 INFO - 'SHLVL': '1', 02:30:22 INFO - 'TERM': 'linux', 02:30:22 INFO - 'TMOUT': '86400', 02:30:22 INFO - 'USER': 'cltbld', 02:30:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:22 INFO - '_': '/tools/buildbot/bin/python'} 02:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 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)) 02:30:22 INFO - Cleaning up... 02:30:22 INFO - Return code: 0 02:30:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:22 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 0x7fbb271edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c97230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, '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': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', '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 02:30:22 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 02:30:22 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 02:30:22 INFO - Using env: {'DISPLAY': ':0', 02:30:22 INFO - 'HOME': '/home/cltbld', 02:30:22 INFO - 'LANG': 'en_US.UTF-8', 02:30:22 INFO - 'LANGUAGE': 'en_US:en', 02:30:22 INFO - 'LOGNAME': 'cltbld', 02:30:22 INFO - 'MAIL': '/var/mail/cltbld', 02:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:22 INFO - 'MOZ_NO_REMOTE': '1', 02:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:22 INFO - 'NO_EM_RESTART': '1', 02:30:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:22 INFO - 'PWD': '/builds/slave/test', 02:30:22 INFO - 'SHELL': '/bin/bash', 02:30:22 INFO - 'SHLVL': '1', 02:30:22 INFO - 'TERM': 'linux', 02:30:22 INFO - 'TMOUT': '86400', 02:30:22 INFO - 'USER': 'cltbld', 02:30:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:22 INFO - '_': '/tools/buildbot/bin/python'} 02:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:30:23 INFO - Cleaning up... 02:30:23 INFO - Return code: 0 02:30:23 INFO - Running post-action listener: _resource_record_post_action 02:30:23 INFO - Running post-action listener: _start_resource_monitoring 02:30:23 INFO - Starting resource monitoring. 02:30:23 INFO - [mozharness: 2016-08-27 09:30:23.201366Z] Finished create-virtualenv step (success) 02:30:23 INFO - [mozharness: 2016-08-27 09:30:23.201867Z] Running install step. 02:30:23 INFO - Running pre-action listener: _resource_record_pre_action 02:30:23 INFO - Running main action method: install 02:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:23 INFO - Reading from file tmpfile_stdout 02:30:23 INFO - Detecting whether we're running mozinstall >=1.0... 02:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:30:23 INFO - Reading from file tmpfile_stdout 02:30:23 INFO - Output received: 02:30:23 INFO - Usage: mozinstall [options] installer 02:30:23 INFO - Options: 02:30:23 INFO - -h, --help show this help message and exit 02:30:23 INFO - -d DEST, --destination=DEST 02:30:23 INFO - Directory to install application into. [default: 02:30:23 INFO - "/builds/slave/test"] 02:30:23 INFO - --app=APP Application being installed. [default: firefox] 02:30:23 INFO - mkdir: /builds/slave/test/build/application 02:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:30:39 INFO - Reading from file tmpfile_stdout 02:30:39 INFO - Output received: 02:30:39 INFO - /builds/slave/test/build/application/firefox/firefox 02:30:39 INFO - Running post-action listener: _resource_record_post_action 02:30:39 INFO - [mozharness: 2016-08-27 09:30:39.637461Z] Finished install step (success) 02:30:39 INFO - [mozharness: 2016-08-27 09:30:39.637742Z] Running run-tests step. 02:30:39 INFO - Running pre-action listener: _resource_record_pre_action 02:30:39 INFO - Running main action method: run_tests 02:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:30:39 INFO - Python 2.7.3 02:30:39 INFO - Return code: 0 02:30:39 INFO - grabbing minidump binary from tooltool 02:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:39 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 0x1c91090>, '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 0x1c949b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c94e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:30:39 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 02:30:39 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 02:30:39 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 02:30:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:30:39 INFO - Return code: 0 02:30:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:30:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:30:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:30:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:30:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 02:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 02:30:39 INFO - Using env: {'DISPLAY': ':0', 02:30:39 INFO - 'HOME': '/home/cltbld', 02:30:39 INFO - 'LANG': 'en_US.UTF-8', 02:30:39 INFO - 'LANGUAGE': 'en_US:en', 02:30:39 INFO - 'LOGNAME': 'cltbld', 02:30:39 INFO - 'MAIL': '/var/mail/cltbld', 02:30:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:39 INFO - 'MOZ_NO_REMOTE': '1', 02:30:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:39 INFO - 'NO_EM_RESTART': '1', 02:30:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:39 INFO - 'PWD': '/builds/slave/test', 02:30:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:39 INFO - 'SHELL': '/bin/bash', 02:30:39 INFO - 'SHLVL': '1', 02:30:39 INFO - 'TERM': 'linux', 02:30:39 INFO - 'TMOUT': '86400', 02:30:39 INFO - 'USER': 'cltbld', 02:30:39 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147', 02:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:39 INFO - '_': '/tools/buildbot/bin/python'} 02:30:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 02:30:39 INFO - mozversion application_buildid: 20160827004713 02:30:39 INFO - mozversion application_changeset: 087a40f5eaa909ceea0d41f0357c50ee3df47a4d 02:30:39 INFO - mozversion application_display_name: Nightly 02:30:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:30:39 INFO - mozversion application_name: Firefox 02:30:39 INFO - mozversion application_remotingname: firefox 02:30:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:30:39 INFO - mozversion application_vendor: Mozilla 02:30:39 INFO - mozversion application_version: 51.0a1 02:30:39 INFO - mozversion platform_buildid: 20160827004713 02:30:39 INFO - mozversion platform_changeset: 087a40f5eaa909ceea0d41f0357c50ee3df47a4d 02:30:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:30:39 INFO - mozversion platform_version: 51.0a1 02:30:39 INFO - using testdate: 1472290239 02:30:39 INFO - actual date: 1472290239 02:30:39 INFO - starting webserver on 'localhost:41852' 02:30:39 INFO - SUITE-START | Running 2 tests 02:30:39 INFO - TEST-START | dromaeo_css 02:30:39 INFO - operating with platform_type : linux_ 02:30:39 INFO - Initialising browser for dromaeo_css test... 02:30:39 INFO - TEST-INFO | started process 30601 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR1kOFc/profile http://localhost:41852/getInfo.html) 02:30:44 INFO - PROCESS | 30601 | __metrics Screen width/height:1600/1200 02:30:44 INFO - PROCESS | 30601 | colorDepth:24 02:30:44 INFO - PROCESS | 30601 | Browser inner width/height: 1024/697 02:30:44 INFO - PROCESS | 30601 | __metrics 02:30:45 INFO - PROCESS | 30601 | 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" 02:30:45 INFO - PROCESS | 30601 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:30:45 INFO - PROCESS | 30601 | console.error: PushService: 02:30:45 INFO - PROCESS | 30601 | stateChangeProcessEnqueue: Error transitioning state 02:30:45 INFO - PROCESS | 30601 | UnknownError 02:30:46 INFO - TEST-INFO | 30601: exit 0 02:30:46 INFO - Browser initialized. 02:30:46 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:46 INFO - TEST-INFO | started process 30683 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR1kOFc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:58 INFO - PROCESS | 30683 | RSS: Main: 180748288 02:30:58 INFO - PROCESS | 30683 | 02:31:49 INFO - PROCESS | 30683 | 1472290309877 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] 02:34:03 INFO - PROCESS | 30683 | Cycle 1(1): loaded http://localhost:41852/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41852/tests/dromaeo/cssquery-ext.html) 02:34:04 INFO - PROCESS | 30683 | RSS: Main: 265203712 02:34:04 INFO - PROCESS | 30683 | 02:37:07 INFO - PROCESS | 30683 | Cycle 1(1): loaded http://localhost:41852/tests/dromaeo/cssquery-ext.html (next: http://localhost:41852/tests/dromaeo/cssquery-jquery.html) 02:37:08 INFO - PROCESS | 30683 | RSS: Main: 276963328 02:37:08 INFO - PROCESS | 30683 | 02:40:10 INFO - PROCESS | 30683 | Cycle 1(1): loaded http://localhost:41852/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41852/tests/dromaeo/cssquery-mootools.html) 02:40:10 INFO - PROCESS | 30683 | RSS: Main: 219791360 02:40:10 INFO - PROCESS | 30683 | 02:43:12 INFO - PROCESS | 30683 | Cycle 1(1): loaded http://localhost:41852/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41852/tests/dromaeo/cssquery-prototype.html) 02:43:13 INFO - PROCESS | 30683 | RSS: Main: 219078656 02:43:13 INFO - PROCESS | 30683 | 02:46:16 INFO - PROCESS | 30683 | Cycle 1(1): loaded http://localhost:41852/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41852/tests/dromaeo/cssquery-yui.html) 02:46:17 INFO - PROCESS | 30683 | RSS: Main: 217845760 02:46:17 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | Cycle 1(1): loaded http://localhost:41852/tests/dromaeo/cssquery-yui.html (next: http://localhost:41852/tests/dromaeo/cssquery-dojo.html) 02:49:20 INFO - PROCESS | 30683 | RSS: Main: 308449280 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | __start_tp_report 02:49:20 INFO - PROCESS | 30683 | _x_x_mozilla_page_load 02:49:20 INFO - PROCESS | 30683 | _x_x_mozilla_page_load_details 02:49:20 INFO - PROCESS | 30683 | |i|pagename|runs| 02:49:20 INFO - PROCESS | 30683 | |0;dojo.html;2614.4901744159883;2619.5415802234606;2633.8814753336096;2672.412069344745;2678.758911697948;4678.461976872661;4733.952660473395;4758.6431017673685;4783.37816083909;4846.200376937801;4772.80908763649;4794.664373493857;4851.029794041197;4856.05306965142;4858.805647774094;4332.588404101605;4340.131973605292;4411.977940110295;4475.820967161309;4575.313702944552;4471.664625153094;4504.414426124616;4521.864344069667;4542.04617030424;4551.1580357633775;4315.525292217853;4351.303791393393;4357.36818161368;4363.65090792739;4396.516383197878;4330.610245077958;4339.566043395666;4351.82592696295;4407.669424793154;4411.073674528353;4330.090680957002;4389.2318844202455;4395.274779661352;4398.076403955173;4406;4692.788824502905;4792.520747925215;4800.375951126366;4806.735629540374;4807.278908163804;5081.568066714333;5133.640645154841;5149.150390185615;5151.150060240056;5155.561777248939;5159.896802063938;5160.458151894072;5169.405518365398;5176.0683077046115;5185.170372740344;4655.930161047587;4767.976160119178;4818.6626936114835;4823.2282834746275;4862.85411437661;5254.842354729364;5287.867803304948;5303.68177909319;5383.973080134652;5396.595255355785;5076.289319495274;5097.64316497849;5101.107306221396;5154.175331946945;5156.5616922562;4995.775190116381;5103.2855400244;5111.642185047085;5123.820666276662;5125.077486052472;5125.461826508237;5136.5377116059735;5190.24741412493;5194.298769666047;5200.219967004904;5164.612654050961;5179.223116532475;5185.325907631984;5185.559227465632;5238.5547228486;4264.381664658607;4429.291313389842;4443.466783985878;4452.487963884195;4493.955060449419;3747.2318174774105;3801.467794508771;3826.234753049401;3881.9805900970878;3905.902352441155;4391.780410979438;4404.053128577307;4406.471223453206;4411.9558804411545;4460.50934397216;5144.176931690911;5151.175811870083;5160.225966105039;5162.586993040548;5221.164613661796;4275.315949448073;4355.303151495746;4408.6913916026215;4423.8451654192595;4468.128714900563;4621.468531118897;4652.395188625457;4652.813887444532;4694.014257006067;4705.247160454311;4788.257820037899;4816.494268101939;4820.9517904821205;4834.057358815067;4895.510448955076;5031.345925029723;5081.644284900021;5098.79604815818;5131.025105229995;5162.9483705162465;4821.4696383397095;4823.155947709207;4826.6621336506105;4853.635977301646;4874.4150701916;5040.672356296829;5047.369078865142;5049.2426136079885;5050.469700681376;5051.696898186121;5081.3902331718955;5124.871878203075;5133.486651334837;5173.1205695031185;5190.740462976912;4932.7286999215385;5014.0974624567925;5033.144365457954;5034.899302014012;5048.343715316985;5039.143345631325;5047.545720885138;5049.166887463527;5051.797928082836;5108.489151084862;4959.876003099951;4961.875953101201;4969.701817890938;4972.254161875748;4972.453030166606;4846.442659094249;5000.4749501303295;5003.599712023102;5035.521625445577;5087.109755792648;4879.878003049952;4892.633052521004;4900.975495122501;4908.969116485435;4972.303877457087 02:49:20 INFO - PROCESS | 30683 | |1;ext.html;537.9004884096441;559.2170960655083;562.13150682196;571.9942800571996;581.7469400810651;27669;37853;39221.607783922154;39249.01877453062;42768.786156069174;39890;40224.59775402245;40326.79836600813;40368.39447408284;40380.394294085614;7679.040119985002;7829.608519574027;7832.412569057315;7837.451378403514;7839.1768864269;7338.266173382673;9558.80882382352;9793.21654267657;9795.069468400527;10087.445190514518;10017;10116.291859569834;10123.797524049514;10128.189744820396;10134.493275336241;9979.30144889858;10046.246531510165;10046.849297260547;10056.245781566338;10069.496525173712;9905.603775848957;10004.649837255734;10007.799844003079;10009.349392289478;10015.248856335804;37028.81485592582;37262.441063384074;37265;37311.06722331936;37347.62652373469;2945.661248956376;3035.2563621912773;3384.323135372935;3449.2066824630224;3474.339875423687;27045.323866903287;27468.58797118045;29111.56332655012;29231.707682923112;29980.10059698191;3473.166440054394;3547.320052087228;3561.5370001899846;3595.7482976191677;3652.8904132876055;241334;244787;247193.7640311805;247477;248046;56125;56781;56853.71573142191;56883.43116568781;56912.71543642338;32675.509867352008;33644.49533257003;33662.158446039044;35850.82074589663;58327;13990.16059036461;14168.787468187986;14169.645758855904;14172.220527871066;14177.291135443393;9369.203617692436;9781.951090244504;10202.642907498202;10305.072543470973;10315.690529283984;18181.54546136357;18819;19087.52281192981;19196;19270.61458770817;7086.39765619928;7188.137423509213;7211.314925082109;7398.667059982314;7469.738559150513;758.4463341760546;819.9221073997961;845.3237410071918;978.8433850583872;1007.4408703169664;25511;27269.227500212422;27270;27562.173134805988;27820.443591128063;883.3242569434387;902.6750369866843;924.8196601662746;944.2304521814699;1000.0749306891242;14471.539838227527;14626.634334141732;14915.552533424014;14938.775918361232;15519.45681901128;393333;394768;394890;395832;396040;49161.508384915694;49644.50355496471;49665.00669986652;49677.751611241714;49769;1052.3580615824342;1110.3005106782675;1115.576081089197;1159.4202898550725;1197.2397527570056;20634.58730825345;29482;29483.262918427212;29981.250468738457;30442.84778576093;46128.769356153;46555;46685.533144668116;46756.29865552088;46780.7660961693;351285;352701;354343;354357;354765;21556.137754489646;22167.556648867256;23230.3030909073;24836;25333.240002799947;35791.64208357883;39222.2155556893;39291.60708392879;39429;39784;33084.66915330912;33581.664183357854;34087.48868766921;34092.14769630779;34932.47601286034;32626.83686581647;32721.672783272814;32974.83512582518;33460.33079338351;33637;33170.83414582912;33566.32867342591;33644;33746.66253337435;34923.825380872935;26958.460830782882;29683.703162968093;32823.67176328302;33041;33387;24846.546036189433;25158.74841251564;25192;25419.87290063538;25656.871715641304 02:49:20 INFO - PROCESS | 30683 | |2;jquery.html;343.8590178027009;356.6968077134436;359.4105666706601;361.72870347239564;384.8210582079333;13091.672708182301;16806.915965420172;16817;17488.038157901312;18715.43853684388;14089.225092619901;14423.5672929812;14595.781063284032;15337.309821058052;15657.686846263068;4437.511873693891;5140.126178549646;5187.221916712486;5216.8174113906025;5268.394134674523;3390.0846771371716;5186.1442861927735;5820.1269809528485;6119.3574674659185;6126.877462450749;6187.257529096515;6189.597676151036;6191.566590338678;6193.6283822970545;6194.659293738842;6735.5621884577595;7051.964740176266;7069.646517674096;7193.784186474414;7217.711291548332;6748.966255168742;6924.134483189601;6951.339622735883;6955.130608673916;6983.8603227935155;22568.66147007796;23056.193033243922;23513.17703880373;23531.17640882578;23686.644700329507;1952.248384372027;1994.3019943019972;2006.7290915726337;2080.3446914221972;2163.5456554123652;13051.41268642904;14480.565583032527;14592.343344549521;14717.852821471863;15265.847341526554;1800.4778614201866;1835.4860639021094;1847.630473905211;1850.1489314915154;1861.2461952909093;65256.67371663159;76031;79355.30315149548;79630.60184699098;81478.59260703658;16459.506214813573;16477.093759843083;16517.504474865775;16717.83282167163;16886.662266754596;28239.57640635392;28261.57607635887;28324.291892702437;28333.57499637507;28494.71505284962;8256.305812313969;8557.144285571518;8789;8884.333674974403;9181.40320879154;7082.787516374517;7123.074000379917;7181.569105853666;7336.339729424378;7361.153467351216;8148.796280092926;8494.362922780692;8526.488410695378;8642.956785216034;8733.737987860375;2588.6505321781697;2954.8818047278296;2965.733084022448;3372.7976321420792;3393.932121357559;742.254034695128;748.4311922938606;801.9879701804477;830.4767996162379;870.9390342676079;16629.417970370978;16729.247183876512;17194.226259818337;17713;17990.460286191435;777.8327659553223;798.6206551887807;838.174398217755;890.7194233816457;895.7939673875045;9479.099485548863;9692.51537423126;9921.751956201153;9927.255455840841;9942.154916832005;387374;388084;392361.0694502772;392630;393905;56375.15437268444;72742.2725772746;76672.61663691758;77839;80113;831.1646794971016;857.8884744983112;858.9441686290371;859.4070091636751;860.247283626839;15139;15169.241537922839;15361.923190383977;15483.84516154869;16003.839961600235;34002.489962650085;34835.303293934485;35139.29721405506;35737.64262357343;35934.8203258982;46446.53553464422;46583;46656;48612;49404.258936116705;7384.224656410999;7419.591922444318;7454.397304579541;7774;7979.122296547491;9424.670136545188;10573.207009474167;10870;11076.944615276872;11350.673112011853;9818.116369526491;9955.20358371314;10200;10307.896921030693;10687;9907.554160062959;10010;10076.596936122472;10076.69769906904;10086.394816311045;9801.705948821547;9976.2517811163;10189.84715229287;10211.183105351703;10501;7535.171131175457;7666.121800158997;7687.2697093776005;7759.844803104019;8145.42981991255;7959.721409750631;9197.954010229905;10075.949620251851;10173.796524069625;10428 02:49:20 INFO - PROCESS | 30683 | |3;mootools.html;1300.3693208793734;1349.372541768078;1370.2799942114907;1608.9436869709562;1624.3827345608674;7201.459890508213;7746.922530774697;7750.4187185961;7752.457327987029;7765.107012693535;6604.867902641944;7028.859422811541;7284.927150728491;7286.089238845145;7653.732119375823;2158.5359147783224;2186;2196.8132708719777;2200.977990220101;2201.8348623853194;2137.871727696343;2176.81497072749;2215.8338124640636;2216.656418255165;2218.744844342897;1906.8665193436466;1966.2528239269059;1967.616314818611;1967.6261510312995;2097.056324653904;2123.819475344598;2157.665561837902;2159.7408311002623;2161.7081693971427;2165.2529877192424;2168.40622142725;2184.71598692169;2187.803097721213;2197.5275315807016;2207.602631526325;7559.28186822251;7617.961910190469;7621.58081305528;7621.695132194705;7624.695012199505;4224.978875105636;4350.673699472521;4372.978135109336;4399.670024748125;4404.075144219686;2807.6911539730613;2868.498012847743;2910.0542323744867;2915.0817492489796;2925.2686828292926;984.9458279794609;1004.7739258666815;1016.3749294184097;1028.9588416463332;1034.3380236648552;9687.1281584657;9716.659916902874;9778.168855647278;9793.853092203623;9830.606775728962;3908.9609103908597;4069.4709687740997;4107.178564287095;4177.289860723684;4235.23765722173;8475.660973561111;8487.915120848837;8495.320374370036;8497.405181637227;8500.957495212608;4405.757683327448;4406.383106365111;4412.227860124465;4415.66882483815;4417.094495628388;3941.0147463134217;3986.661133803659;4012.9197416052098;4014.458048163519;4018.0758425562285;3561.821908904544;3605.837737301828;3617.421212606023;3653.3606618841595;3656.8720094797086;1178.7642471505733;1220.9877901221053;1231.94456249469;1235.4687484381802;1243.925364478116;799.7320897499286;805.5046146619903;810.9756707298791;814.9551774652451;826.7561069484517;4976.6765160265195;5022.547970682583;5047.798088076509;5187.688738675692;5220.295260139908;400.7555391211359;400.7735629369397;405.83157989434056;428.69134223359754;437.82924659382866;3499.107727529464;3531.69980551656;3536.664016918389;3586.7489275750445;3589.2821435712963;8736.563171841382;9256.444613323221;9257.259419246444;9257.398269112486;9278.814423711488;9176.81646367069;9196.586153623102;9208.815823683488;9215.03242159577;9228.815423691623;6873.896891546631;7088.04311417952;7168.641567921479;7172.928270717331;7174.210836807947;5109.463541276587;5132.79468821243;5176.275321454924;5238.266642670105;5270;8455.154484551496;8471.237588617054;8512.829743405222;8518.78703032429;8624.353173512138;8356.331493480386;8633.35249856276;8642.740717778477;8680.435771674822;8773.078826723358;6525.086487891794;6575.112359831553;6613.801585952429;6616.338366163345;6622.70197841108;7138.500304978602;7445.739399121005;7518.849623007619;7706.730264440717;7711.922880771121;6397.808065758035;6520.608763474207;6590;6689.464842812466;6844.1444819397575;6471.902921456276;6592.340765923561;6711.395974362331;6751.628660423527;6766.695498702668;5761.798337058183;5860.2967643883;5877.382874798084;5879.147523609138;5885.058390657474;4308.13837232561;4378.737275763465;4400.12377843669;4476.9328460072475;4540.704854184468;4485.596296333346;4531.637469002538;4537.478190008059;4596.379488768974;4608.88477788058 02:49:20 INFO - PROCESS | 30683 | |4;prototype.html;292.8140630699506;292.89602191222116;303.8146730494398;311.6447250134847;311.7396973527105;4826.42082950046;4884.462709101996;4909.901801963963;4910.1898186799135;4937.950620493794;4780.737059461728;4792.472827988919;4844.442889067758;4882.217990433493;4884.6824956377895;3624.6226433955126;3657.6342365763344;3784.924301513968;3801.657850793428;3803.1442925341717;3784.7161462890253;3795.886123416302;3822.73240873139;3838.5201849768778;3852.84588616455;3759.7556158849725;3768.340540405432;3776.3580191367396;3781.2815565042556;3814.1036856338737;3599.4240921452574;3679.392900171482;3730.9067273318115;3756.981215093934;3795.7153213508823;3295.6045274566964;3332.800031998088;3335.916602084943;3344.4481660526226;3345.6821601947777;4692.5218556154705;4698.647601429906;4704.153252414564;4717.127331443694;4785.138675038492;4250.574942505756;4342.804573794187;4348.5216626171095;4388.091180336563;4398.164348773755;4953.826616068456;4996.675216110941;4996.975015124937;4999.225120106389;5000.374953130859;3656.7567027210875;3662.1760103976394;3674.9816250918843;3686.4101743720876;3716.9628303716886;5083.839368268471;5248.160294352923;5287.9471205287455;5319.654222475527;5325.174597937287;3725.6274372562525;3726.1802403471193;3729.2355067211697;3766.133789228438;3768.8304026318883;3781.621837816251;3814;3827.9043023924623;3852.9422058669143;3855.151866589346;4339.435873336509;4342.805728424645;4346.348047792856;4347.4131941841;4350.543192964757;4880.902381952341;5048.7728052237;5054.772535235923;5075.416327122428;5135.255387968724;1312.1274352555445;1330.4545136494185;1333.953311634088;1344.877027681877;1345.3744009035845;851.199872120205;869.1525762381627;877.8156587116777;893.1693525021662;900.8603666431713;4351.238533256667;4484.968457254849;4485.41689580359;4486.259767138451;4497.168023915586;5175.9741201293755;5187.481251874782;5200.609954253446;5221.712805795718;5238.292830467838;4463.575960283768;4473.843415480443;4485.708428952108;4497.268551607654;4500.279955207151;2600.9869950650127;2763.350612606036;2765.6542932133484;2772.265349682336;2794.5109605818902;4919.237518184687;4923.90152196954;4924.020967314795;4947.727874966911;4955.35580374549;5197.272381866542;5242.846573753768;5337.6797392156595;5362.300012991023;5370.4629537045985;4849.418069831572;4886.60907127429;4914.852554423373;4925.556699896955;4940.481249468754;5224.346956630421;5233.424323324507;5235.502627250406;5235.842924712265;5245.9475405246985;4914.655974081923;5126.53861152498;5143.125668636266;5161.587073034223;5185.351831021122;4939.283394133284;4941.160002799605;4949.554540091409;4956.10790057793;4968.850934471971;3681.576618688885;3705.184859330943;3730.9440358394104;3731.1530792258004;3747.662710356081;5090.898182036264;5282.81510147143;5309.893802124013;5317.6011799115995;5344.893102138013;5069.138246498178;5071.619628527801;5087.262346959744;5087.440381557952;5102.744862756922;5058.54473097423;5066.898662026812;5079.469768510997;5141.820036298712;5150.690958542499;4744.4069491313585;4995.450500444876;5037.748112594429;5044;5085.847424577269;4895.4370247420575;4974.303597496281;5085.796568137233;5093.261477085728;5142.51146141116;4913.852584422473;4924.482929292516;4932.966225771053;4935.259711043373;4939.110960027229 02:49:20 INFO - PROCESS | 30683 | |5;yui.html;621.8445388652837;649.9350064993498;658.2035736758522;696.5089611823665;719.4316489972922;11884.405779711013;12786.296753678544;12806.615801525946;12809;12817.743645127091;12871.292078935678;12923.870761292384;12944.676383090427;12948.870511294885;12971.740565188715;598.6826981699706;726.1576571177435;742.1317059040908;746.1642959884937;749.7938067031582;756.5990025286579;759.4607828441785;759.4873460414226;759.525296689569;762.211111499598;792.1959211400433;796.398718967179;798.22572105058;798.4889670610813;799.4244144216184;635.1552435261115;776.3323541754087;792.774059393076;794.51931581393;794.7496538590326;591.5977135547826;591.60658162322;591.8165368735663;592.0881841963371;592.1975722895501;12097.274163550215;12198.451069701885;12225.205361651553;12251.632451026484;12258.1419300649;11737.882621173765;11936.940315298369;12351.073669474736;12569;13056.216627002315;9664.75838104046;9775.560099795526;9864.50677466131;9882.752931176778;9930.404175749478;3515.5668106658823;3556.0931962349696;3557.7509574329547;3657.250263695936;3753.3431649461236;63574;64711;65782.67108664474;66088.66955665238;66905.33094669088;9195.862062069074;9299.349045566745;9326.813463730687;9339.610383119923;9443.858342124873;17675.20461579232;17744;17828.3760068397;17892.910535447238;17977.1011449427;6685.097511835937;6750.797476075725;6879.6216208108035;6901.723931042802;7000.719971201095;3439.793612383265;3515.4375299951885;3556.2176321209295;3601.3877640801124;3639.3267245559127;2819.2528979820363;2908.4473949949443;2911.1848682368973;2919.518279483904;2921.0944607171846;483.22683706070006;486.34343636090995;489.26121556449306;495.86115887551546;502.36200025966815;395.6024195683377;470.4542730432682;520.9713465759344;541.3395657298169;544.3495023447022;6934.514583979174;7290.198078211392;7291.52605080668;7328.633568321563;7355.190928997806;475.2182659525062;488.74096728733826;516.1560848013524;519.3456245131086;522.5558275465823;3292.8188949608;3445.707114895212;3469.5836499619704;3513.6662017108847;3660.5790334111384;57071.71464142653;76236;77877;78929;88671;47611.5238847614;71002.2899771006;78426.60786696144;79007.60496197482;81508.36620323859;44464.3330350045;44479.11041779211;70080;70652;73005;7226.205117436973;7307;7599.354054905395;7639.159692433939;7678.385729141767;34784.47823282602;42473;43387.78306108449;43418.34872476852;43524.56475435205;54095.72952135214;57816;60031.39968600258;60827.391726083944;64868;9515.715378423823;9673.951630241803;9759.9024009759;9931.404115753077;9954.651587194414;10428.634997775345;10505.527251273865;10621.522031508755;10667.466626668791;11036.282641628268;9792.461414622265;9875.259355548218;9888.258380621339;9991.25065620096;10521.842172367573;8438.409311348392;8452.112528184452;8767.079456656958;8928.285737140883;9349.672761453588;9179.681667512628;9197.908020919705;9207.447553146832;9248.953755231181;9277.2578193743;8484.915150848412;9047.728568142966;9092.227160691415;9320.114589114024;9515.381500202464;8515.40392172542;8580.399372043898;8637;8720.563971801257;8890.911090889267 02:49:20 INFO - PROCESS | 30683 | __end_tp_report 02:49:20 INFO - PROCESS | 30683 | __start_cc_report 02:49:20 INFO - PROCESS | 30683 | _x_x_mozilla_cycle_collect,1204 02:49:20 INFO - PROCESS | 30683 | __end_cc_report 02:49:20 INFO - PROCESS | 30683 | __startTimestamp1472291360481__endTimestamp 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | ------- Summary: start ------- 02:49:20 INFO - PROCESS | 30683 | Number of tests: 6 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | [#0] dojo.html Cycles:165 Average:4752.67 Median:4858.81 stddev:507.06 (10.4%) stddev-sans-first:480.07 02:49:20 INFO - PROCESS | 30683 | Values: 2614.5 2619.5 2633.9 2672.4 2678.8 4678.5 4734.0 4758.6 4783.4 4846.2 4772.8 4794.7 4851.0 4856.1 4858.8 4332.6 4340.1 4412.0 4475.8 4575.3 4471.7 4504.4 4521.9 4542.0 4551.2 4315.5 4351.3 4357.4 4363.7 4396.5 4330.6 4339.6 4351.8 4407.7 4411.1 4330.1 4389.2 4395.3 4398.1 4406.0 4692.8 4792.5 4800.4 4806.7 4807.3 5081.6 5133.6 5149.2 5151.2 5155.6 5159.9 5160.5 5169.4 5176.1 5185.2 4655.9 4768.0 4818.7 4823.2 4862.9 5254.8 5287.9 5303.7 5384.0 5396.6 5076.3 5097.6 5101.1 5154.2 5156.6 4995.8 5103.3 5111.6 5123.8 5125.1 5125.5 5136.5 5190.2 5194.3 5200.2 5164.6 5179.2 5185.3 5185.6 5238.6 4264.4 4429.3 4443.5 4452.5 4494.0 3747.2 3801.5 3826.2 3882.0 3905.9 4391.8 4404.1 4406.5 4412.0 4460.5 5144.2 5151.2 5160.2 5162.6 5221.2 4275.3 4355.3 4408.7 4423.8 4468.1 4621.5 4652.4 4652.8 4694.0 4705.2 4788.3 4816.5 4821.0 4834.1 4895.5 5031.3 5081.6 5098.8 5131.0 5162.9 4821.5 4823.2 4826.7 4853.6 4874.4 5040.7 5047.4 5049.2 5050.5 5051.7 5081.4 5124.9 5133.5 5173.1 5190.7 4932.7 5014.1 5033.1 5034.9 5048.3 5039.1 5047.5 5049.2 5051.8 5108.5 4959.9 4961.9 4969.7 4972.3 4972.5 4846.4 5000.5 5003.6 5035.5 5087.1 4879.9 4892.6 4901.0 4909.0 4972.3 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | [#1] ext.html Cycles:180 Average:47961.83 Median:18565.49 stddev:88949.82 (479.1%) stddev-sans-first:89128.08 02:49:20 INFO - PROCESS | 30683 | Values: 537.9 559.2 562.1 572.0 581.7 27669.0 37853.0 39221.6 39249.0 42768.8 39890.0 40224.6 40326.8 40368.4 40380.4 7679.0 7829.6 7832.4 7837.5 7839.2 7338.3 9558.8 9793.2 9795.1 10087.4 10017.0 10116.3 10123.8 10128.2 10134.5 9979.3 10046.2 10046.8 10056.2 10069.5 9905.6 10004.6 10007.8 10009.3 10015.2 37028.8 37262.4 37265.0 37311.1 37347.6 2945.7 3035.3 3384.3 3449.2 3474.3 27045.3 27468.6 29111.6 29231.7 29980.1 3473.2 3547.3 3561.5 3595.7 3652.9 241334.0 244787.0 247193.8 247477.0 248046.0 56125.0 56781.0 56853.7 56883.4 56912.7 32675.5 33644.5 33662.2 35850.8 58327.0 13990.2 14168.8 14169.6 14172.2 14177.3 9369.2 9782.0 10202.6 10305.1 10315.7 18181.5 18819.0 19087.5 19196.0 19270.6 7086.4 7188.1 7211.3 7398.7 7469.7 758.4 819.9 845.3 978.8 1007.4 25511.0 27269.2 27270.0 27562.2 27820.4 883.3 902.7 924.8 944.2 1000.1 14471.5 14626.6 14915.6 14938.8 15519.5 393333.0 394768.0 394890.0 395832.0 396040.0 49161.5 49644.5 49665.0 49677.8 49769.0 1052.4 1110.3 1115.6 1159.4 1197.2 20634.6 29482.0 29483.3 29981.3 30442.8 46128.8 46555.0 46685.5 46756.3 46780.8 351285.0 352701.0 354343.0 354357.0 354765.0 21556.1 22167.6 23230.3 24836.0 25333.2 35791.6 39222.2 39291.6 39429.0 39784.0 33084.7 33581.7 34087.5 34092.1 34932.5 32626.8 32721.7 32974.8 33460.3 33637.0 33170.8 33566.3 33644.0 33746.7 34923.8 26958.5 29683.7 32823.7 33041.0 33387.0 24846.5 25158.7 25192.0 25419.9 25656.9 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | [#2] jquery.html Cycles:180 Average:25274.64 Median:46619.50 stddev:64408.95 (138.2%) stddev-sans-first:64562.43 02:49:20 INFO - PROCESS | 30683 | Values: 343.9 356.7 359.4 361.7 384.8 13091.7 16806.9 16817.0 17488.0 18715.4 14089.2 14423.6 14595.8 15337.3 15657.7 4437.5 5140.1 5187.2 5216.8 5268.4 3390.1 5186.1 5820.1 6119.4 6126.9 6187.3 6189.6 6191.6 6193.6 6194.7 6735.6 7052.0 7069.6 7193.8 7217.7 6749.0 6924.1 6951.3 6955.1 6983.9 22568.7 23056.2 23513.2 23531.2 23686.6 1952.2 1994.3 2006.7 2080.3 2163.5 13051.4 14480.6 14592.3 14717.9 15265.8 1800.5 1835.5 1847.6 1850.1 1861.2 65256.7 76031.0 79355.3 79630.6 81478.6 16459.5 16477.1 16517.5 16717.8 16886.7 28239.6 28261.6 28324.3 28333.6 28494.7 8256.3 8557.1 8789.0 8884.3 9181.4 7082.8 7123.1 7181.6 7336.3 7361.2 8148.8 8494.4 8526.5 8643.0 8733.7 2588.7 2954.9 2965.7 3372.8 3393.9 742.3 748.4 802.0 830.5 870.9 16629.4 16729.2 17194.2 17713.0 17990.5 777.8 798.6 838.2 890.7 895.8 9479.1 9692.5 9921.8 9927.3 9942.2 387374.0 388084.0 392361.1 392630.0 393905.0 56375.2 72742.3 76672.6 77839.0 80113.0 831.2 857.9 858.9 859.4 860.2 15139.0 15169.2 15361.9 15483.8 16003.8 34002.5 34835.3 35139.3 35737.6 35934.8 46446.5 46583.0 46656.0 48612.0 49404.3 7384.2 7419.6 7454.4 7774.0 7979.1 9424.7 10573.2 10870.0 11076.9 11350.7 9818.1 9955.2 10200.0 10307.9 10687.0 9907.6 10010.0 10076.6 10076.7 10086.4 9801.7 9976.3 10189.8 10211.2 10501.0 7535.2 7666.1 7687.3 7759.8 8145.4 7959.7 9198.0 10075.9 10173.8 10428.0 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | [#3] mootools.html Cycles:180 Average:4951.69 Median:5035.17 stddev:2732.20 (54.3%) stddev-sans-first:2726.08 02:49:20 INFO - PROCESS | 30683 | Values: 1300.4 1349.4 1370.3 1608.9 1624.4 7201.5 7746.9 7750.4 7752.5 7765.1 6604.9 7028.9 7284.9 7286.1 7653.7 2158.5 2186.0 2196.8 2201.0 2201.8 2137.9 2176.8 2215.8 2216.7 2218.7 1906.9 1966.3 1967.6 1967.6 2097.1 2123.8 2157.7 2159.7 2161.7 2165.3 2168.4 2184.7 2187.8 2197.5 2207.6 7559.3 7618.0 7621.6 7621.7 7624.7 4225.0 4350.7 4373.0 4399.7 4404.1 2807.7 2868.5 2910.1 2915.1 2925.3 984.9 1004.8 1016.4 1029.0 1034.3 9687.1 9716.7 9778.2 9793.9 9830.6 3909.0 4069.5 4107.2 4177.3 4235.2 8475.7 8487.9 8495.3 8497.4 8501.0 4405.8 4406.4 4412.2 4415.7 4417.1 3941.0 3986.7 4012.9 4014.5 4018.1 3561.8 3605.8 3617.4 3653.4 3656.9 1178.8 1221.0 1231.9 1235.5 1243.9 799.7 805.5 811.0 815.0 826.8 4976.7 5022.5 5047.8 5187.7 5220.3 400.8 400.8 405.8 428.7 437.8 3499.1 3531.7 3536.7 3586.7 3589.3 8736.6 9256.4 9257.3 9257.4 9278.8 9176.8 9196.6 9208.8 9215.0 9228.8 6873.9 7088.0 7168.6 7172.9 7174.2 5109.5 5132.8 5176.3 5238.3 5270.0 8455.2 8471.2 8512.8 8518.8 8624.4 8356.3 8633.4 8642.7 8680.4 8773.1 6525.1 6575.1 6613.8 6616.3 6622.7 7138.5 7445.7 7518.8 7706.7 7711.9 6397.8 6520.6 6590.0 6689.5 6844.1 6471.9 6592.3 6711.4 6751.6 6766.7 5761.8 5860.3 5877.4 5879.1 5885.1 4308.1 4378.7 4400.1 4476.9 4540.7 4485.6 4531.6 4537.5 4596.4 4608.9 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | [#4] prototype.html Cycles:180 Average:4222.34 Median:4865.16 stddev:1217.98 (25.0%) stddev-sans-first:1185.15 02:49:20 INFO - PROCESS | 30683 | Values: 292.8 292.9 303.8 311.6 311.7 4826.4 4884.5 4909.9 4910.2 4938.0 4780.7 4792.5 4844.4 4882.2 4884.7 3624.6 3657.6 3784.9 3801.7 3803.1 3784.7 3795.9 3822.7 3838.5 3852.8 3759.8 3768.3 3776.4 3781.3 3814.1 3599.4 3679.4 3730.9 3757.0 3795.7 3295.6 3332.8 3335.9 3344.4 3345.7 4692.5 4698.6 4704.2 4717.1 4785.1 4250.6 4342.8 4348.5 4388.1 4398.2 4953.8 4996.7 4997.0 4999.2 5000.4 3656.8 3662.2 3675.0 3686.4 3717.0 5083.8 5248.2 5287.9 5319.7 5325.2 3725.6 3726.2 3729.2 3766.1 3768.8 3781.6 3814.0 3827.9 3852.9 3855.2 4339.4 4342.8 4346.3 4347.4 4350.5 4880.9 5048.8 5054.8 5075.4 5135.3 1312.1 1330.5 1334.0 1344.9 1345.4 851.2 869.2 877.8 893.2 900.9 4351.2 4485.0 4485.4 4486.3 4497.2 5176.0 5187.5 5200.6 5221.7 5238.3 4463.6 4473.8 4485.7 4497.3 4500.3 2601.0 2763.4 2765.7 2772.3 2794.5 4919.2 4923.9 4924.0 4947.7 4955.4 5197.3 5242.8 5337.7 5362.3 5370.5 4849.4 4886.6 4914.9 4925.6 4940.5 5224.3 5233.4 5235.5 5235.8 5245.9 4914.7 5126.5 5143.1 5161.6 5185.4 4939.3 4941.2 4949.6 4956.1 4968.9 3681.6 3705.2 3730.9 3731.2 3747.7 5090.9 5282.8 5309.9 5317.6 5344.9 5069.1 5071.6 5087.3 5087.4 5102.7 5058.5 5066.9 5079.5 5141.8 5150.7 4744.4 4995.5 5037.7 5044.0 5085.8 4895.4 4974.3 5085.8 5093.3 5142.5 4913.9 4924.5 4933.0 4935.3 4939.1 02:49:20 INFO - PROCESS | 30683 | 02:49:20 INFO - PROCESS | 30683 | [#5] yui.html Cycles:180 Average:15789.06 Median:33216.30 stddev:22117.42 (66.6%) stddev-sans-first:22150.14 02:49:20 INFO - PROCESS | 30683 | Values: 621.8 649.9 658.2 696.5 719.4 11884.4 12786.3 12806.6 12809.0 12817.7 12871.3 12923.9 12944.7 12948.9 12971.7 598.7 726.2 742.1 746.2 749.8 756.6 759.5 759.5 759.5 762.2 792.2 796.4 798.2 798.5 799.4 635.2 776.3 792.8 794.5 794.7 591.6 591.6 591.8 592.1 592.2 12097.3 12198.5 12225.2 12251.6 12258.1 11737.9 11936.9 12351.1 12569.0 13056.2 9664.8 9775.6 9864.5 9882.8 9930.4 3515.6 3556.1 3557.8 3657.3 3753.3 63574.0 64711.0 65782.7 66088.7 66905.3 9195.9 9299.3 9326.8 9339.6 9443.9 17675.2 17744.0 17828.4 17892.9 17977.1 6685.1 6750.8 6879.6 6901.7 7000.7 3439.8 3515.4 3556.2 3601.4 3639.3 2819.3 2908.4 2911.2 2919.5 2921.1 483.2 486.3 489.3 495.9 502.4 395.6 470.5 521.0 541.3 544.3 6934.5 7290.2 7291.5 7328.6 7355.2 475.2 488.7 516.2 519.3 522.6 3292.8 3445.7 3469.6 3513.7 3660.6 57071.7 76236.0 77877.0 78929.0 88671.0 47611.5 71002.3 78426.6 79007.6 81508.4 44464.3 44479.1 70080.0 70652.0 73005.0 7226.2 7307.0 7599.4 7639.2 7678.4 34784.5 42473.0 43387.8 43418.3 43524.6 54095.7 57816.0 60031.4 60827.4 64868.0 9515.7 9674.0 9759.9 9931.4 9954.7 10428.6 10505.5 10621.5 10667.5 11036.3 9792.5 9875.3 9888.3 9991.3 10521.8 8438.4 8452.1 8767.1 8928.3 9349.7 9179.7 9197.9 9207.4 9249.0 9277.3 8484.9 9047.7 9092.2 9320.1 9515.4 8515.4 8580.4 8637.0 8720.6 8890.9 02:49:20 INFO - PROCESS | 30683 | -------- Summary: end -------- 02:49:20 INFO - PROCESS | 30683 | 02:49:21 INFO - TEST-INFO | 30683: exit 0 02:49:21 INFO - TEST-OK | dromaeo_css | took 1121557ms 02:49:21 INFO - TEST-START | kraken 02:49:21 INFO - operating with platform_type : linux_ 02:49:21 INFO - Initialising browser for kraken test... 02:49:21 INFO - TEST-INFO | started process 30983 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeTRN6l/profile http://localhost:41852/getInfo.html) 02:49:26 INFO - PROCESS | 30983 | __metrics Screen width/height:1600/1200 02:49:26 INFO - PROCESS | 30983 | colorDepth:24 02:49:26 INFO - PROCESS | 30983 | Browser inner width/height: 1024/697 02:49:26 INFO - PROCESS | 30983 | __metrics 02:49:26 INFO - PROCESS | 30983 | 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" 02:49:26 INFO - PROCESS | 30983 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:49:27 INFO - PROCESS | 30983 | console.error: PushService: 02:49:27 INFO - PROCESS | 30983 | stateChangeProcessEnqueue: Error transitioning state 02:49:27 INFO - PROCESS | 30983 | UnknownError 02:49:28 INFO - TEST-INFO | 30983: exit 0 02:49:28 INFO - Browser initialized. 02:49:28 INFO - Running cycle 1/1 for kraken test... 02:49:28 INFO - TEST-INFO | started process 31062 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeTRN6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:40 INFO - PROCESS | 31062 | RSS: Main: 181460992 02:49:40 INFO - PROCESS | 31062 | 02:49:42 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41852/tests/kraken/driver.html?testName=audio-beat-detection) 02:49:43 INFO - PROCESS | 31062 | RSS: Main: 235139072 02:49:43 INFO - PROCESS | 31062 | 02:49:46 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41852/tests/kraken/driver.html?testName=audio-dft) 02:49:47 INFO - PROCESS | 31062 | RSS: Main: 225267712 02:49:47 INFO - PROCESS | 31062 | 02:49:50 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41852/tests/kraken/driver.html?testName=audio-fft) 02:49:51 INFO - PROCESS | 31062 | RSS: Main: 304005120 02:49:51 INFO - PROCESS | 31062 | 02:49:53 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41852/tests/kraken/driver.html?testName=audio-oscillator) 02:49:53 INFO - PROCESS | 31062 | RSS: Main: 214261760 02:49:53 INFO - PROCESS | 31062 | 02:49:55 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41852/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:56 INFO - PROCESS | 31062 | RSS: Main: 214867968 02:49:56 INFO - PROCESS | 31062 | 02:49:59 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41852/tests/kraken/driver.html?testName=imaging-darkroom) 02:50:00 INFO - PROCESS | 31062 | RSS: Main: 253751296 02:50:00 INFO - PROCESS | 31062 | 02:50:03 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41852/tests/kraken/driver.html?testName=imaging-desaturate) 02:50:04 INFO - PROCESS | 31062 | RSS: Main: 248180736 02:50:04 INFO - PROCESS | 31062 | 02:50:07 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41852/tests/kraken/driver.html?testName=json-parse-financial) 02:50:08 INFO - PROCESS | 31062 | RSS: Main: 251179008 02:50:08 INFO - PROCESS | 31062 | 02:50:09 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41852/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:50:10 INFO - PROCESS | 31062 | RSS: Main: 214618112 02:50:10 INFO - PROCESS | 31062 | 02:50:12 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:50:12 INFO - PROCESS | 31062 | RSS: Main: 222937088 02:50:12 INFO - PROCESS | 31062 | 02:50:14 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:50:15 INFO - PROCESS | 31062 | RSS: Main: 223068160 02:50:15 INFO - PROCESS | 31062 | 02:50:18 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:50:19 INFO - PROCESS | 31062 | RSS: Main: 224256000 02:50:19 INFO - PROCESS | 31062 | 02:50:22 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:50:22 INFO - PROCESS | 31062 | RSS: Main: 222367744 02:50:22 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | Cycle 1(1): loaded http://localhost:41852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41852/tests/kraken/driver.html?testName=ai-astar) 02:50:24 INFO - PROCESS | 31062 | RSS: Main: 272703488 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | __start_tp_report 02:50:24 INFO - PROCESS | 31062 | _x_x_mozilla_page_load 02:50:24 INFO - PROCESS | 31062 | _x_x_mozilla_page_load_details 02:50:24 INFO - PROCESS | 31062 | |i|pagename|runs| 02:50:24 INFO - PROCESS | 31062 | |0;ai-astar;115;117;135;118;115;116;115;114;114;114 02:50:24 INFO - PROCESS | 31062 | |1;audio-beat-detection;151;122;152;189;122;150;153;124;186;122 02:50:24 INFO - PROCESS | 31062 | |2;audio-dft;162;163;165;161;162;161;161;162;159;161 02:50:24 INFO - PROCESS | 31062 | |3;audio-fft;76;76;76;177;78;76;75;75;166;75 02:50:24 INFO - PROCESS | 31062 | |4;audio-oscillator;83;83;143;83;83;88;140;83;84;150 02:50:24 INFO - PROCESS | 31062 | |5;imaging-gaussian-blur;117;117;117;118;118;118;117;118;117;120 02:50:24 INFO - PROCESS | 31062 | |6;imaging-darkroom;147;146;148;149;146;147;147;148;146;146 02:50:24 INFO - PROCESS | 31062 | |7;imaging-desaturate;96;95;96;96;96;96;95;96;94;95 02:50:24 INFO - PROCESS | 31062 | |8;json-parse-financial;71;63;62;64;70;64;64;64;64;63 02:50:24 INFO - PROCESS | 31062 | |9;json-stringify-tinderbox;64;66;64;65;65;65;64;65;65;64 02:50:24 INFO - PROCESS | 31062 | |10;stanford-crypto-aes;88;85;88;82;82;88;81;80;83;81 02:50:24 INFO - PROCESS | 31062 | |11;stanford-crypto-ccm;131;134;128;139;132;132;133;132;163;142 02:50:24 INFO - PROCESS | 31062 | |12;stanford-crypto-pbkdf2;176;176;173;173;177;173;176;171;171;175 02:50:24 INFO - PROCESS | 31062 | |13;stanford-crypto-sha256-iterative;69;69;72;69;68;69;69;69;68;67 02:50:24 INFO - PROCESS | 31062 | __end_tp_report 02:50:24 INFO - PROCESS | 31062 | __start_cc_report 02:50:24 INFO - PROCESS | 31062 | _x_x_mozilla_cycle_collect,2002 02:50:24 INFO - PROCESS | 31062 | __end_cc_report 02:50:24 INFO - PROCESS | 31062 | __startTimestamp1472291424677__endTimestamp 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | ------- Summary: start ------- 02:50:24 INFO - PROCESS | 31062 | Number of tests: 14 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#0] ai-astar Cycles:10 Average:117.30 Median:115.50 stddev:6.36 (5.5%) stddev-sans-first:6.69 02:50:24 INFO - PROCESS | 31062 | Values: 115.0 117.0 135.0 118.0 115.0 116.0 115.0 114.0 114.0 114.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:151.50 stddev:25.33 (16.7%) stddev-sans-first:26.83 02:50:24 INFO - PROCESS | 31062 | Values: 151.0 122.0 152.0 189.0 122.0 150.0 153.0 124.0 186.0 122.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.57 (1.0%) stddev-sans-first:1.66 02:50:24 INFO - PROCESS | 31062 | Values: 162.0 163.0 165.0 161.0 162.0 161.0 161.0 162.0 159.0 161.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#3] audio-fft Cycles:10 Average:95.00 Median:76.00 stddev:40.41 (53.2%) stddev-sans-first:42.27 02:50:24 INFO - PROCESS | 31062 | Values: 76.0 76.0 76.0 177.0 78.0 76.0 75.0 75.0 166.0 75.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:83.00 stddev:29.35 (35.4%) stddev-sans-first:30.32 02:50:24 INFO - PROCESS | 31062 | Values: 83.0 83.0 143.0 83.0 83.0 88.0 140.0 83.0 84.0 150.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.95 (0.8%) stddev-sans-first:0.97 02:50:24 INFO - PROCESS | 31062 | Values: 117.0 117.0 117.0 118.0 118.0 118.0 117.0 118.0 117.0 120.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 02:50:24 INFO - PROCESS | 31062 | Values: 147.0 146.0 148.0 149.0 146.0 147.0 147.0 148.0 146.0 146.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 02:50:24 INFO - PROCESS | 31062 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 94.0 95.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.00 stddev:3.03 (4.7%) stddev-sans-first:2.28 02:50:24 INFO - PROCESS | 31062 | Values: 71.0 63.0 62.0 64.0 70.0 64.0 64.0 64.0 64.0 63.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 02:50:24 INFO - PROCESS | 31062 | Values: 64.0 66.0 64.0 65.0 65.0 65.0 64.0 65.0 65.0 64.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:3.19 (3.8%) stddev-sans-first:3.00 02:50:24 INFO - PROCESS | 31062 | Values: 88.0 85.0 88.0 82.0 82.0 88.0 81.0 80.0 83.0 81.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:133.50 stddev:10.11 (7.6%) stddev-sans-first:10.52 02:50:24 INFO - PROCESS | 31062 | Values: 131.0 134.0 128.0 139.0 132.0 132.0 133.0 132.0 163.0 142.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:175.50 stddev:2.18 (1.2%) stddev-sans-first:2.20 02:50:24 INFO - PROCESS | 31062 | Values: 176.0 176.0 173.0 173.0 177.0 173.0 176.0 171.0 171.0 175.0 02:50:24 INFO - PROCESS | 31062 | 02:50:24 INFO - PROCESS | 31062 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.29 (1.9%) stddev-sans-first:1.36 02:50:24 INFO - PROCESS | 31062 | Values: 69.0 69.0 72.0 69.0 68.0 69.0 69.0 69.0 68.0 67.0 02:50:24 INFO - PROCESS | 31062 | -------- Summary: end -------- 02:50:24 INFO - PROCESS | 31062 | 02:50:25 INFO - TEST-INFO | 31062: exit 0 02:50:25 INFO - TEST-OK | kraken | took 64518ms 02:50:25 INFO - SUITE-END | took 1186s 02:50:26 INFO - Completed test suite (00:19:46) 02:50:26 INFO - javascript benchmark 02:50:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2614.4901744159883, 2619.5415802234606, 2633.8814753336096, 2672.412069344745, 2678.758911697948, 4678.461976872661, 4733.952660473395, 4758.6431017673685, 4783.37816083909, 4846.200376937801, 4772.80908763649, 4794.664373493857, 4851.029794041197, 4856.05306965142, 4858.805647774094, 4332.588404101605, 4340.131973605292, 4411.977940110295, 4475.820967161309, 4575.313702944552, 4471.664625153094, 4504.414426124616, 4521.864344069667, 4542.04617030424, 4551.1580357633775, 4315.525292217853, 4351.303791393393, 4357.36818161368, 4363.65090792739, 4396.516383197878, 4330.610245077958, 4339.566043395666, 4351.82592696295, 4407.669424793154, 4411.073674528353, 4330.090680957002, 4389.2318844202455, 4395.274779661352, 4398.076403955173, 4406.0, 4692.788824502905, 4792.520747925215, 4800.375951126366, 4806.735629540374, 4807.278908163804, 5081.568066714333, 5133.640645154841, 5149.150390185615, 5151.150060240056, 5155.561777248939, 5159.896802063938, 5160.458151894072, 5169.405518365398, 5176.0683077046115, 5185.170372740344, 4655.930161047587, 4767.976160119178, 4818.6626936114835, 4823.2282834746275, 4862.85411437661, 5254.842354729364, 5287.867803304948, 5303.68177909319, 5383.973080134652, 5396.595255355785, 5076.289319495274, 5097.64316497849, 5101.107306221396, 5154.175331946945, 5156.5616922562, 4995.775190116381, 5103.2855400244, 5111.642185047085, 5123.820666276662, 5125.077486052472, 5125.461826508237, 5136.5377116059735, 5190.24741412493, 5194.298769666047, 5200.219967004904, 5164.612654050961, 5179.223116532475, 5185.325907631984, 5185.559227465632, 5238.5547228486, 4264.381664658607, 4429.291313389842, 4443.466783985878, 4452.487963884195, 4493.955060449419, 3747.2318174774105, 3801.467794508771, 3826.234753049401, 3881.9805900970878, 3905.902352441155, 4391.780410979438, 4404.053128577307, 4406.471223453206, 4411.9558804411545, 4460.50934397216, 5144.176931690911, 5151.175811870083, 5160.225966105039, 5162.586993040548, 5221.164613661796, 4275.315949448073, 4355.303151495746, 4408.6913916026215, 4423.8451654192595, 4468.128714900563, 4621.468531118897, 4652.395188625457, 4652.813887444532, 4694.014257006067, 4705.247160454311, 4788.257820037899, 4816.494268101939, 4820.9517904821205, 4834.057358815067, 4895.510448955076, 5031.345925029723, 5081.644284900021, 5098.79604815818, 5131.025105229995, 5162.9483705162465, 4821.4696383397095, 4823.155947709207, 4826.6621336506105, 4853.635977301646, 4874.4150701916, 5040.672356296829, 5047.369078865142, 5049.2426136079885, 5050.469700681376, 5051.696898186121, 5081.3902331718955, 5124.871878203075, 5133.486651334837, 5173.1205695031185, 5190.740462976912, 4932.7286999215385, 5014.0974624567925, 5033.144365457954, 5034.899302014012, 5048.343715316985, 5039.143345631325, 5047.545720885138, 5049.166887463527, 5051.797928082836, 5108.489151084862, 4959.876003099951, 4961.875953101201, 4969.701817890938, 4972.254161875748, 4972.453030166606, 4846.442659094249, 5000.4749501303295, 5003.599712023102, 5035.521625445577, 5087.109755792648, 4879.878003049952, 4892.633052521004, 4900.975495122501, 4908.969116485435, 4972.303877457087], "value": 4719.513879320409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.9004884096441, 559.2170960655083, 562.13150682196, 571.9942800571996, 581.7469400810651, 27669.0, 37853.0, 39221.607783922154, 39249.01877453062, 42768.786156069174, 39890.0, 40224.59775402245, 40326.79836600813, 40368.39447408284, 40380.394294085614, 7679.040119985002, 7829.608519574027, 7832.412569057315, 7837.451378403514, 7839.1768864269, 7338.266173382673, 9558.80882382352, 9793.21654267657, 9795.069468400527, 10087.445190514518, 10017.0, 10116.291859569834, 10123.797524049514, 10128.189744820396, 10134.493275336241, 9979.30144889858, 10046.246531510165, 10046.849297260547, 10056.245781566338, 10069.496525173712, 9905.603775848957, 10004.649837255734, 10007.799844003079, 10009.349392289478, 10015.248856335804, 37028.81485592582, 37262.441063384074, 37265.0, 37311.06722331936, 37347.62652373469, 2945.661248956376, 3035.2563621912773, 3384.323135372935, 3449.2066824630224, 3474.339875423687, 27045.323866903287, 27468.58797118045, 29111.56332655012, 29231.707682923112, 29980.10059698191, 3473.166440054394, 3547.320052087228, 3561.5370001899846, 3595.7482976191677, 3652.8904132876055, 241334.0, 244787.0, 247193.7640311805, 247477.0, 248046.0, 56125.0, 56781.0, 56853.71573142191, 56883.43116568781, 56912.71543642338, 32675.509867352008, 33644.49533257003, 33662.158446039044, 35850.82074589663, 58327.0, 13990.16059036461, 14168.787468187986, 14169.645758855904, 14172.220527871066, 14177.291135443393, 9369.203617692436, 9781.951090244504, 10202.642907498202, 10305.072543470973, 10315.690529283984, 18181.54546136357, 18819.0, 19087.52281192981, 19196.0, 19270.61458770817, 7086.39765619928, 7188.137423509213, 7211.314925082109, 7398.667059982314, 7469.738559150513, 758.4463341760546, 819.9221073997961, 845.3237410071918, 978.8433850583872, 1007.4408703169664, 25511.0, 27269.227500212422, 27270.0, 27562.173134805988, 27820.443591128063, 883.3242569434387, 902.6750369866843, 924.8196601662746, 944.2304521814699, 1000.0749306891242, 14471.539838227527, 14626.634334141732, 14915.552533424014, 14938.775918361232, 15519.45681901128, 393333.0, 394768.0, 394890.0, 395832.0, 396040.0, 49161.508384915694, 49644.50355496471, 49665.00669986652, 49677.751611241714, 49769.0, 1052.3580615824342, 1110.3005106782675, 1115.576081089197, 1159.4202898550725, 1197.2397527570056, 20634.58730825345, 29482.0, 29483.262918427212, 29981.250468738457, 30442.84778576093, 46128.769356153, 46555.0, 46685.533144668116, 46756.29865552088, 46780.7660961693, 351285.0, 352701.0, 354343.0, 354357.0, 354765.0, 21556.137754489646, 22167.556648867256, 23230.3030909073, 24836.0, 25333.240002799947, 35791.64208357883, 39222.2155556893, 39291.60708392879, 39429.0, 39784.0, 33084.66915330912, 33581.664183357854, 34087.48868766921, 34092.14769630779, 34932.47601286034, 32626.83686581647, 32721.672783272814, 32974.83512582518, 33460.33079338351, 33637.0, 33170.83414582912, 33566.32867342591, 33644.0, 33746.66253337435, 34923.825380872935, 26958.460830782882, 29683.703162968093, 32823.67176328302, 33041.0, 33387.0, 24846.546036189433, 25158.74841251564, 25192.0, 25419.87290063538, 25656.871715641304], "value": 17637.014975552476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8590178027009, 356.6968077134436, 359.4105666706601, 361.72870347239564, 384.8210582079333, 13091.672708182301, 16806.915965420172, 16817.0, 17488.038157901312, 18715.43853684388, 14089.225092619901, 14423.5672929812, 14595.781063284032, 15337.309821058052, 15657.686846263068, 4437.511873693891, 5140.126178549646, 5187.221916712486, 5216.8174113906025, 5268.394134674523, 3390.0846771371716, 5186.1442861927735, 5820.1269809528485, 6119.3574674659185, 6126.877462450749, 6187.257529096515, 6189.597676151036, 6191.566590338678, 6193.6283822970545, 6194.659293738842, 6735.5621884577595, 7051.964740176266, 7069.646517674096, 7193.784186474414, 7217.711291548332, 6748.966255168742, 6924.134483189601, 6951.339622735883, 6955.130608673916, 6983.8603227935155, 22568.66147007796, 23056.193033243922, 23513.17703880373, 23531.17640882578, 23686.644700329507, 1952.248384372027, 1994.3019943019972, 2006.7290915726337, 2080.3446914221972, 2163.5456554123652, 13051.41268642904, 14480.565583032527, 14592.343344549521, 14717.852821471863, 15265.847341526554, 1800.4778614201866, 1835.4860639021094, 1847.630473905211, 1850.1489314915154, 1861.2461952909093, 65256.67371663159, 76031.0, 79355.30315149548, 79630.60184699098, 81478.59260703658, 16459.506214813573, 16477.093759843083, 16517.504474865775, 16717.83282167163, 16886.662266754596, 28239.57640635392, 28261.57607635887, 28324.291892702437, 28333.57499637507, 28494.71505284962, 8256.305812313969, 8557.144285571518, 8789.0, 8884.333674974403, 9181.40320879154, 7082.787516374517, 7123.074000379917, 7181.569105853666, 7336.339729424378, 7361.153467351216, 8148.796280092926, 8494.362922780692, 8526.488410695378, 8642.956785216034, 8733.737987860375, 2588.6505321781697, 2954.8818047278296, 2965.733084022448, 3372.7976321420792, 3393.932121357559, 742.254034695128, 748.4311922938606, 801.9879701804477, 830.4767996162379, 870.9390342676079, 16629.417970370978, 16729.247183876512, 17194.226259818337, 17713.0, 17990.460286191435, 777.8327659553223, 798.6206551887807, 838.174398217755, 890.7194233816457, 895.7939673875045, 9479.099485548863, 9692.51537423126, 9921.751956201153, 9927.255455840841, 9942.154916832005, 387374.0, 388084.0, 392361.0694502772, 392630.0, 393905.0, 56375.15437268444, 72742.2725772746, 76672.61663691758, 77839.0, 80113.0, 831.1646794971016, 857.8884744983112, 858.9441686290371, 859.4070091636751, 860.247283626839, 15139.0, 15169.241537922839, 15361.923190383977, 15483.84516154869, 16003.839961600235, 34002.489962650085, 34835.303293934485, 35139.29721405506, 35737.64262357343, 35934.8203258982, 46446.53553464422, 46583.0, 46656.0, 48612.0, 49404.258936116705, 7384.224656410999, 7419.591922444318, 7454.397304579541, 7774.0, 7979.122296547491, 9424.670136545188, 10573.207009474167, 10870.0, 11076.944615276872, 11350.673112011853, 9818.116369526491, 9955.20358371314, 10200.0, 10307.896921030693, 10687.0, 9907.554160062959, 10010.0, 10076.596936122472, 10076.69769906904, 10086.394816311045, 9801.705948821547, 9976.2517811163, 10189.84715229287, 10211.183105351703, 10501.0, 7535.171131175457, 7666.121800158997, 7687.2697093776005, 7759.844803104019, 8145.42981991255, 7959.721409750631, 9197.954010229905, 10075.949620251851, 10173.796524069625, 10428.0], "value": 9013.636622257542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.3693208793734, 1349.372541768078, 1370.2799942114907, 1608.9436869709562, 1624.3827345608674, 7201.459890508213, 7746.922530774697, 7750.4187185961, 7752.457327987029, 7765.107012693535, 6604.867902641944, 7028.859422811541, 7284.927150728491, 7286.089238845145, 7653.732119375823, 2158.5359147783224, 2186.0, 2196.8132708719777, 2200.977990220101, 2201.8348623853194, 2137.871727696343, 2176.81497072749, 2215.8338124640636, 2216.656418255165, 2218.744844342897, 1906.8665193436466, 1966.2528239269059, 1967.616314818611, 1967.6261510312995, 2097.056324653904, 2123.819475344598, 2157.665561837902, 2159.7408311002623, 2161.7081693971427, 2165.2529877192424, 2168.40622142725, 2184.71598692169, 2187.803097721213, 2197.5275315807016, 2207.602631526325, 7559.28186822251, 7617.961910190469, 7621.58081305528, 7621.695132194705, 7624.695012199505, 4224.978875105636, 4350.673699472521, 4372.978135109336, 4399.670024748125, 4404.075144219686, 2807.6911539730613, 2868.498012847743, 2910.0542323744867, 2915.0817492489796, 2925.2686828292926, 984.9458279794609, 1004.7739258666815, 1016.3749294184097, 1028.9588416463332, 1034.3380236648552, 9687.1281584657, 9716.659916902874, 9778.168855647278, 9793.853092203623, 9830.606775728962, 3908.9609103908597, 4069.4709687740997, 4107.178564287095, 4177.289860723684, 4235.23765722173, 8475.660973561111, 8487.915120848837, 8495.320374370036, 8497.405181637227, 8500.957495212608, 4405.757683327448, 4406.383106365111, 4412.227860124465, 4415.66882483815, 4417.094495628388, 3941.0147463134217, 3986.661133803659, 4012.9197416052098, 4014.458048163519, 4018.0758425562285, 3561.821908904544, 3605.837737301828, 3617.421212606023, 3653.3606618841595, 3656.8720094797086, 1178.7642471505733, 1220.9877901221053, 1231.94456249469, 1235.4687484381802, 1243.925364478116, 799.7320897499286, 805.5046146619903, 810.9756707298791, 814.9551774652451, 826.7561069484517, 4976.6765160265195, 5022.547970682583, 5047.798088076509, 5187.688738675692, 5220.295260139908, 400.7555391211359, 400.7735629369397, 405.83157989434056, 428.69134223359754, 437.82924659382866, 3499.107727529464, 3531.69980551656, 3536.664016918389, 3586.7489275750445, 3589.2821435712963, 8736.563171841382, 9256.444613323221, 9257.259419246444, 9257.398269112486, 9278.814423711488, 9176.81646367069, 9196.586153623102, 9208.815823683488, 9215.03242159577, 9228.815423691623, 6873.896891546631, 7088.04311417952, 7168.641567921479, 7172.928270717331, 7174.210836807947, 5109.463541276587, 5132.79468821243, 5176.275321454924, 5238.266642670105, 5270.0, 8455.154484551496, 8471.237588617054, 8512.829743405222, 8518.78703032429, 8624.353173512138, 8356.331493480386, 8633.35249856276, 8642.740717778477, 8680.435771674822, 8773.078826723358, 6525.086487891794, 6575.112359831553, 6613.801585952429, 6616.338366163345, 6622.70197841108, 7138.500304978602, 7445.739399121005, 7518.849623007619, 7706.730264440717, 7711.922880771121, 6397.808065758035, 6520.608763474207, 6590.0, 6689.464842812466, 6844.1444819397575, 6471.902921456276, 6592.340765923561, 6711.395974362331, 6751.628660423527, 6766.695498702668, 5761.798337058183, 5860.2967643883, 5877.382874798084, 5879.147523609138, 5885.058390657474, 4308.13837232561, 4378.737275763465, 4400.12377843669, 4476.9328460072475, 4540.704854184468, 4485.596296333346, 4531.637469002538, 4537.478190008059, 4596.379488768974, 4608.88477788058], "value": 3958.972820384796, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.8140630699506, 292.89602191222116, 303.8146730494398, 311.6447250134847, 311.7396973527105, 4826.42082950046, 4884.462709101996, 4909.901801963963, 4910.1898186799135, 4937.950620493794, 4780.737059461728, 4792.472827988919, 4844.442889067758, 4882.217990433493, 4884.6824956377895, 3624.6226433955126, 3657.6342365763344, 3784.924301513968, 3801.657850793428, 3803.1442925341717, 3784.7161462890253, 3795.886123416302, 3822.73240873139, 3838.5201849768778, 3852.84588616455, 3759.7556158849725, 3768.340540405432, 3776.3580191367396, 3781.2815565042556, 3814.1036856338737, 3599.4240921452574, 3679.392900171482, 3730.9067273318115, 3756.981215093934, 3795.7153213508823, 3295.6045274566964, 3332.800031998088, 3335.916602084943, 3344.4481660526226, 3345.6821601947777, 4692.5218556154705, 4698.647601429906, 4704.153252414564, 4717.127331443694, 4785.138675038492, 4250.574942505756, 4342.804573794187, 4348.5216626171095, 4388.091180336563, 4398.164348773755, 4953.826616068456, 4996.675216110941, 4996.975015124937, 4999.225120106389, 5000.374953130859, 3656.7567027210875, 3662.1760103976394, 3674.9816250918843, 3686.4101743720876, 3716.9628303716886, 5083.839368268471, 5248.160294352923, 5287.9471205287455, 5319.654222475527, 5325.174597937287, 3725.6274372562525, 3726.1802403471193, 3729.2355067211697, 3766.133789228438, 3768.8304026318883, 3781.621837816251, 3814.0, 3827.9043023924623, 3852.9422058669143, 3855.151866589346, 4339.435873336509, 4342.805728424645, 4346.348047792856, 4347.4131941841, 4350.543192964757, 4880.902381952341, 5048.7728052237, 5054.772535235923, 5075.416327122428, 5135.255387968724, 1312.1274352555445, 1330.4545136494185, 1333.953311634088, 1344.877027681877, 1345.3744009035845, 851.199872120205, 869.1525762381627, 877.8156587116777, 893.1693525021662, 900.8603666431713, 4351.238533256667, 4484.968457254849, 4485.41689580359, 4486.259767138451, 4497.168023915586, 5175.9741201293755, 5187.481251874782, 5200.609954253446, 5221.712805795718, 5238.292830467838, 4463.575960283768, 4473.843415480443, 4485.708428952108, 4497.268551607654, 4500.279955207151, 2600.9869950650127, 2763.350612606036, 2765.6542932133484, 2772.265349682336, 2794.5109605818902, 4919.237518184687, 4923.90152196954, 4924.020967314795, 4947.727874966911, 4955.35580374549, 5197.272381866542, 5242.846573753768, 5337.6797392156595, 5362.300012991023, 5370.4629537045985, 4849.418069831572, 4886.60907127429, 4914.852554423373, 4925.556699896955, 4940.481249468754, 5224.346956630421, 5233.424323324507, 5235.502627250406, 5235.842924712265, 5245.9475405246985, 4914.655974081923, 5126.53861152498, 5143.125668636266, 5161.587073034223, 5185.351831021122, 4939.283394133284, 4941.160002799605, 4949.554540091409, 4956.10790057793, 4968.850934471971, 3681.576618688885, 3705.184859330943, 3730.9440358394104, 3731.1530792258004, 3747.662710356081, 5090.898182036264, 5282.81510147143, 5309.893802124013, 5317.6011799115995, 5344.893102138013, 5069.138246498178, 5071.619628527801, 5087.262346959744, 5087.440381557952, 5102.744862756922, 5058.54473097423, 5066.898662026812, 5079.469768510997, 5141.820036298712, 5150.690958542499, 4744.4069491313585, 4995.450500444876, 5037.748112594429, 5044.0, 5085.847424577269, 4895.4370247420575, 4974.303597496281, 5085.796568137233, 5093.261477085728, 5142.51146141116, 4913.852584422473, 4924.482929292516, 4932.966225771053, 4935.259711043373, 4939.110960027229], "value": 3837.3036955176785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.8445388652837, 649.9350064993498, 658.2035736758522, 696.5089611823665, 719.4316489972922, 11884.405779711013, 12786.296753678544, 12806.615801525946, 12809.0, 12817.743645127091, 12871.292078935678, 12923.870761292384, 12944.676383090427, 12948.870511294885, 12971.740565188715, 598.6826981699706, 726.1576571177435, 742.1317059040908, 746.1642959884937, 749.7938067031582, 756.5990025286579, 759.4607828441785, 759.4873460414226, 759.525296689569, 762.211111499598, 792.1959211400433, 796.398718967179, 798.22572105058, 798.4889670610813, 799.4244144216184, 635.1552435261115, 776.3323541754087, 792.774059393076, 794.51931581393, 794.7496538590326, 591.5977135547826, 591.60658162322, 591.8165368735663, 592.0881841963371, 592.1975722895501, 12097.274163550215, 12198.451069701885, 12225.205361651553, 12251.632451026484, 12258.1419300649, 11737.882621173765, 11936.940315298369, 12351.073669474736, 12569.0, 13056.216627002315, 9664.75838104046, 9775.560099795526, 9864.50677466131, 9882.752931176778, 9930.404175749478, 3515.5668106658823, 3556.0931962349696, 3557.7509574329547, 3657.250263695936, 3753.3431649461236, 63574.0, 64711.0, 65782.67108664474, 66088.66955665238, 66905.33094669088, 9195.862062069074, 9299.349045566745, 9326.813463730687, 9339.610383119923, 9443.858342124873, 17675.20461579232, 17744.0, 17828.3760068397, 17892.910535447238, 17977.1011449427, 6685.097511835937, 6750.797476075725, 6879.6216208108035, 6901.723931042802, 7000.719971201095, 3439.793612383265, 3515.4375299951885, 3556.2176321209295, 3601.3877640801124, 3639.3267245559127, 2819.2528979820363, 2908.4473949949443, 2911.1848682368973, 2919.518279483904, 2921.0944607171846, 483.22683706070006, 486.34343636090995, 489.26121556449306, 495.86115887551546, 502.36200025966815, 395.6024195683377, 470.4542730432682, 520.9713465759344, 541.3395657298169, 544.3495023447022, 6934.514583979174, 7290.198078211392, 7291.52605080668, 7328.633568321563, 7355.190928997806, 475.2182659525062, 488.74096728733826, 516.1560848013524, 519.3456245131086, 522.5558275465823, 3292.8188949608, 3445.707114895212, 3469.5836499619704, 3513.6662017108847, 3660.5790334111384, 57071.71464142653, 76236.0, 77877.0, 78929.0, 88671.0, 47611.5238847614, 71002.2899771006, 78426.60786696144, 79007.60496197482, 81508.36620323859, 44464.3330350045, 44479.11041779211, 70080.0, 70652.0, 73005.0, 7226.205117436973, 7307.0, 7599.354054905395, 7639.159692433939, 7678.385729141767, 34784.47823282602, 42473.0, 43387.78306108449, 43418.34872476852, 43524.56475435205, 54095.72952135214, 57816.0, 60031.39968600258, 60827.391726083944, 64868.0, 9515.715378423823, 9673.951630241803, 9759.9024009759, 9931.404115753077, 9954.651587194414, 10428.634997775345, 10505.527251273865, 10621.522031508755, 10667.466626668791, 11036.282641628268, 9792.461414622265, 9875.259355548218, 9888.258380621339, 9991.25065620096, 10521.842172367573, 8438.409311348392, 8452.112528184452, 8767.079456656958, 8928.285737140883, 9349.672761453588, 9179.681667512628, 9197.908020919705, 9207.447553146832, 9248.953755231181, 9277.2578193743, 8484.915150848412, 9047.728568142966, 9092.227160691415, 9320.114589114024, 9515.381500202464, 8515.40392172542, 8580.399372043898, 8637.0, 8720.563971801257, 8890.911090889267], "value": 6008.888506071968, "unit": "score"}], "name": "dromaeo_css", "value": 6396.557445597108, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 135.0, 118.0, 115.0, 116.0, 115.0, 114.0, 114.0, 114.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 152.0, 189.0, 122.0, 150.0, 153.0, 124.0, 186.0, 122.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 165.0, 161.0, 162.0, 161.0, 161.0, 162.0, 159.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 177.0, 78.0, 76.0, 75.0, 75.0, 166.0, 75.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 143.0, 83.0, 83.0, 88.0, 140.0, 83.0, 84.0, 150.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0, 120.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 148.0, 149.0, 146.0, 147.0, 147.0, 148.0, 146.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 94.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 63.0, 62.0, 64.0, 70.0, 64.0, 64.0, 64.0, 64.0, 63.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 82.0, 82.0, 88.0, 81.0, 80.0, 83.0, 81.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 134.0, 128.0, 139.0, 132.0, 132.0, 133.0, 132.0, 163.0, 142.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 173.0, 173.0, 177.0, 173.0, 176.0, 171.0, 171.0, 175.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 72.0, 69.0, 68.0, 69.0, 69.0, 69.0, 68.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1576.3, "alertThreshold": 2.0}]} 02:50:26 INFO - Return code: 0 02:50:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:50:26 INFO - # TBPL SUCCESS # 02:50:26 INFO - Running post-action listener: _resource_record_post_action 02:50:26 INFO - [mozharness: 2016-08-27 09:50:26.478424Z] Finished run-tests step (success) 02:50:26 INFO - Running post-run listener: _resource_record_post_run 02:50:26 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 12288; Write bytes: 417734656; Read time: 312; Write time: 312272 02:50:26 INFO - TinderboxPrint: CPU usage
13.0% 02:50:26 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 312 02:50:26 INFO - TinderboxPrint: I/O write bytes / time
417,734,656 / 312,272 02:50:26 INFO - TinderboxPrint: CPU idle
8,348.3 (87.0%) 02:50:26 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 02:50:26 INFO - TinderboxPrint: Swap in / out
0 / 0 02:50:26 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 125386752; Read time: 120; Write time: 116184 02:50:26 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292347904; Read time: 192; Write time: 196088 02:50:27 INFO - Running post-run listener: _upload_blobber_files 02:50:27 INFO - Blob upload gear active. 02:50:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:50:27 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 02:50:27 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:50:27 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:50:27 INFO - (blobuploader) - INFO - Open directory for files ... 02:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:28 INFO - (blobuploader) - INFO - Done attempting. 02:50:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:50:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:50:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:31 INFO - (blobuploader) - INFO - Done attempting. 02:50:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:50:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:50:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:32 INFO - (blobuploader) - INFO - Done attempting. 02:50:32 INFO - (blobuploader) - INFO - Iteration through files over. 02:50:32 INFO - Return code: 0 02:50:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:50:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:50:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/537f51fe1ad2ecc4550f836e61ff12bfede76ada31027c3c757b7275cc09eaa7e82064c2285cd39ac0e7f8fb0ad0a4aa194d930ea8a03e50d1acf7836e3fb68d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db015eaa7d0df2ea77f60a6d7197aa754a893b677349780ef8c260738814b1cc7cdfa9ec7c7d932758cdce239450021862ca3ea22f31ebed1c7ffa7540aec2fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dac2495a5c78687194dcbece14c791297fa79e109365d707181a1fd6cf33700a50c7f99532f1364a36465166da099e62e58a0ef153cbab05dfaf7c554c812c5d"} 02:50:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:50:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:50:32 INFO - Contents: 02:50:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/537f51fe1ad2ecc4550f836e61ff12bfede76ada31027c3c757b7275cc09eaa7e82064c2285cd39ac0e7f8fb0ad0a4aa194d930ea8a03e50d1acf7836e3fb68d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db015eaa7d0df2ea77f60a6d7197aa754a893b677349780ef8c260738814b1cc7cdfa9ec7c7d932758cdce239450021862ca3ea22f31ebed1c7ffa7540aec2fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dac2495a5c78687194dcbece14c791297fa79e109365d707181a1fd6cf33700a50c7f99532f1364a36465166da099e62e58a0ef153cbab05dfaf7c554c812c5d"} 02:50:32 INFO - Running post-run listener: copy_logs_to_upload_dir 02:50:32 INFO - Copying logs to upload dir... 02:50:32 INFO - mkdir: /builds/slave/test/build/upload/logs 02:50:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.941243 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-27 02:50:31.721357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 02:50:31.725098) ========= 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=920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147 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-Non-PGO/sha512/537f51fe1ad2ecc4550f836e61ff12bfede76ada31027c3c757b7275cc09eaa7e82064c2285cd39ac0e7f8fb0ad0a4aa194d930ea8a03e50d1acf7836e3fb68d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db015eaa7d0df2ea77f60a6d7197aa754a893b677349780ef8c260738814b1cc7cdfa9ec7c7d932758cdce239450021862ca3ea22f31ebed1c7ffa7540aec2fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dac2495a5c78687194dcbece14c791297fa79e109365d707181a1fd6cf33700a50c7f99532f1364a36465166da099e62e58a0ef153cbab05dfaf7c554c812c5d"} build_url:https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011132 build_url: 'https://queue.taskcluster.net/v1/task/VVdzsGsGTEezfJ4yntgBaQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/537f51fe1ad2ecc4550f836e61ff12bfede76ada31027c3c757b7275cc09eaa7e82064c2285cd39ac0e7f8fb0ad0a4aa194d930ea8a03e50d1acf7836e3fb68d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db015eaa7d0df2ea77f60a6d7197aa754a893b677349780ef8c260738814b1cc7cdfa9ec7c7d932758cdce239450021862ca3ea22f31ebed1c7ffa7540aec2fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dac2495a5c78687194dcbece14c791297fa79e109365d707181a1fd6cf33700a50c7f99532f1364a36465166da099e62e58a0ef153cbab05dfaf7c554c812c5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 02:50:31.752111) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:50:31.752728) ========= 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=920470bf432669ad7e3a5387000001ca-1472290184.965677-2098627147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004388 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 02:50:31.835570) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 02:50:31.835956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 02:50:31.836276) ========= ========= Total master_lag: 0.18 =========