builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1472238821.71 results: success (0) buildid: 20160826102618 builduid: fae1501d99ac4d6791cdd6643b550d53 revision: 9797d309cc749cacf1f8d092ca5186b0f0e2bdaf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.708662) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.709149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.709472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.750968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.751368) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 12:13:41-- 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% 87.7M=0s 2016-08-26 12:13:41 (87.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.071714 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.845101) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.845420) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015556 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:41.899756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 12:13:41.900115) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9797d309cc749cacf1f8d092ca5186b0f0e2bdaf --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 9797d309cc749cacf1f8d092ca5186b0f0e2bdaf --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=2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 12:13:42,053 truncating revision to first 12 chars 2016-08-26 12:13:42,054 Setting DEBUG logging. 2016-08-26 12:13:42,054 attempt 1/10 2016-08-26 12:13:42,054 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9797d309cc74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 12:13:43,210 unpacking tar archive at: fx-team-9797d309cc74/testing/mozharness/ program finished with exit code 0 elapsedTime=1.461494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 12:13:43.376776) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:43.377458) ========= script_repo_revision: 9797d309cc749cacf1f8d092ca5186b0f0e2bdaf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:43.378231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:43.378726) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 12:13:43.392600) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-26 12:13:43.393104) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:13:43 INFO - MultiFileLogger online at 20160826 12:13:43 in /builds/slave/test 12:13:43 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 12:13:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:43 INFO - {'append_to_log': False, 12:13:43 INFO - 'base_work_dir': '/builds/slave/test', 12:13:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:13:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:43 INFO - 'branch': 'Fx-Team-Non-PGO', 12:13:43 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:43 INFO - 'config_files': ('talos/linux_config.py',), 12:13:43 INFO - 'default_actions': ('clobber', 12:13:43 INFO - 'read-buildbot-config', 12:13:43 INFO - 'download-and-extract', 12:13:43 INFO - 'populate-webroot', 12:13:43 INFO - 'create-virtualenv', 12:13:43 INFO - 'install', 12:13:43 INFO - 'run-tests'), 12:13:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:43 INFO - 'download_minidump_stackwalk': True, 12:13:43 INFO - 'download_symbols': 'ondemand', 12:13:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:43 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:43 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:43 INFO - 'installer_path': 'installer.exe', 12:13:43 INFO - 'log_level': 'info', 12:13:43 INFO - 'log_name': 'talos', 12:13:43 INFO - 'log_to_console': True, 12:13:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:13:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:13:43 INFO - 'opt_config_files': (), 12:13:43 INFO - 'pip_index': False, 12:13:43 INFO - 'sps_profile': False, 12:13:43 INFO - 'sps_profile_interval': 0, 12:13:43 INFO - 'suite': 'dromaeojs', 12:13:43 INFO - 'system_bits': '32', 12:13:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:43 INFO - 'title': 'talos-linux64-ix-012', 12:13:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:43 INFO - 'use_talos_json': True, 12:13:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:43 INFO - 'work_dir': 'build'} 12:13:43 INFO - [mozharness: 2016-08-26 19:13:43.641821Z] Running clobber step. 12:13:43 INFO - Running pre-action listener: _resource_record_pre_action 12:13:43 INFO - Running main action method: clobber 12:13:43 INFO - rmtree: /builds/slave/test/build 12:13:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:44 INFO - Running post-action listener: _resource_record_post_action 12:13:44 INFO - [mozharness: 2016-08-26 19:13:44.106810Z] Finished clobber step (success) 12:13:44 INFO - [mozharness: 2016-08-26 19:13:44.106912Z] Running read-buildbot-config step. 12:13:44 INFO - Running pre-action listener: _resource_record_pre_action 12:13:44 INFO - Running main action method: read_buildbot_config 12:13:44 INFO - Using buildbot properties: 12:13:44 INFO - { 12:13:44 INFO - "project": "", 12:13:44 INFO - "product": "firefox", 12:13:44 INFO - "script_repo_revision": "production", 12:13:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:13:44 INFO - "repository": "", 12:13:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 12:13:44 INFO - "buildid": "20160826102618", 12:13:44 INFO - "pgo_build": "False", 12:13:44 INFO - "basedir": "/builds/slave/test", 12:13:44 INFO - "buildnumber": 1887, 12:13:44 INFO - "slavename": "talos-linux64-ix-012", 12:13:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:13:44 INFO - "platform": "ubuntu64_hw", 12:13:44 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:13:44 INFO - "branch": "fx-team", 12:13:44 INFO - "repo_path": "integration/fx-team", 12:13:44 INFO - "revision": "9797d309cc749cacf1f8d092ca5186b0f0e2bdaf", 12:13:44 INFO - "stage_platform": "linux64", 12:13:44 INFO - "builduid": "fae1501d99ac4d6791cdd6643b550d53", 12:13:44 INFO - "slavebuilddir": "test" 12:13:44 INFO - } 12:13:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:13:44 INFO - Running post-action listener: _resource_record_post_action 12:13:44 INFO - [mozharness: 2016-08-26 19:13:44.109371Z] Finished read-buildbot-config step (success) 12:13:44 INFO - [mozharness: 2016-08-26 19:13:44.109461Z] Running download-and-extract step. 12:13:44 INFO - Running pre-action listener: _resource_record_pre_action 12:13:44 INFO - Running main action method: download_and_extract 12:13:44 INFO - mkdir: /builds/slave/test/build/tests 12:13:44 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')]} 12:13:44 INFO - trying https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:13:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:44 INFO - Downloaded 1361 bytes. 12:13:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:13:44 INFO - Using the following test package requirements: 12:13:44 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:13:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:13:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:13:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'jsshell-linux-x86_64.zip'], 12:13:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:13:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:13:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:13:44 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:13:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:13:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:13:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:13:44 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')]} 12:13:44 INFO - trying https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:13:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:45 INFO - Downloaded 20303367 bytes. 12:13:45 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 12:13:49 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 12:13:49 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')]} 12:13:49 INFO - trying https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:13:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:50 INFO - Downloaded 20303367 bytes. 12:13:50 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 12:13:55 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')]} 12:13:55 INFO - trying https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:13:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:56 INFO - Downloaded 14413998 bytes. 12:13:56 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 12:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:57 INFO - trying https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:13:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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 12:13:59 INFO - Downloaded 55858667 bytes. 12:13:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:13:59 INFO - mkdir: /builds/slave/test/properties 12:13:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:13:59 INFO - Writing to file /builds/slave/test/properties/build_url 12:13:59 INFO - Contents: 12:13:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:13:59 INFO - Running post-action listener: _resource_record_post_action 12:13:59 INFO - Running post-action listener: set_extra_try_arguments 12:13:59 INFO - [mozharness: 2016-08-26 19:13:59.887678Z] Finished download-and-extract step (success) 12:13:59 INFO - [mozharness: 2016-08-26 19:13:59.887905Z] Running populate-webroot step. 12:13:59 INFO - Running pre-action listener: _resource_record_pre_action 12:13:59 INFO - Running main action method: populate_webroot 12:13:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:13:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:13:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:13:59 INFO - '--tppagecycles', 12:13:59 INFO - '7'], 12:13:59 INFO - 'tests': ['tsvgm']}}, 12:13:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:13:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:13:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:13:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:13:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['damp', 'tps']}, 12:13:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'tests': ['damp', 'tps']}, 12:13:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['dromaeo_dom']}, 12:13:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:13:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['basic_compositor_video']}, 12:13:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:13:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['a11yr', 12:13:59 INFO - 'ts_paint', 12:13:59 INFO - 'tpaint', 12:13:59 INFO - 'sessionrestore', 12:13:59 INFO - 'sessionrestore_no_auto_restore', 12:13:59 INFO - 'tabpaint']}, 12:13:59 INFO - 'other-e10s': {'tests': ['a11yr', 12:13:59 INFO - 'ts_paint', 12:13:59 INFO - 'tpaint', 12:13:59 INFO - 'sessionrestore', 12:13:59 INFO - 'sessionrestore_no_auto_restore', 12:13:59 INFO - 'tabpaint']}, 12:13:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:13:59 INFO - 'ts_paint', 12:13:59 INFO - 'tpaint', 12:13:59 INFO - 'sessionrestore', 12:13:59 INFO - 'sessionrestore_no_auto_restore', 12:13:59 INFO - 'tabpaint']}, 12:13:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:13:59 INFO - 'ts_paint', 12:13:59 INFO - 'tpaint', 12:13:59 INFO - 'sessionrestore', 12:13:59 INFO - 'sessionrestore_no_auto_restore', 12:13:59 INFO - 'tabpaint']}, 12:13:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['a11yr', 12:13:59 INFO - 'ts_paint', 12:13:59 INFO - 'tpaint', 12:13:59 INFO - 'sessionrestore', 12:13:59 INFO - 'sessionrestore_no_auto_restore', 12:13:59 INFO - 'tabpaint']}, 12:13:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['a11yr', 12:13:59 INFO - 'ts_paint', 12:13:59 INFO - 'tpaint', 12:13:59 INFO - 'sessionrestore', 12:13:59 INFO - 'sessionrestore_no_auto_restore', 12:13:59 INFO - 'tabpaint']}, 12:13:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['tsvgx', 12:13:59 INFO - 'tsvgr_opacity', 12:13:59 INFO - 'tart', 12:13:59 INFO - 'tscrollx', 12:13:59 INFO - 'cart']}, 12:13:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:13:59 INFO - 'tsvgr_opacity', 12:13:59 INFO - 'tart', 12:13:59 INFO - 'tscrollx', 12:13:59 INFO - 'cart']}, 12:13:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'talos_options': ['--disable-e10s'], 12:13:59 INFO - 'tests': ['tp5o']}, 12:13:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'tests': ['tp5o']}, 12:13:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'talos_options': ['--disable-e10s', 12:13:59 INFO - '--xperf_path', 12:13:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:59 INFO - 'tests': ['tp5n']}, 12:13:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:59 INFO - 'talos_options': ['--xperf_path', 12:13:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:59 INFO - 'tests': ['tp5n']}}, 12:13:59 INFO - 'talos.zip': {'path': '', 12:13:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:13:59 INFO - Running post-action listener: _resource_record_post_action 12:13:59 INFO - [mozharness: 2016-08-26 19:13:59.908898Z] Finished populate-webroot step (success) 12:13:59 INFO - [mozharness: 2016-08-26 19:13:59.908988Z] Running create-virtualenv step. 12:13:59 INFO - Running pre-action listener: _resource_record_pre_action 12:13:59 INFO - Running main action method: create_virtualenv 12:13:59 INFO - Creating virtualenv /builds/slave/test/build/venv 12:13:59 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 12:13:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:14:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:14:00 INFO - Using real prefix '/usr' 12:14:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:14:00 INFO - Installing distribute.............................................................................................................................................................................................done. 12:14:02 INFO - Installing pip.................done. 12:14:02 INFO - Return code: 0 12:14:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:14:02 INFO - Using env: {'DISPLAY': ':0', 12:14:02 INFO - 'HOME': '/home/cltbld', 12:14:02 INFO - 'LANG': 'en_US.UTF-8', 12:14:02 INFO - 'LANGUAGE': 'en_US:en', 12:14:02 INFO - 'LOGNAME': 'cltbld', 12:14:02 INFO - 'MAIL': '/var/mail/cltbld', 12:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:02 INFO - 'MOZ_NO_REMOTE': '1', 12:14:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:02 INFO - 'NO_EM_RESTART': '1', 12:14:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:02 INFO - 'PWD': '/builds/slave/test', 12:14:02 INFO - 'SHELL': '/bin/bash', 12:14:02 INFO - 'SHLVL': '1', 12:14:02 INFO - 'TERM': 'linux', 12:14:02 INFO - 'TMOUT': '86400', 12:14:02 INFO - 'USER': 'cltbld', 12:14:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:02 INFO - '_': '/tools/buildbot/bin/python'} 12:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:02 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 12:14:02 INFO - Cleaning up... 12:14:02 INFO - Return code: 0 12:14:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:14:02 INFO - Using env: {'DISPLAY': ':0', 12:14:02 INFO - 'HOME': '/home/cltbld', 12:14:02 INFO - 'LANG': 'en_US.UTF-8', 12:14:02 INFO - 'LANGUAGE': 'en_US:en', 12:14:02 INFO - 'LOGNAME': 'cltbld', 12:14:02 INFO - 'MAIL': '/var/mail/cltbld', 12:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:02 INFO - 'MOZ_NO_REMOTE': '1', 12:14:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:02 INFO - 'NO_EM_RESTART': '1', 12:14:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:02 INFO - 'PWD': '/builds/slave/test', 12:14:02 INFO - 'SHELL': '/bin/bash', 12:14:02 INFO - 'SHLVL': '1', 12:14:02 INFO - 'TERM': 'linux', 12:14:02 INFO - 'TMOUT': '86400', 12:14:02 INFO - 'USER': 'cltbld', 12:14:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:02 INFO - '_': '/tools/buildbot/bin/python'} 12:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:02 INFO - Downloading/unpacking psutil>=3.1.1 12:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:14:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:04 INFO - Installing collected packages: psutil 12:14:04 INFO - Running setup.py install for psutil 12:14:04 INFO - building 'psutil._psutil_linux' extension 12:14:04 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 12:14:05 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 12:14:05 INFO - building 'psutil._psutil_posix' extension 12:14:05 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 12:14:05 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 12:14:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:05 INFO - Successfully installed psutil 12:14:05 INFO - Cleaning up... 12:14:05 INFO - Return code: 0 12:14:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:14:05 INFO - Using env: {'DISPLAY': ':0', 12:14:05 INFO - 'HOME': '/home/cltbld', 12:14:05 INFO - 'LANG': 'en_US.UTF-8', 12:14:05 INFO - 'LANGUAGE': 'en_US:en', 12:14:05 INFO - 'LOGNAME': 'cltbld', 12:14:05 INFO - 'MAIL': '/var/mail/cltbld', 12:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:05 INFO - 'MOZ_NO_REMOTE': '1', 12:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:05 INFO - 'NO_EM_RESTART': '1', 12:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:05 INFO - 'PWD': '/builds/slave/test', 12:14:05 INFO - 'SHELL': '/bin/bash', 12:14:05 INFO - 'SHLVL': '1', 12:14:05 INFO - 'TERM': 'linux', 12:14:05 INFO - 'TMOUT': '86400', 12:14:05 INFO - 'USER': 'cltbld', 12:14:05 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:05 INFO - '_': '/tools/buildbot/bin/python'} 12:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:14:07 INFO - Installing collected packages: mozsystemmonitor 12:14:07 INFO - Running setup.py install for mozsystemmonitor 12:14:07 INFO - Successfully installed mozsystemmonitor 12:14:07 INFO - Cleaning up... 12:14:07 INFO - Return code: 0 12:14:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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', '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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:14: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 jsonschema==2.5.1 12:14:07 INFO - Using env: {'DISPLAY': ':0', 12:14:07 INFO - 'HOME': '/home/cltbld', 12:14:07 INFO - 'LANG': 'en_US.UTF-8', 12:14:07 INFO - 'LANGUAGE': 'en_US:en', 12:14:07 INFO - 'LOGNAME': 'cltbld', 12:14:07 INFO - 'MAIL': '/var/mail/cltbld', 12:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:07 INFO - 'MOZ_NO_REMOTE': '1', 12:14:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:07 INFO - 'NO_EM_RESTART': '1', 12:14:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:07 INFO - 'PWD': '/builds/slave/test', 12:14:07 INFO - 'SHELL': '/bin/bash', 12:14:07 INFO - 'SHLVL': '1', 12:14:07 INFO - 'TERM': 'linux', 12:14:07 INFO - 'TMOUT': '86400', 12:14:07 INFO - 'USER': 'cltbld', 12:14:07 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:07 INFO - '_': '/tools/buildbot/bin/python'} 12:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:07 INFO - Downloading/unpacking jsonschema==2.5.1 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:14:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:10 INFO - Downloading functools32-3.2.3-2.tar.gz 12:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:14:10 INFO - warning: no files found matching '*.txt' 12:14:10 INFO - no previously-included directories found matching 'build' 12:14:10 INFO - no previously-included directories found matching 'dist' 12:14:10 INFO - no previously-included directories found matching '.git*' 12:14:10 INFO - Installing collected packages: jsonschema, functools32 12:14:10 INFO - Running setup.py install for functools32 12:14:10 INFO - warning: no files found matching '*.txt' 12:14:10 INFO - no previously-included directories found matching 'build' 12:14:10 INFO - no previously-included directories found matching 'dist' 12:14:10 INFO - no previously-included directories found matching '.git*' 12:14:10 INFO - Successfully installed jsonschema functools32 12:14:10 INFO - Cleaning up... 12:14:10 INFO - Return code: 0 12:14:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:14:10 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')]} 12:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:10 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')]} 12:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:10 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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:10 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 12:14:10 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 12:14:10 INFO - Using env: {'DISPLAY': ':0', 12:14:10 INFO - 'HOME': '/home/cltbld', 12:14:10 INFO - 'LANG': 'en_US.UTF-8', 12:14:10 INFO - 'LANGUAGE': 'en_US:en', 12:14:10 INFO - 'LOGNAME': 'cltbld', 12:14:10 INFO - 'MAIL': '/var/mail/cltbld', 12:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:10 INFO - 'MOZ_NO_REMOTE': '1', 12:14:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:10 INFO - 'NO_EM_RESTART': '1', 12:14:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:10 INFO - 'PWD': '/builds/slave/test', 12:14:10 INFO - 'SHELL': '/bin/bash', 12:14:10 INFO - 'SHLVL': '1', 12:14:10 INFO - 'TERM': 'linux', 12:14:10 INFO - 'TMOUT': '86400', 12:14:10 INFO - 'USER': 'cltbld', 12:14:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:10 INFO - '_': '/tools/buildbot/bin/python'} 12:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:14:10 INFO - Cleaning up... 12:14:10 INFO - Return code: 0 12:14:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:14:10 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')]} 12:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:10 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')]} 12:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:10 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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:10 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 12:14:10 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 12:14:10 INFO - Using env: {'DISPLAY': ':0', 12:14:10 INFO - 'HOME': '/home/cltbld', 12:14:10 INFO - 'LANG': 'en_US.UTF-8', 12:14:10 INFO - 'LANGUAGE': 'en_US:en', 12:14:10 INFO - 'LOGNAME': 'cltbld', 12:14:10 INFO - 'MAIL': '/var/mail/cltbld', 12:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:10 INFO - 'MOZ_NO_REMOTE': '1', 12:14:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:10 INFO - 'NO_EM_RESTART': '1', 12:14:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:10 INFO - 'PWD': '/builds/slave/test', 12:14:10 INFO - 'SHELL': '/bin/bash', 12:14:10 INFO - 'SHLVL': '1', 12:14:10 INFO - 'TERM': 'linux', 12:14:10 INFO - 'TMOUT': '86400', 12:14:10 INFO - 'USER': 'cltbld', 12:14:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:10 INFO - '_': '/tools/buildbot/bin/python'} 12:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:12 INFO - Downloading blobuploader-1.2.4.tar.gz 12:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:14:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:14:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:13 INFO - Downloading docopt-0.6.1.tar.gz 12:14:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:14:13 INFO - Installing collected packages: blobuploader, requests, docopt 12:14:13 INFO - Running setup.py install for blobuploader 12:14:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:14:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:14:13 INFO - Running setup.py install for requests 12:14:13 INFO - Running setup.py install for docopt 12:14:13 INFO - Successfully installed blobuploader requests docopt 12:14:13 INFO - Cleaning up... 12:14:14 INFO - Return code: 0 12:14:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:14 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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:14 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 12:14:14 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 12:14:14 INFO - Using env: {'DISPLAY': ':0', 12:14:14 INFO - 'HOME': '/home/cltbld', 12:14:14 INFO - 'LANG': 'en_US.UTF-8', 12:14:14 INFO - 'LANGUAGE': 'en_US:en', 12:14:14 INFO - 'LOGNAME': 'cltbld', 12:14:14 INFO - 'MAIL': '/var/mail/cltbld', 12:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:14 INFO - 'MOZ_NO_REMOTE': '1', 12:14:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:14 INFO - 'NO_EM_RESTART': '1', 12:14:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:14 INFO - 'PWD': '/builds/slave/test', 12:14:14 INFO - 'SHELL': '/bin/bash', 12:14:14 INFO - 'SHLVL': '1', 12:14:14 INFO - 'TERM': 'linux', 12:14:14 INFO - 'TMOUT': '86400', 12:14:14 INFO - 'USER': 'cltbld', 12:14:14 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:14 INFO - '_': '/tools/buildbot/bin/python'} 12:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:14 INFO - Running setup.py (path:/tmp/pip-cGhHMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:14 INFO - Running setup.py (path:/tmp/pip-ZmvPP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:14 INFO - Running setup.py (path:/tmp/pip-EQoYD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:14 INFO - Running setup.py (path:/tmp/pip-shqW1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:14 INFO - Running setup.py (path:/tmp/pip-Ad_pD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:14 INFO - Running setup.py (path:/tmp/pip-KLEYWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:14 INFO - Running setup.py (path:/tmp/pip-Z0Wcfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:14 INFO - Running setup.py (path:/tmp/pip-6IpnJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:14 INFO - Running setup.py (path:/tmp/pip-QJT8Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:14 INFO - Running setup.py (path:/tmp/pip-iOOgiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:14 INFO - Running setup.py (path:/tmp/pip-SI3_ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:15 INFO - Running setup.py (path:/tmp/pip-ZHlcHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:15 INFO - Running setup.py (path:/tmp/pip-P_z90S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:15 INFO - Running setup.py (path:/tmp/pip-8fXJ35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:15 INFO - Running setup.py (path:/tmp/pip-DiIBgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:15 INFO - Running setup.py (path:/tmp/pip-VsZFjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:15 INFO - Running setup.py (path:/tmp/pip-PSctP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:14:15 INFO - Running setup.py install for manifestparser 12:14:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for mozcrash 12:14:15 INFO - Running setup.py install for mozdebug 12:14:15 INFO - Running setup.py install for mozdevice 12:14:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for mozfile 12:14:15 INFO - Running setup.py install for mozhttpd 12:14:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozinfo 12:14:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozInstall 12:14:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozleak 12:14:16 INFO - Running setup.py install for mozlog 12:14:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for moznetwork 12:14:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozprocess 12:14:16 INFO - Running setup.py install for mozprofile 12:14:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozrunner 12:14:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozscreenshot 12:14:16 INFO - Running setup.py install for moztest 12:14:17 INFO - Running setup.py install for mozversion 12:14:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:14:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:14:17 INFO - Cleaning up... 12:14:17 INFO - Return code: 0 12:14:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:17 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')]} 12:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:17 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')]} 12:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:17 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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:17 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 12:14:17 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 12:14:17 INFO - Using env: {'DISPLAY': ':0', 12:14:17 INFO - 'HOME': '/home/cltbld', 12:14:17 INFO - 'LANG': 'en_US.UTF-8', 12:14:17 INFO - 'LANGUAGE': 'en_US:en', 12:14:17 INFO - 'LOGNAME': 'cltbld', 12:14:17 INFO - 'MAIL': '/var/mail/cltbld', 12:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:17 INFO - 'MOZ_NO_REMOTE': '1', 12:14:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:17 INFO - 'NO_EM_RESTART': '1', 12:14:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:17 INFO - 'PWD': '/builds/slave/test', 12:14:17 INFO - 'SHELL': '/bin/bash', 12:14:17 INFO - 'SHLVL': '1', 12:14:17 INFO - 'TERM': 'linux', 12:14:17 INFO - 'TMOUT': '86400', 12:14:17 INFO - 'USER': 'cltbld', 12:14:17 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:17 INFO - '_': '/tools/buildbot/bin/python'} 12:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:17 INFO - Running setup.py (path:/tmp/pip-KAlVNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:17 INFO - Running setup.py (path:/tmp/pip-vKU237-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:17 INFO - Running setup.py (path:/tmp/pip-ABRRRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:17 INFO - Running setup.py (path:/tmp/pip-ymg7BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:17 INFO - Running setup.py (path:/tmp/pip-Hikani-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:17 INFO - Running setup.py (path:/tmp/pip-YIM_lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:17 INFO - Running setup.py (path:/tmp/pip-v2WIVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:17 INFO - Running setup.py (path:/tmp/pip-bfNMCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:18 INFO - Running setup.py (path:/tmp/pip-TJIlbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:18 INFO - Running setup.py (path:/tmp/pip-EiNk2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:18 INFO - Running setup.py (path:/tmp/pip-Dnd044-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:18 INFO - Running setup.py (path:/tmp/pip-bZIctC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:18 INFO - Running setup.py (path:/tmp/pip-EG41qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:18 INFO - Running setup.py (path:/tmp/pip-i9aVyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:18 INFO - Running setup.py (path:/tmp/pip-QXGjnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:18 INFO - Running setup.py (path:/tmp/pip-s4zyuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:18 INFO - Running setup.py (path:/tmp/pip-d0XbVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:18 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)) 12:14:18 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)) 12:14:18 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)) 12:14:18 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)) 12:14:18 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)) 12:14:18 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)) 12:14:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14:18 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 12:14:18 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 12:14:18 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 12:14:18 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 12:14:18 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 12:14:18 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 12:14:20 INFO - Downloading blessings-1.6.tar.gz 12:14:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:14:20 INFO - Installing collected packages: blessings 12:14:20 INFO - Running setup.py install for blessings 12:14:20 INFO - Successfully installed blessings 12:14:20 INFO - Cleaning up... 12:14:20 INFO - Return code: 0 12:14:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:14:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:21 INFO - Reading from file tmpfile_stdout 12:14:21 INFO - Current package versions: 12:14:21 INFO - argparse == 1.2.1 12:14:21 INFO - blessings == 1.6 12:14:21 INFO - blobuploader == 1.2.4 12:14:21 INFO - docopt == 0.6.1 12:14:21 INFO - functools32 == 3.2.3-2 12:14:21 INFO - jsonschema == 2.5.1 12:14:21 INFO - manifestparser == 1.1 12:14:21 INFO - mozInstall == 1.12 12:14:21 INFO - mozcrash == 0.17 12:14:21 INFO - mozdebug == 0.1 12:14:21 INFO - mozdevice == 0.48 12:14:21 INFO - mozfile == 1.2 12:14:21 INFO - mozhttpd == 0.7 12:14:21 INFO - mozinfo == 0.9 12:14:21 INFO - mozleak == 0.1 12:14:21 INFO - mozlog == 3.3 12:14:21 INFO - moznetwork == 0.27 12:14:21 INFO - mozprocess == 0.23 12:14:21 INFO - mozprofile == 0.28 12:14:21 INFO - mozrunner == 6.12 12:14:21 INFO - mozscreenshot == 0.1 12:14:21 INFO - mozsystemmonitor == 0.3 12:14:21 INFO - moztest == 0.7 12:14:21 INFO - mozversion == 1.4 12:14:21 INFO - psutil == 3.1.1 12:14:21 INFO - requests == 1.2.3 12:14:21 INFO - wsgiref == 0.1.2 12:14:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:21 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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:21 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 12:14:21 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 12:14:21 INFO - Using env: {'DISPLAY': ':0', 12:14:21 INFO - 'HOME': '/home/cltbld', 12:14:21 INFO - 'LANG': 'en_US.UTF-8', 12:14:21 INFO - 'LANGUAGE': 'en_US:en', 12:14:21 INFO - 'LOGNAME': 'cltbld', 12:14:21 INFO - 'MAIL': '/var/mail/cltbld', 12:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:21 INFO - 'MOZ_NO_REMOTE': '1', 12:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:21 INFO - 'NO_EM_RESTART': '1', 12:14:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:21 INFO - 'PWD': '/builds/slave/test', 12:14:21 INFO - 'SHELL': '/bin/bash', 12:14:21 INFO - 'SHLVL': '1', 12:14:21 INFO - 'TERM': 'linux', 12:14:21 INFO - 'TMOUT': '86400', 12:14:21 INFO - 'USER': 'cltbld', 12:14:21 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:21 INFO - '_': '/tools/buildbot/bin/python'} 12:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 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)) 12:14:21 INFO - Cleaning up... 12:14:21 INFO - Return code: 0 12:14:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:21 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 0x7fe7f0b44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2431230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', '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 12:14:21 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 12:14:21 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 12:14:21 INFO - Using env: {'DISPLAY': ':0', 12:14:21 INFO - 'HOME': '/home/cltbld', 12:14:21 INFO - 'LANG': 'en_US.UTF-8', 12:14:21 INFO - 'LANGUAGE': 'en_US:en', 12:14:21 INFO - 'LOGNAME': 'cltbld', 12:14:21 INFO - 'MAIL': '/var/mail/cltbld', 12:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:21 INFO - 'MOZ_NO_REMOTE': '1', 12:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:21 INFO - 'NO_EM_RESTART': '1', 12:14:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:21 INFO - 'PWD': '/builds/slave/test', 12:14:21 INFO - 'SHELL': '/bin/bash', 12:14:21 INFO - 'SHLVL': '1', 12:14:21 INFO - 'TERM': 'linux', 12:14:21 INFO - 'TMOUT': '86400', 12:14:21 INFO - 'USER': 'cltbld', 12:14:21 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:21 INFO - '_': '/tools/buildbot/bin/python'} 12:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:21 INFO - Cleaning up... 12:14:21 INFO - Return code: 0 12:14:21 INFO - Running post-action listener: _resource_record_post_action 12:14:21 INFO - Running post-action listener: _start_resource_monitoring 12:14:21 INFO - Starting resource monitoring. 12:14:21 INFO - [mozharness: 2016-08-26 19:14:21.488784Z] Finished create-virtualenv step (success) 12:14:21 INFO - [mozharness: 2016-08-26 19:14:21.489297Z] Running install step. 12:14:21 INFO - Running pre-action listener: _resource_record_pre_action 12:14:21 INFO - Running main action method: install 12:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:21 INFO - Reading from file tmpfile_stdout 12:14:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:21 INFO - Reading from file tmpfile_stdout 12:14:21 INFO - Output received: 12:14:21 INFO - Usage: mozinstall [options] installer 12:14:21 INFO - Options: 12:14:21 INFO - -h, --help show this help message and exit 12:14:21 INFO - -d DEST, --destination=DEST 12:14:21 INFO - Directory to install application into. [default: 12:14:21 INFO - "/builds/slave/test"] 12:14:21 INFO - --app=APP Application being installed. [default: firefox] 12:14:21 INFO - mkdir: /builds/slave/test/build/application 12:14:21 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'] 12:14:21 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 12:14:37 INFO - Reading from file tmpfile_stdout 12:14:37 INFO - Output received: 12:14:37 INFO - /builds/slave/test/build/application/firefox/firefox 12:14:37 INFO - Running post-action listener: _resource_record_post_action 12:14:37 INFO - [mozharness: 2016-08-26 19:14:37.911669Z] Finished install step (success) 12:14:37 INFO - [mozharness: 2016-08-26 19:14:37.911951Z] Running run-tests step. 12:14:37 INFO - Running pre-action listener: _resource_record_pre_action 12:14:37 INFO - Running main action method: run_tests 12:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:37 INFO - Python 2.7.3 12:14:37 INFO - Return code: 0 12:14:37 INFO - grabbing minidump binary from tooltool 12:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242b090>, '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 0x242e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242ee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:14:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:14:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:14:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:37 INFO - Return code: 0 12:14:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:14:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:14:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:37 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/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 12:14:37 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/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 12:14:37 INFO - Using env: {'DISPLAY': ':0', 12:14:37 INFO - 'HOME': '/home/cltbld', 12:14:37 INFO - 'LANG': 'en_US.UTF-8', 12:14:37 INFO - 'LANGUAGE': 'en_US:en', 12:14:37 INFO - 'LOGNAME': 'cltbld', 12:14:37 INFO - 'MAIL': '/var/mail/cltbld', 12:14:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:37 INFO - 'MOZ_NO_REMOTE': '1', 12:14:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:37 INFO - 'NO_EM_RESTART': '1', 12:14:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:37 INFO - 'PWD': '/builds/slave/test', 12:14:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:37 INFO - 'SHELL': '/bin/bash', 12:14:37 INFO - 'SHLVL': '1', 12:14:37 INFO - 'TERM': 'linux', 12:14:37 INFO - 'TMOUT': '86400', 12:14:37 INFO - 'USER': 'cltbld', 12:14:37 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872', 12:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:37 INFO - '_': '/tools/buildbot/bin/python'} 12:14:37 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/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 12:14:38 INFO - mozversion application_buildid: 20160826102618 12:14:38 INFO - mozversion application_changeset: 9797d309cc749cacf1f8d092ca5186b0f0e2bdaf 12:14:38 INFO - mozversion application_display_name: Nightly 12:14:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:38 INFO - mozversion application_name: Firefox 12:14:38 INFO - mozversion application_remotingname: firefox 12:14:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:14:38 INFO - mozversion application_vendor: Mozilla 12:14:38 INFO - mozversion application_version: 51.0a1 12:14:38 INFO - mozversion platform_buildid: 20160826102618 12:14:38 INFO - mozversion platform_changeset: 9797d309cc749cacf1f8d092ca5186b0f0e2bdaf 12:14:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:14:38 INFO - mozversion platform_version: 51.0a1 12:14:38 INFO - using testdate: 1472238878 12:14:38 INFO - actual date: 1472238878 12:14:38 INFO - starting webserver on 'localhost:50048' 12:14:38 INFO - SUITE-START | Running 2 tests 12:14:38 INFO - TEST-START | dromaeo_css 12:14:38 INFO - operating with platform_type : linux_ 12:14:38 INFO - Initialising browser for dromaeo_css test... 12:14:38 INFO - TEST-INFO | started process 20348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbfngef/profile http://localhost:50048/getInfo.html) 12:14:42 INFO - PROCESS | 20348 | __metrics Screen width/height:1600/1200 12:14:42 INFO - PROCESS | 20348 | colorDepth:24 12:14:42 INFO - PROCESS | 20348 | Browser inner width/height: 1024/697 12:14:42 INFO - PROCESS | 20348 | __metrics 12:14:42 INFO - PROCESS | 20348 | 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" 12:14:42 INFO - PROCESS | 20348 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:43 INFO - PROCESS | 20348 | console.error: PushService: 12:14:43 INFO - PROCESS | 20348 | stateChangeProcessEnqueue: Error transitioning state 12:14:43 INFO - PROCESS | 20348 | UnknownError 12:14:43 INFO - TEST-INFO | 20348: exit 0 12:14:43 INFO - Browser initialized. 12:14:43 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:43 INFO - TEST-INFO | started process 20428 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbfngef/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:56 INFO - PROCESS | 20428 | RSS: Main: 180948992 12:14:56 INFO - PROCESS | 20428 | 12:15:47 INFO - PROCESS | 20428 | 1472238947263 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] 12:18:00 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:50048/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50048/tests/dromaeo/cssquery-ext.html) 12:18:01 INFO - PROCESS | 20428 | RSS: Main: 297164800 12:18:01 INFO - PROCESS | 20428 | 12:21:04 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:50048/tests/dromaeo/cssquery-ext.html (next: http://localhost:50048/tests/dromaeo/cssquery-jquery.html) 12:21:04 INFO - PROCESS | 20428 | RSS: Main: 274124800 12:21:04 INFO - PROCESS | 20428 | 12:24:06 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:50048/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50048/tests/dromaeo/cssquery-mootools.html) 12:24:07 INFO - PROCESS | 20428 | RSS: Main: 218767360 12:24:07 INFO - PROCESS | 20428 | 12:27:09 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:50048/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50048/tests/dromaeo/cssquery-prototype.html) 12:27:10 INFO - PROCESS | 20428 | RSS: Main: 217079808 12:27:10 INFO - PROCESS | 20428 | 12:30:13 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:50048/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50048/tests/dromaeo/cssquery-yui.html) 12:30:13 INFO - PROCESS | 20428 | RSS: Main: 218759168 12:30:13 INFO - PROCESS | 20428 | 12:33:16 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:50048/tests/dromaeo/cssquery-yui.html (next: http://localhost:50048/tests/dromaeo/cssquery-dojo.html) 12:33:17 INFO - PROCESS | 20428 | RSS: Main: 306368512 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | __start_tp_report 12:33:17 INFO - PROCESS | 20428 | _x_x_mozilla_page_load 12:33:17 INFO - PROCESS | 20428 | _x_x_mozilla_page_load_details 12:33:17 INFO - PROCESS | 20428 | |i|pagename|runs| 12:33:17 INFO - PROCESS | 20428 | |0;dojo.html;2585.806706504025;2591.3133019749766;2596.584546472565;2634.7233540478237;2649.8145129840905;4541.818327266906;4606.078784243148;4649.30260460931;4781.18719817631;4845.030993801241;4610.9538904610945;4651.093036857806;4658.743769092698;4666.253399456088;4671.20589499785;4258.97870510647;4288.228118938606;4297.312430011199;4413.44831896013;4439.001224724428;4489.730616163024;4500.572445617661;4518.209313370164;4520.98277887476;4521.728696278217;4516.932246016312;4542.500324964251;4545.795439205243;4550.4994450610575;4602.21762300408;4369.563043695637;4561.817527298904;4567.0409214065085;4569.862904112882;4570.405847239871;4265.168292183025;4311.116221174683;4346.456692913386;4358.4987726411555;4371.060222052274;4688.835890743808;4757.738324392157;4765.2375619900995;4793.544613261734;4808.2787581863;5076.111680455942;5084.923726144111;5085.898282034339;5161.869158878505;5162.664426812245;5150.484951504857;5208.401033881114;5208.8958220835375;5213.061648903196;5258.92111618326;4608.746518941491;4714.245720684674;4775.761211939389;4779.02030083832;4849.733264670477;5215.504527069922;5247.553957913544;5249.238860365225;5252.448492908266;5262.394824595221;5032.270320803463;5034.395872495324;5041.395032596112;5042;5101.2603172539775;4904.803807847647;5123.513266239701;5180.4560521145495;5184.351956005499;5254.474552544715;5158.690478571222;5160.174372100521;5168.01807656544;5168.2764412982215;5172.896542069213;5112.948870511247;5136.640435169502;5141.665791723526;5142.537171654569;5188.351456067991;4214.220369231702;4254.851080212177;4279.850205242801;4309.59058889405;4312.978435107804;3790.8104594770152;3791.8483260669263;3797.0697179191275;3834.578196398394;3847.615238476186;4361.6074553290355;4365.497967733688;4365.847195348148;4413.7351758894565;4414.27164517851;4921.803127874917;5060.342155519833;5086.694798312114;5103.464136265713;5113;4338.544452832407;4360.760158191267;4372.934405983913;4412.735235885857;4413.337999300131;4580.564846339593;4596.126735920231;4598.931016034762;4600.332951721982;4638.443386793539;4697.718136911796;4832.37179166699;4839.637027222902;4848.0546293472435;4882.755862206946;5145.459726728789;5168.508991645787;5170.457102004236;5185.455527169593;5233.581313494988;4879.39007624047;4882.7314497701545;4883.584895283941;4886.071646387175;4944.703317800943;4899.971006088619;5044.8234311798915;5064.5948324134715;5068.037072956274;5098.337216161875;5146.459621739664;5176.7152806596;5177.559907407913;5177.792888284426;5198.272241886215;4985.052839960396;5006.7246301452315;5009;5010.69935803853;5065.39215294167;4825.662203645711;4972.602191824573;5003.599712023102;5021.548060674557;5042.39491261051;4923.08922849274;4937.086638971802;4941.75291235444;4948.084604348207;4995.8001679932395;5052.040112378636;5059.241113832954;5059.316992206005;5060.038592667382;5105.1576489880235;4712.104700106968;4760.309755085563;4763;4916.778744956557;4918.770307423144 12:33:17 INFO - PROCESS | 20428 | |1;ext.html;557.5873853348522;558.6592178770951;567.2285691459617;569.6468189722373;572.9686564184468;27456.725432745665;37630.81184594076;39037.21925561487;39071;43783.78108109455;39476.407853882214;39967;40059;40081.19837603246;40081.19837603254;7648.694052237917;7780.6109694515335;7795.688172473095;7796.220377962187;7796.76609701707;7502.624868756568;7593;7594.810129746746;7599.620018999056;7600;7614.695412183506;7642.770716878503;7658.349040331551;7658.846823063535;7666.540007599534;7566.735164269252;7567.243275672444;7602.809929751773;7604.0875094988405;7606.619669016527;7609.239076092402;7658.578778167199;7658.578778167199;7663.118741344761;7663.7317693881005;36378;36602.63397366046;36697.63302366996;36705;36723.81638091819;3097.225693576606;3322.5182348559565;3361.7310615150727;3487.2676737885076;3677.2277821657485;28004.29989250285;28120.437591248265;28701.42597148066;30303.393932121453;31756.682433175603;3311.106001379638;3327.1183136468853;3327.3012667339885;3348.430766769655;3348.782329148622;253485;257541;258247;258272;258362;30881.536776947913;52988.205176922376;53512.46487535153;53550.46449535533;53573;31213.687863121533;33275.500867486524;33497.497537536954;33528.1617959553;33541.49687754686;13171.209727416386;13208.405621747044;13266.27035513056;13277.136986095873;13297.202167869958;10744.409057501756;10786.838197427045;10835.891641083646;10868.239223254297;10886.67339979802;7653.540787552764;7737.806554836062;7798.5710785906185;7799.220077992155;7813.804654883674;2672.6124711916664;2756.9586456203174;2789.121426750567;2799.7060308667706;2824.110405222348;828.9668413263522;887.4187407248263;903.1284810158229;935.5088578496344;951.3150531617224;26447;26872.193834185007;27119.322016949733;27285.317867053483;27764.30589235285;924.4638109896244;935.102301790289;938.8075444533854;961.6201600367815;992.4045572656348;13768.586942391745;14454.783178252335;14493.724351316387;14520;14756.262186890612;393093;396131;396877;396959;397250;47039;47147.29279060817;47309.5269047312;47367;47397;1045.9790804184026;1083.312096818526;1094.8412480190486;1126.0934947367448;1159.8782127876452;25390.365240869127;28308.35011310762;28656.42687146287;28753.568696469152;29061.85469072641;40595.18809623732;40973.38539921963;41075;41093.38359924544;41105;292952;295823;296204;296347;297058;22182;24026.279211623678;24215.152469663477;24924.37689057788;25144.74855251424;40441.39337909875;40775.38836917508;40950.79524602477;41004.1799164021;41272.38091428691;34536;34766.47850282197;34869.825650871586;35016.299674005866;35059.298814024085;33959;33991.66008339885;34234.65765342315;34311.31377372389;34529.654703452645;33964.83017584896;34765.65234347725;34970;35134.82432587821;35163.64836351604;29167.85416072906;30865;31866;31883.36233275368;32074.198145045626;24102.397440063436;24157.396065098514;24567.38581535476;24650.63024054605;25039.624405634295 12:33:17 INFO - PROCESS | 20428 | |2;jquery.html;320.93420848726015;354.2366200837195;354.5638864197036;356.1914454188991;375.81397208381856;12313.322767247797;15056.849431505681;16338.26477808497;16342.428015019446;16723.83276167238;13356.398962046707;13472.865271347284;13903.930480347584;14074.366653500592;14261.928690356559;4605.953940460586;4751.738654374008;4758.976205118969;4759.262314341283;4760.09558183944;4209.200251952135;4634.88762696952;4712.458067322267;4748.5251474852585;4753.762311884409;4663.673542852001;4709.670323077386;4712.175369310374;4713.976430117862;4714.387129673138;4518.3082288491005;4654.162250794856;4657.3246879202325;4706.5293470653005;4708.081924024782;4595.333676616905;4596.241620132674;4605.332226827091;4607.746573938432;4608.838690645811;12475.563355282522;12656.620301390973;12676.68308292291;12681.619551413472;12802.743945121045;1768.45177994822;1877.8873267603851;2104.0005997151384;2106.410205142547;2362.2795047510504;8881.733547993506;9113.179813816789;9261.583228754722;9410.811783764355;9581.281403894665;1704.9318027278894;1750.3523696232476;1788.7585176001203;1793.9820601793945;1794.102948525737;67017.32982670159;69289.65355173242;69782;71249.64375178143;71600.28399715989;9667.274954378308;9874.901250987397;9903.25725570585;10083.848742268872;10221.488925553842;13642.454301827816;13755;13794.241316727675;13798.793018104736;13809;6375.139356186855;6566.179227596551;6769.120014398195;6770.966145169341;7128.536645118051;6156.830202261556;6181.289151747516;6302.432781049728;6515.967420162963;6716.764913228014;4214.494260688737;4292.763897985641;4323.524412314643;4339.175556644227;4346.956530434719;1750.054970316015;1808.8191180881806;1849.5098798818324;1895.8672892897364;1896.4500294914596;675.166169780321;798.9241022103007;806.1736719862188;808.7978005498626;910.2763303173992;16324.75512867308;16552.668946621;17170.055646939538;17260.223289951984;17455.912720436318;792.5522080024767;819.4223072733708;827.6234313387515;851.9957400212959;860.7159637319669;9254.722358329127;9324.11420915012;9526.380785248937;9597.472139032281;9837.769759003553;271738;273965;274821;365497;367116;48507.75746121317;49388.75305623449;52140.21789673158;57491.42508574944;82804.58597706973;900.243795212025;905.6739573753443;930.2139691960283;935.5696379665294;938;15662.530124096294;15753.133577652881;16343.427980020642;16390.590235244214;16709.832901671314;27470.862645686644;31570;36322.63677363192;39772.005699857735;39970;49015.26477102775;51014.97970040504;54483.182752257955;54683.72658136684;54818.725906370215;7655.5023923447025;7694.499857509244;7782.455228133977;7803.985481887318;7982.960085199769;9877.703668889944;11712.648620541397;12097.57658481949;12703.36483175819;12729.872701272869;10205.183585313305;10340.741481462723;10373;10476.319039262424;11042.613508527164;7563.432742544222;8112.310453611509;10096.69709908704;10113.262986529699;10506.264561480622;7692.538447693157;7744.225577442211;7956.363490920597;8024.2376974187355;8110;11432.771344572895;11491.367974761133;11503.367314797431;11932.463039163085;11940.70148246266;10632.84050739224;10684.732881678021;10697.51861166265;10713.089387401846;10935.671929842118 12:33:17 INFO - PROCESS | 20428 | |3;mootools.html;1301.4273719563391;1338.3642769684402;1395.6510872281929;1608.3164655021612;1644.4080131152775;6567.802965911018;6832.658367081651;7261.164966028909;7851.685932562697;7852.725154619582;7313.378362839165;7624.313811756941;7649;7847.411444141684;7859.29266366027;2196.9780302196973;2221.511267521151;2225.6772767948637;2233.698450709158;2235;1849.4359220437761;2074.7510298764096;2141.4004078857943;2145.7961493658154;2177.205320058183;2160.8811515366647;2185.1368709359795;2188.277868303464;2188.8686678799245;2198.912043518257;2141.7322834645747;2158.6546152615583;2172.576347612216;2174.9021294041804;2177.771334009922;2140.2937030779804;2141.764405915348;2142.2073832681854;2142.775008624103;2181.029441898356;7690.769276921701;7866.921330786676;7867.055953285585;7914.920850791476;7924.722634707758;4475.351074094271;4610.469795973472;4612.792424340879;4616.653750968657;4623.42207224097;2783.860806959644;2927.0487091695286;2965.5996440480476;2978.4043191361598;2986.4027194561177;881.4711173296035;912.6988093928987;928.1600151862575;930.0234753508789;932.421898422982;10587.205959552988;10594.682159535148;10599.417032063233;10609.045185933302;10639.734006649818;3798.810059497014;3905.8047097645003;4143.91712165755;4160.521540022875;4186.602272784081;9129.406588571721;9139.680111196076;9148.496832674267;9153.038930912291;9156.679516217033;4588.770561471913;4590.311453282035;4594.241950078266;4596.287575425815;4599.05719327537;4147.357159640261;4193.643540299109;4204.3903633972905;4204.684648651363;4206.831726730897;3530.3645343838357;3599.8200089995394;3600.6399360063783;3623.528941237675;3631.055925459347;1024.7489365105596;1080.702806728156;1116.7040734205443;1128.9379084150287;1143.519721716881;777.9961100194463;791.5765065689828;791.8693415586479;804.5615139748851;808.599743127144;4908.2637604358915;4929.433115191727;5057.241413787961;5219.164933610604;5365.436629153961;765.7740966414921;779.6647441600053;792.4136139256908;801.3989507869098;844.7170197983621;3354.5639066921144;3466.5666791651042;3486.8430920608635;3492.5983511895947;3539.610642829287;9334.579943902541;9668.274879384075;9741.220702343937;9744.46405447693;9752.024797520333;9662.227021838236;9668.516574171263;9672.661456848977;9679.70960871175;9688.176504997155;6993.615351155735;7013.298670133048;7152.642367881585;7259.12890453149;7268.527545709512;4945.18404463274;4992.375953005874;5030.0945829749535;5061.190209566452;5062.493750624908;8601;8606.870896936425;8699.086595907338;8721.215090641872;8793.780155496164;8717.651293948426;8724.214820666171;8799.560021998746;8803.955980220058;8888.511131887808;6081.209442772411;6169.691515424301;6222.502199823913;6230.003199488241;6235.75056997715;7211.67547460376;7239.674214660458;7254.492185547172;7258.056452661121;7409.962605235379;5528.336599608073;6097.969510152599;6681.799546013627;6702.966485167543;6722;6631.436327911989;6643.435307998874;6676.866462670817;6678.131842860397;6702.128723266139;6125.418085281891;6228.532860035425;6232.781852635136;6233.127362169209;6241.875162496815;4363.432753741993;4414.668899832461;4437.400950871591;4450.621697155778;4482.036362182146;4464.754438505914;4466.5086840446875;4550.522195169459;4573;4609.87945545053 12:33:17 INFO - PROCESS | 20428 | |4;prototype.html;290.82259821508876;294.7538805097743;297.4081577660456;300.19098529463076;301.9199912023313;4908.435529914056;4934.654574179809;4956.925646115302;4965.925511117336;4971.701697898124;4876.7808047988;4911.680740751857;4916.37759539352;4925.852224433273;4934.679245849017;3612.5303710517596;3731.8693845715406;3748.1941382602736;3770.6417890300377;3796.373598356268;3718.869839555616;3828.559715632696;3878.1080351519167;3880.0881792778673;3883.359245724452;3796.545143919034;3807.9619203807883;3821.331267028273;3824.9426258606145;3830.8467661293516;3569.1077230692326;3764.8682296119505;3778.6599206071587;3787.829547670348;3839.8656047038357;3296.917577060569;3301.8018918864705;3306.8015919044933;3308.983455082733;3325.3515564464938;4782.904341913177;4794.35276237707;4795.376601041842;4808.326834243208;4812.7352995585225;4324.091940692459;4333.696641235115;4340.262155433584;4346.9347959780625;4350.8042138103865;5000.699958002531;5112.795488180468;5149.201873709581;5156.355455568054;5180.948190518122;3575.964240357589;3577.588577313616;3581.820908954568;3586.4082426399614;3598.208394153282;5383.703896285702;5416.377116631598;5424.864378390572;5433.1035379162395;5435.211894275308;3609.1879327151705;3688.3176612326806;3711.2391959648207;3711.9443208351895;3715.702743780491;3746.4942232798767;3758.473813666089;3759.3609086455367;3759.774413535197;3768.660820526111;4313.986213239886;4323.351497275434;4327.6105150536605;4331.682549835823;4335.588119128678;5002.874928126753;5148.897022059537;5160.451864440653;5166.147585648326;5174.741262936838;1268.8160216768517;1289.548657969703;1289.5615490733196;1290.8773666501668;1293.424426130363;779.6296759039411;802.2619190344897;802.6829402386024;814.4909431605247;823.3495538524618;4339.674524410681;4401.405810215644;4507.774611269423;4523.140603285366;4534;5277.577793776489;5278.97360513195;5286.312779338738;5287.947120528823;5297.788088476418;4518.401918896582;4530.524294948983;4542.318652202196;4543.727376357429;4556.3621093047;2639.274199595127;2758.806883518175;2789.4421115776927;2793.7904657150793;2804.733550312717;4929.112759703216;4951.4897956123;4980.427250866197;4987.825426110141;4997.225430058346;5104.872378190575;5376.1935709643085;5380.838574842761;5393.325834270716;5405.729713514309;4984.800607975713;5003.949960500421;5011.649184557046;5012.04771093491;5012.899742005213;5275.736213189249;5282.234076059027;5283.577313814809;5299.311089558333;5306.646805064838;4935.876603084952;5176.534111929945;5183.81856635016;5192.9740351299515;5198.6880787152895;5033.295338652665;5034.697918124924;5037.319961805109;5040.319556859777;5047.520485553867;3672.2288319453214;3689.815509224474;3706.1475860551686;3709.7217708672497;3716.5168528091176;5073.568746656428;5287.233351164136;5294.12646913255;5297.205419187153;5307.752678120716;5100.923486147636;5104.744762761921;5127.846164615067;5138.280640710229;5145.765016395963;5093.4651861554;5101.617378696538;5141.480674469558;5152.819651312186;5154.201098829687;4897.240927656219;5006.4743201963265;5009.0482808266315;5055.014272216882;5089.160288552347;4926.827561035347;5006.549410553068;5114.33513643224;5160.251763494197;5206.167013277857;4944.332515110414;5018.899622007466;5053.519915608012;5062.392512898476;5074.16276314404 12:33:17 INFO - PROCESS | 20428 | |5;yui.html;606.1907353682833;613.8311964209842;650.3431534150507;681.3152781454637;693.2097408953792;12015;12626.621201363974;12975.67560810979;12976.54582089627;13015.869841301585;12215.38923053846;12397.07021973351;12401;12403.317817520032;12409.131360804748;593.2969431223992;714.2429025233243;732.8424388756416;737.5685224143856;738.1658725640019;603.6921170203184;612.4487960835239;612.6783438694677;708.19973430024;736.2784471218209;761.3566536276837;765.3838659878795;765.6975494679598;766.1074847802304;766.2452484302958;761.2805898425989;761.9276168763959;762.7139822566537;763.427429427929;763.4617594595796;456.84467281124245;465.5739997901916;468.40746455733523;561.390890883392;561.4666067236101;10005.799884002352;11791.587294444738;12336.5065397384;12348.135630505869;12883.098183127186;12467.93766031173;13123;13150.934245328808;13425.798613020812;13629.795553066711;10284.897151028468;10342.172626189962;10347.94826025865;10401.583936642524;10702;3270.16610764256;3340.265141668829;3365.5961284645755;3365.9663403366144;3439.5356626855305;64216.67891660558;64317.35682643207;65085.3491465084;65838.3416165837;67683.3231667682;9250.95374523123;9263.814723705487;9306.813863722824;9418.576164072632;9466.479343636167;18233.27066917335;18418.894866307797;18424.907875460805;18854.71717924232;19096.522586935436;6781.084553585301;6812.080369150201;6887.345702158287;6949.548279361826;7112.92887071133;3708.165662725865;3753.3324679733255;3765.566959799658;3778.7354885157774;3903.843846246175;2836.9148925532268;2936.295289130636;2951.9704802951696;2955.2316397736745;2957.3050333171686;521.4290352064484;546.8824202796418;548.8847342058212;558.9804356847491;569.1718549510452;483.54546726077376;487.7536843893811;527.944565820591;570.0451743329988;589.0957380421119;8165.004434271238;8221.424500284922;8627.137286271323;8699.391042626956;8791.736247912573;495.2892599120202;531.9573635674122;554.4400155842628;584.1325631437312;585.7569108819878;3719.590845007047;3756.492873462102;3773.8867833965023;3845.73079884411;4031.9798401008393;98019;99006;100139.49930250448;101810.4909475448;102010.48994754978;50807;79749.60751177474;80479;80799.5960020196;82114;73269.2673073273;77838.22161778423;77951.61024194956;78938;79571;7454.366378857858;7785.2094018374955;7799.087849276046;7936.603169841369;8076.152004039492;42590;43203;43370.349444758984;44496.55503445054;44999;57430;63215.6839215801;64560.67719661372;66980;67977;9289.012556223737;9321.056965341826;9659.323847330901;9681.273904457335;9859.802803944025;11041.83437248458;11060.004599586076;11163.218574700019;11428.371439570903;11801;10364.740881478023;10491.318064325795;10600.734981625521;10697.14422846186;11064.28082174656;9632.181264592587;10318.380897146195;10333.379997200193;10768.784624307627;11134.443277836237;10467.476626168509;10714.303570267908;10909.509072091934;10993.945030275117;11770.352630605057;9059.85889157593;9145.5884485197;9398.812023759623;9450.102240287162;9741.220702343653;9520.333576649846;9761.951190244003;9803.362781419184;9905;9993.300468967103 12:33:17 INFO - PROCESS | 20428 | __end_tp_report 12:33:17 INFO - PROCESS | 20428 | __start_cc_report 12:33:17 INFO - PROCESS | 20428 | _x_x_mozilla_cycle_collect,1014 12:33:17 INFO - PROCESS | 20428 | __end_cc_report 12:33:17 INFO - PROCESS | 20428 | __startTimestamp1472239997207__endTimestamp 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | ------- Summary: start ------- 12:33:17 INFO - PROCESS | 20428 | Number of tests: 6 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | [#0] dojo.html Cycles:165 Average:4733.61 Median:4849.73 stddev:510.22 (10.5%) stddev-sans-first:483.16 12:33:17 INFO - PROCESS | 20428 | Values: 2585.8 2591.3 2596.6 2634.7 2649.8 4541.8 4606.1 4649.3 4781.2 4845.0 4611.0 4651.1 4658.7 4666.3 4671.2 4259.0 4288.2 4297.3 4413.4 4439.0 4489.7 4500.6 4518.2 4521.0 4521.7 4516.9 4542.5 4545.8 4550.5 4602.2 4369.6 4561.8 4567.0 4569.9 4570.4 4265.2 4311.1 4346.5 4358.5 4371.1 4688.8 4757.7 4765.2 4793.5 4808.3 5076.1 5084.9 5085.9 5161.9 5162.7 5150.5 5208.4 5208.9 5213.1 5258.9 4608.7 4714.2 4775.8 4779.0 4849.7 5215.5 5247.6 5249.2 5252.4 5262.4 5032.3 5034.4 5041.4 5042.0 5101.3 4904.8 5123.5 5180.5 5184.4 5254.5 5158.7 5160.2 5168.0 5168.3 5172.9 5112.9 5136.6 5141.7 5142.5 5188.4 4214.2 4254.9 4279.9 4309.6 4313.0 3790.8 3791.8 3797.1 3834.6 3847.6 4361.6 4365.5 4365.8 4413.7 4414.3 4921.8 5060.3 5086.7 5103.5 5113.0 4338.5 4360.8 4372.9 4412.7 4413.3 4580.6 4596.1 4598.9 4600.3 4638.4 4697.7 4832.4 4839.6 4848.1 4882.8 5145.5 5168.5 5170.5 5185.5 5233.6 4879.4 4882.7 4883.6 4886.1 4944.7 4900.0 5044.8 5064.6 5068.0 5098.3 5146.5 5176.7 5177.6 5177.8 5198.3 4985.1 5006.7 5009.0 5010.7 5065.4 4825.7 4972.6 5003.6 5021.5 5042.4 4923.1 4937.1 4941.8 4948.1 4995.8 5052.0 5059.2 5059.3 5060.0 5105.2 4712.1 4760.3 4763.0 4916.8 4918.8 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | [#1] ext.html Cycles:180 Average:45541.55 Median:19228.63 stddev:84943.10 (441.8%) stddev-sans-first:85114.25 12:33:17 INFO - PROCESS | 20428 | Values: 557.6 558.7 567.2 569.6 573.0 27456.7 37630.8 39037.2 39071.0 43783.8 39476.4 39967.0 40059.0 40081.2 40081.2 7648.7 7780.6 7795.7 7796.2 7796.8 7502.6 7593.0 7594.8 7599.6 7600.0 7614.7 7642.8 7658.3 7658.8 7666.5 7566.7 7567.2 7602.8 7604.1 7606.6 7609.2 7658.6 7658.6 7663.1 7663.7 36378.0 36602.6 36697.6 36705.0 36723.8 3097.2 3322.5 3361.7 3487.3 3677.2 28004.3 28120.4 28701.4 30303.4 31756.7 3311.1 3327.1 3327.3 3348.4 3348.8 253485.0 257541.0 258247.0 258272.0 258362.0 30881.5 52988.2 53512.5 53550.5 53573.0 31213.7 33275.5 33497.5 33528.2 33541.5 13171.2 13208.4 13266.3 13277.1 13297.2 10744.4 10786.8 10835.9 10868.2 10886.7 7653.5 7737.8 7798.6 7799.2 7813.8 2672.6 2757.0 2789.1 2799.7 2824.1 829.0 887.4 903.1 935.5 951.3 26447.0 26872.2 27119.3 27285.3 27764.3 924.5 935.1 938.8 961.6 992.4 13768.6 14454.8 14493.7 14520.0 14756.3 393093.0 396131.0 396877.0 396959.0 397250.0 47039.0 47147.3 47309.5 47367.0 47397.0 1046.0 1083.3 1094.8 1126.1 1159.9 25390.4 28308.4 28656.4 28753.6 29061.9 40595.2 40973.4 41075.0 41093.4 41105.0 292952.0 295823.0 296204.0 296347.0 297058.0 22182.0 24026.3 24215.2 24924.4 25144.7 40441.4 40775.4 40950.8 41004.2 41272.4 34536.0 34766.5 34869.8 35016.3 35059.3 33959.0 33991.7 34234.7 34311.3 34529.7 33964.8 34765.7 34970.0 35134.8 35163.6 29167.9 30865.0 31866.0 31883.4 32074.2 24102.4 24157.4 24567.4 24650.6 25039.6 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | [#2] jquery.html Cycles:180 Average:21184.63 Median:4556.82 stddev:52158.60 (1144.6%) stddev-sans-first:52281.39 12:33:17 INFO - PROCESS | 20428 | Values: 320.9 354.2 354.6 356.2 375.8 12313.3 15056.8 16338.3 16342.4 16723.8 13356.4 13472.9 13903.9 14074.4 14261.9 4606.0 4751.7 4759.0 4759.3 4760.1 4209.2 4634.9 4712.5 4748.5 4753.8 4663.7 4709.7 4712.2 4714.0 4714.4 4518.3 4654.2 4657.3 4706.5 4708.1 4595.3 4596.2 4605.3 4607.7 4608.8 12475.6 12656.6 12676.7 12681.6 12802.7 1768.5 1877.9 2104.0 2106.4 2362.3 8881.7 9113.2 9261.6 9410.8 9581.3 1704.9 1750.4 1788.8 1794.0 1794.1 67017.3 69289.7 69782.0 71249.6 71600.3 9667.3 9874.9 9903.3 10083.8 10221.5 13642.5 13755.0 13794.2 13798.8 13809.0 6375.1 6566.2 6769.1 6771.0 7128.5 6156.8 6181.3 6302.4 6516.0 6716.8 4214.5 4292.8 4323.5 4339.2 4347.0 1750.1 1808.8 1849.5 1895.9 1896.5 675.2 798.9 806.2 808.8 910.3 16324.8 16552.7 17170.1 17260.2 17455.9 792.6 819.4 827.6 852.0 860.7 9254.7 9324.1 9526.4 9597.5 9837.8 271738.0 273965.0 274821.0 365497.0 367116.0 48507.8 49388.8 52140.2 57491.4 82804.6 900.2 905.7 930.2 935.6 938.0 15662.5 15753.1 16343.4 16390.6 16709.8 27470.9 31570.0 36322.6 39772.0 39970.0 49015.3 51015.0 54483.2 54683.7 54818.7 7655.5 7694.5 7782.5 7804.0 7983.0 9877.7 11712.6 12097.6 12703.4 12729.9 10205.2 10340.7 10373.0 10476.3 11042.6 7563.4 8112.3 10096.7 10113.3 10506.3 7692.5 7744.2 7956.4 8024.2 8110.0 11432.8 11491.4 11503.4 11932.5 11940.7 10632.8 10684.7 10697.5 10713.1 10935.7 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | [#3] mootools.html Cycles:180 Average:5041.72 Median:5059.22 stddev:2843.32 (56.2%) stddev-sans-first:2837.41 12:33:17 INFO - PROCESS | 20428 | Values: 1301.4 1338.4 1395.7 1608.3 1644.4 6567.8 6832.7 7261.2 7851.7 7852.7 7313.4 7624.3 7649.0 7847.4 7859.3 2197.0 2221.5 2225.7 2233.7 2235.0 1849.4 2074.8 2141.4 2145.8 2177.2 2160.9 2185.1 2188.3 2188.9 2198.9 2141.7 2158.7 2172.6 2174.9 2177.8 2140.3 2141.8 2142.2 2142.8 2181.0 7690.8 7866.9 7867.1 7914.9 7924.7 4475.4 4610.5 4612.8 4616.7 4623.4 2783.9 2927.0 2965.6 2978.4 2986.4 881.5 912.7 928.2 930.0 932.4 10587.2 10594.7 10599.4 10609.0 10639.7 3798.8 3905.8 4143.9 4160.5 4186.6 9129.4 9139.7 9148.5 9153.0 9156.7 4588.8 4590.3 4594.2 4596.3 4599.1 4147.4 4193.6 4204.4 4204.7 4206.8 3530.4 3599.8 3600.6 3623.5 3631.1 1024.7 1080.7 1116.7 1128.9 1143.5 778.0 791.6 791.9 804.6 808.6 4908.3 4929.4 5057.2 5219.2 5365.4 765.8 779.7 792.4 801.4 844.7 3354.6 3466.6 3486.8 3492.6 3539.6 9334.6 9668.3 9741.2 9744.5 9752.0 9662.2 9668.5 9672.7 9679.7 9688.2 6993.6 7013.3 7152.6 7259.1 7268.5 4945.2 4992.4 5030.1 5061.2 5062.5 8601.0 8606.9 8699.1 8721.2 8793.8 8717.7 8724.2 8799.6 8804.0 8888.5 6081.2 6169.7 6222.5 6230.0 6235.8 7211.7 7239.7 7254.5 7258.1 7410.0 5528.3 6098.0 6681.8 6703.0 6722.0 6631.4 6643.4 6676.9 6678.1 6702.1 6125.4 6228.5 6232.8 6233.1 6241.9 4363.4 4414.7 4437.4 4450.6 4482.0 4464.8 4466.5 4550.5 4573.0 4609.9 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | [#4] prototype.html Cycles:180 Average:4249.91 Median:4926.34 stddev:1252.70 (25.4%) stddev-sans-first:1220.46 12:33:17 INFO - PROCESS | 20428 | Values: 290.8 294.8 297.4 300.2 301.9 4908.4 4934.7 4956.9 4965.9 4971.7 4876.8 4911.7 4916.4 4925.9 4934.7 3612.5 3731.9 3748.2 3770.6 3796.4 3718.9 3828.6 3878.1 3880.1 3883.4 3796.5 3808.0 3821.3 3824.9 3830.8 3569.1 3764.9 3778.7 3787.8 3839.9 3296.9 3301.8 3306.8 3309.0 3325.4 4782.9 4794.4 4795.4 4808.3 4812.7 4324.1 4333.7 4340.3 4346.9 4350.8 5000.7 5112.8 5149.2 5156.4 5180.9 3576.0 3577.6 3581.8 3586.4 3598.2 5383.7 5416.4 5424.9 5433.1 5435.2 3609.2 3688.3 3711.2 3711.9 3715.7 3746.5 3758.5 3759.4 3759.8 3768.7 4314.0 4323.4 4327.6 4331.7 4335.6 5002.9 5148.9 5160.5 5166.1 5174.7 1268.8 1289.5 1289.6 1290.9 1293.4 779.6 802.3 802.7 814.5 823.3 4339.7 4401.4 4507.8 4523.1 4534.0 5277.6 5279.0 5286.3 5287.9 5297.8 4518.4 4530.5 4542.3 4543.7 4556.4 2639.3 2758.8 2789.4 2793.8 2804.7 4929.1 4951.5 4980.4 4987.8 4997.2 5104.9 5376.2 5380.8 5393.3 5405.7 4984.8 5003.9 5011.6 5012.0 5012.9 5275.7 5282.2 5283.6 5299.3 5306.6 4935.9 5176.5 5183.8 5193.0 5198.7 5033.3 5034.7 5037.3 5040.3 5047.5 3672.2 3689.8 3706.1 3709.7 3716.5 5073.6 5287.2 5294.1 5297.2 5307.8 5100.9 5104.7 5127.8 5138.3 5145.8 5093.5 5101.6 5141.5 5152.8 5154.2 4897.2 5006.5 5009.0 5055.0 5089.2 4926.8 5006.5 5114.3 5160.3 5206.2 4944.3 5018.9 5053.5 5062.4 5074.2 12:33:17 INFO - PROCESS | 20428 | 12:33:17 INFO - PROCESS | 20428 | [#5] yui.html Cycles:180 Average:17472.89 Median:560.19 stddev:25491.60 (4550.6%) stddev-sans-first:25531.65 12:33:17 INFO - PROCESS | 20428 | Values: 606.2 613.8 650.3 681.3 693.2 12015.0 12626.6 12975.7 12976.5 13015.9 12215.4 12397.1 12401.0 12403.3 12409.1 593.3 714.2 732.8 737.6 738.2 603.7 612.4 612.7 708.2 736.3 761.4 765.4 765.7 766.1 766.2 761.3 761.9 762.7 763.4 763.5 456.8 465.6 468.4 561.4 561.5 10005.8 11791.6 12336.5 12348.1 12883.1 12467.9 13123.0 13150.9 13425.8 13629.8 10284.9 10342.2 10347.9 10401.6 10702.0 3270.2 3340.3 3365.6 3366.0 3439.5 64216.7 64317.4 65085.3 65838.3 67683.3 9251.0 9263.8 9306.8 9418.6 9466.5 18233.3 18418.9 18424.9 18854.7 19096.5 6781.1 6812.1 6887.3 6949.5 7112.9 3708.2 3753.3 3765.6 3778.7 3903.8 2836.9 2936.3 2952.0 2955.2 2957.3 521.4 546.9 548.9 559.0 569.2 483.5 487.8 527.9 570.0 589.1 8165.0 8221.4 8627.1 8699.4 8791.7 495.3 532.0 554.4 584.1 585.8 3719.6 3756.5 3773.9 3845.7 4032.0 98019.0 99006.0 100139.5 101810.5 102010.5 50807.0 79749.6 80479.0 80799.6 82114.0 73269.3 77838.2 77951.6 78938.0 79571.0 7454.4 7785.2 7799.1 7936.6 8076.2 42590.0 43203.0 43370.3 44496.6 44999.0 57430.0 63215.7 64560.7 66980.0 67977.0 9289.0 9321.1 9659.3 9681.3 9859.8 11041.8 11060.0 11163.2 11428.4 11801.0 10364.7 10491.3 10600.7 10697.1 11064.3 9632.2 10318.4 10333.4 10768.8 11134.4 10467.5 10714.3 10909.5 10993.9 11770.4 9059.9 9145.6 9398.8 9450.1 9741.2 9520.3 9762.0 9803.4 9905.0 9993.3 12:33:17 INFO - PROCESS | 20428 | -------- Summary: end -------- 12:33:17 INFO - PROCESS | 20428 | 12:33:18 INFO - TEST-INFO | 20428: exit 0 12:33:18 INFO - TEST-OK | dromaeo_css | took 1119967ms 12:33:18 INFO - TEST-START | kraken 12:33:18 INFO - operating with platform_type : linux_ 12:33:18 INFO - Initialising browser for kraken test... 12:33:18 INFO - TEST-INFO | started process 20737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzSQmTj/profile http://localhost:50048/getInfo.html) 12:33:22 INFO - PROCESS | 20737 | __metrics Screen width/height:1600/1200 12:33:22 INFO - PROCESS | 20737 | colorDepth:24 12:33:22 INFO - PROCESS | 20737 | Browser inner width/height: 1024/697 12:33:22 INFO - PROCESS | 20737 | __metrics 12:33:22 INFO - PROCESS | 20737 | 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" 12:33:22 INFO - PROCESS | 20737 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:23 INFO - PROCESS | 20737 | console.error: PushService: 12:33:23 INFO - PROCESS | 20737 | stateChangeProcessEnqueue: Error transitioning state 12:33:23 INFO - PROCESS | 20737 | UnknownError 12:33:23 INFO - TEST-INFO | 20737: exit 0 12:33:23 INFO - Browser initialized. 12:33:23 INFO - Running cycle 1/1 for kraken test... 12:33:23 INFO - TEST-INFO | started process 20818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzSQmTj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:35 INFO - PROCESS | 20818 | RSS: Main: 181444608 12:33:35 INFO - PROCESS | 20818 | 12:33:38 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50048/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:39 INFO - PROCESS | 20818 | RSS: Main: 234074112 12:33:39 INFO - PROCESS | 20818 | 12:33:42 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50048/tests/kraken/driver.html?testName=audio-dft) 12:33:42 INFO - PROCESS | 20818 | RSS: Main: 224296960 12:33:42 INFO - PROCESS | 20818 | 12:33:46 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50048/tests/kraken/driver.html?testName=audio-fft) 12:33:46 INFO - PROCESS | 20818 | RSS: Main: 302215168 12:33:46 INFO - PROCESS | 20818 | 12:33:49 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50048/tests/kraken/driver.html?testName=audio-oscillator) 12:33:49 INFO - PROCESS | 20818 | RSS: Main: 213409792 12:33:49 INFO - PROCESS | 20818 | 12:33:51 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50048/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:33:52 INFO - PROCESS | 20818 | RSS: Main: 214941696 12:33:52 INFO - PROCESS | 20818 | 12:33:55 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50048/tests/kraken/driver.html?testName=imaging-darkroom) 12:33:56 INFO - PROCESS | 20818 | RSS: Main: 252878848 12:33:56 INFO - PROCESS | 20818 | 12:33:59 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50048/tests/kraken/driver.html?testName=imaging-desaturate) 12:34:00 INFO - PROCESS | 20818 | RSS: Main: 252690432 12:34:00 INFO - PROCESS | 20818 | 12:34:03 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50048/tests/kraken/driver.html?testName=json-parse-financial) 12:34:03 INFO - PROCESS | 20818 | RSS: Main: 251940864 12:34:03 INFO - PROCESS | 20818 | 12:34:05 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50048/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:06 INFO - PROCESS | 20818 | RSS: Main: 215015424 12:34:06 INFO - PROCESS | 20818 | 12:34:08 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:08 INFO - PROCESS | 20818 | RSS: Main: 221790208 12:34:08 INFO - PROCESS | 20818 | 12:34:10 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:11 INFO - PROCESS | 20818 | RSS: Main: 222691328 12:34:11 INFO - PROCESS | 20818 | 12:34:14 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:15 INFO - PROCESS | 20818 | RSS: Main: 224075776 12:34:15 INFO - PROCESS | 20818 | 12:34:17 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:18 INFO - PROCESS | 20818 | RSS: Main: 222769152 12:34:18 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | Cycle 1(1): loaded http://localhost:50048/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50048/tests/kraken/driver.html?testName=ai-astar) 12:34:20 INFO - PROCESS | 20818 | RSS: Main: 272404480 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | __start_tp_report 12:34:20 INFO - PROCESS | 20818 | _x_x_mozilla_page_load 12:34:20 INFO - PROCESS | 20818 | _x_x_mozilla_page_load_details 12:34:20 INFO - PROCESS | 20818 | |i|pagename|runs| 12:34:20 INFO - PROCESS | 20818 | |0;ai-astar;113;114;129;115;114;115;114;113;114;114 12:34:20 INFO - PROCESS | 20818 | |1;audio-beat-detection;148;123;155;186;125;155;154;124;185;122 12:34:20 INFO - PROCESS | 20818 | |2;audio-dft;161;162;161;160;161;164;163;162;161;162 12:34:20 INFO - PROCESS | 20818 | |3;audio-fft;78;74;76;164;77;76;76;74;165;77 12:34:20 INFO - PROCESS | 20818 | |4;audio-oscillator;85;84;147;84;84;84;130;84;83;136 12:34:20 INFO - PROCESS | 20818 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;118 12:34:20 INFO - PROCESS | 20818 | |6;imaging-darkroom;147;147;146;147;147;147;146;146;147;146 12:34:20 INFO - PROCESS | 20818 | |7;imaging-desaturate;95;95;95;95;95;95;96;97;97;94 12:34:20 INFO - PROCESS | 20818 | |8;json-parse-financial;63;63;63;64;64;64;65;64;65;63 12:34:20 INFO - PROCESS | 20818 | |9;json-stringify-tinderbox;66;66;66;67;66;66;66;65;65;66 12:34:20 INFO - PROCESS | 20818 | |10;stanford-crypto-aes;83;83;83;89;85;82;89;83;86;87 12:34:20 INFO - PROCESS | 20818 | |11;stanford-crypto-ccm;134;132;132;142;133;140;145;148;181;142 12:34:20 INFO - PROCESS | 20818 | |12;stanford-crypto-pbkdf2;177;173;172;169;178;171;170;170;170;171 12:34:20 INFO - PROCESS | 20818 | |13;stanford-crypto-sha256-iterative;68;73;69;70;76;68;69;69;68;68 12:34:20 INFO - PROCESS | 20818 | __end_tp_report 12:34:20 INFO - PROCESS | 20818 | __start_cc_report 12:34:20 INFO - PROCESS | 20818 | _x_x_mozilla_cycle_collect,2030 12:34:20 INFO - PROCESS | 20818 | __end_cc_report 12:34:20 INFO - PROCESS | 20818 | __startTimestamp1472240060645__endTimestamp 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | ------- Summary: start ------- 12:34:20 INFO - PROCESS | 20818 | Number of tests: 14 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#0] ai-astar Cycles:10 Average:115.50 Median:114.00 stddev:4.79 (4.2%) stddev-sans-first:4.99 12:34:20 INFO - PROCESS | 20818 | Values: 113.0 114.0 129.0 115.0 114.0 115.0 114.0 113.0 114.0 114.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:154.50 stddev:24.39 (15.8%) stddev-sans-first:25.87 12:34:20 INFO - PROCESS | 20818 | Values: 148.0 123.0 155.0 186.0 125.0 155.0 154.0 124.0 185.0 122.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 12:34:20 INFO - PROCESS | 20818 | Values: 161.0 162.0 161.0 160.0 161.0 164.0 163.0 162.0 161.0 162.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.00 stddev:37.34 (49.1%) stddev-sans-first:39.17 12:34:20 INFO - PROCESS | 20818 | Values: 78.0 74.0 76.0 164.0 77.0 76.0 76.0 74.0 165.0 77.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#4] audio-oscillator Cycles:10 Average:100.10 Median:84.00 stddev:26.24 (31.2%) stddev-sans-first:27.26 12:34:20 INFO - PROCESS | 20818 | Values: 85.0 84.0 147.0 84.0 84.0 84.0 130.0 84.0 83.0 136.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 12:34:20 INFO - PROCESS | 20818 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 12:34:20 INFO - PROCESS | 20818 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 146.0 146.0 147.0 146.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 12:34:20 INFO - PROCESS | 20818 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 97.0 97.0 94.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 12:34:20 INFO - PROCESS | 20818 | Values: 63.0 63.0 63.0 64.0 64.0 64.0 65.0 64.0 65.0 63.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 12:34:20 INFO - PROCESS | 20818 | Values: 66.0 66.0 66.0 67.0 66.0 66.0 66.0 65.0 65.0 66.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.50 stddev:2.62 (3.1%) stddev-sans-first:2.68 12:34:20 INFO - PROCESS | 20818 | Values: 83.0 83.0 83.0 89.0 85.0 82.0 89.0 83.0 86.0 87.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:142.00 stddev:14.56 (10.3%) stddev-sans-first:15.08 12:34:20 INFO - PROCESS | 20818 | Values: 134.0 132.0 132.0 142.0 133.0 140.0 145.0 148.0 181.0 142.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:171.50 stddev:3.07 (1.8%) stddev-sans-first:2.70 12:34:20 INFO - PROCESS | 20818 | Values: 177.0 173.0 172.0 169.0 178.0 171.0 170.0 170.0 170.0 171.0 12:34:20 INFO - PROCESS | 20818 | 12:34:20 INFO - PROCESS | 20818 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:69.00 stddev:2.66 (3.9%) stddev-sans-first:2.74 12:34:20 INFO - PROCESS | 20818 | Values: 68.0 73.0 69.0 70.0 76.0 68.0 69.0 69.0 68.0 68.0 12:34:20 INFO - PROCESS | 20818 | -------- Summary: end -------- 12:34:20 INFO - PROCESS | 20818 | 12:34:21 INFO - TEST-INFO | 20818: exit 0 12:34:21 INFO - TEST-OK | kraken | took 63695ms 12:34:21 INFO - SUITE-END | took 1183s 12:34:22 INFO - Completed test suite (00:19:44) 12:34:22 INFO - javascript benchmark 12:34:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2585.806706504025, 2591.3133019749766, 2596.584546472565, 2634.7233540478237, 2649.8145129840905, 4541.818327266906, 4606.078784243148, 4649.30260460931, 4781.18719817631, 4845.030993801241, 4610.9538904610945, 4651.093036857806, 4658.743769092698, 4666.253399456088, 4671.20589499785, 4258.97870510647, 4288.228118938606, 4297.312430011199, 4413.44831896013, 4439.001224724428, 4489.730616163024, 4500.572445617661, 4518.209313370164, 4520.98277887476, 4521.728696278217, 4516.932246016312, 4542.500324964251, 4545.795439205243, 4550.4994450610575, 4602.21762300408, 4369.563043695637, 4561.817527298904, 4567.0409214065085, 4569.862904112882, 4570.405847239871, 4265.168292183025, 4311.116221174683, 4346.456692913386, 4358.4987726411555, 4371.060222052274, 4688.835890743808, 4757.738324392157, 4765.2375619900995, 4793.544613261734, 4808.2787581863, 5076.111680455942, 5084.923726144111, 5085.898282034339, 5161.869158878505, 5162.664426812245, 5150.484951504857, 5208.401033881114, 5208.8958220835375, 5213.061648903196, 5258.92111618326, 4608.746518941491, 4714.245720684674, 4775.761211939389, 4779.02030083832, 4849.733264670477, 5215.504527069922, 5247.553957913544, 5249.238860365225, 5252.448492908266, 5262.394824595221, 5032.270320803463, 5034.395872495324, 5041.395032596112, 5042.0, 5101.2603172539775, 4904.803807847647, 5123.513266239701, 5180.4560521145495, 5184.351956005499, 5254.474552544715, 5158.690478571222, 5160.174372100521, 5168.01807656544, 5168.2764412982215, 5172.896542069213, 5112.948870511247, 5136.640435169502, 5141.665791723526, 5142.537171654569, 5188.351456067991, 4214.220369231702, 4254.851080212177, 4279.850205242801, 4309.59058889405, 4312.978435107804, 3790.8104594770152, 3791.8483260669263, 3797.0697179191275, 3834.578196398394, 3847.615238476186, 4361.6074553290355, 4365.497967733688, 4365.847195348148, 4413.7351758894565, 4414.27164517851, 4921.803127874917, 5060.342155519833, 5086.694798312114, 5103.464136265713, 5113.0, 4338.544452832407, 4360.760158191267, 4372.934405983913, 4412.735235885857, 4413.337999300131, 4580.564846339593, 4596.126735920231, 4598.931016034762, 4600.332951721982, 4638.443386793539, 4697.718136911796, 4832.37179166699, 4839.637027222902, 4848.0546293472435, 4882.755862206946, 5145.459726728789, 5168.508991645787, 5170.457102004236, 5185.455527169593, 5233.581313494988, 4879.39007624047, 4882.7314497701545, 4883.584895283941, 4886.071646387175, 4944.703317800943, 4899.971006088619, 5044.8234311798915, 5064.5948324134715, 5068.037072956274, 5098.337216161875, 5146.459621739664, 5176.7152806596, 5177.559907407913, 5177.792888284426, 5198.272241886215, 4985.052839960396, 5006.7246301452315, 5009.0, 5010.69935803853, 5065.39215294167, 4825.662203645711, 4972.602191824573, 5003.599712023102, 5021.548060674557, 5042.39491261051, 4923.08922849274, 4937.086638971802, 4941.75291235444, 4948.084604348207, 4995.8001679932395, 5052.040112378636, 5059.241113832954, 5059.316992206005, 5060.038592667382, 5105.1576489880235, 4712.104700106968, 4760.309755085563, 4763.0, 4916.778744956557, 4918.770307423144], "value": 4699.895553537301, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.5873853348522, 558.6592178770951, 567.2285691459617, 569.6468189722373, 572.9686564184468, 27456.725432745665, 37630.81184594076, 39037.21925561487, 39071.0, 43783.78108109455, 39476.407853882214, 39967.0, 40059.0, 40081.19837603246, 40081.19837603254, 7648.694052237917, 7780.6109694515335, 7795.688172473095, 7796.220377962187, 7796.76609701707, 7502.624868756568, 7593.0, 7594.810129746746, 7599.620018999056, 7600.0, 7614.695412183506, 7642.770716878503, 7658.349040331551, 7658.846823063535, 7666.540007599534, 7566.735164269252, 7567.243275672444, 7602.809929751773, 7604.0875094988405, 7606.619669016527, 7609.239076092402, 7658.578778167199, 7658.578778167199, 7663.118741344761, 7663.7317693881005, 36378.0, 36602.63397366046, 36697.63302366996, 36705.0, 36723.81638091819, 3097.225693576606, 3322.5182348559565, 3361.7310615150727, 3487.2676737885076, 3677.2277821657485, 28004.29989250285, 28120.437591248265, 28701.42597148066, 30303.393932121453, 31756.682433175603, 3311.106001379638, 3327.1183136468853, 3327.3012667339885, 3348.430766769655, 3348.782329148622, 253485.0, 257541.0, 258247.0, 258272.0, 258362.0, 30881.536776947913, 52988.205176922376, 53512.46487535153, 53550.46449535533, 53573.0, 31213.687863121533, 33275.500867486524, 33497.497537536954, 33528.1617959553, 33541.49687754686, 13171.209727416386, 13208.405621747044, 13266.27035513056, 13277.136986095873, 13297.202167869958, 10744.409057501756, 10786.838197427045, 10835.891641083646, 10868.239223254297, 10886.67339979802, 7653.540787552764, 7737.806554836062, 7798.5710785906185, 7799.220077992155, 7813.804654883674, 2672.6124711916664, 2756.9586456203174, 2789.121426750567, 2799.7060308667706, 2824.110405222348, 828.9668413263522, 887.4187407248263, 903.1284810158229, 935.5088578496344, 951.3150531617224, 26447.0, 26872.193834185007, 27119.322016949733, 27285.317867053483, 27764.30589235285, 924.4638109896244, 935.102301790289, 938.8075444533854, 961.6201600367815, 992.4045572656348, 13768.586942391745, 14454.783178252335, 14493.724351316387, 14520.0, 14756.262186890612, 393093.0, 396131.0, 396877.0, 396959.0, 397250.0, 47039.0, 47147.29279060817, 47309.5269047312, 47367.0, 47397.0, 1045.9790804184026, 1083.312096818526, 1094.8412480190486, 1126.0934947367448, 1159.8782127876452, 25390.365240869127, 28308.35011310762, 28656.42687146287, 28753.568696469152, 29061.85469072641, 40595.18809623732, 40973.38539921963, 41075.0, 41093.38359924544, 41105.0, 292952.0, 295823.0, 296204.0, 296347.0, 297058.0, 22182.0, 24026.279211623678, 24215.152469663477, 24924.37689057788, 25144.74855251424, 40441.39337909875, 40775.38836917508, 40950.79524602477, 41004.1799164021, 41272.38091428691, 34536.0, 34766.47850282197, 34869.825650871586, 35016.299674005866, 35059.298814024085, 33959.0, 33991.66008339885, 34234.65765342315, 34311.31377372389, 34529.654703452645, 33964.83017584896, 34765.65234347725, 34970.0, 35134.82432587821, 35163.64836351604, 29167.85416072906, 30865.0, 31866.0, 31883.36233275368, 32074.198145045626, 24102.397440063436, 24157.396065098514, 24567.38581535476, 24650.63024054605, 25039.624405634295], "value": 16053.896499256874, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.93420848726015, 354.2366200837195, 354.5638864197036, 356.1914454188991, 375.81397208381856, 12313.322767247797, 15056.849431505681, 16338.26477808497, 16342.428015019446, 16723.83276167238, 13356.398962046707, 13472.865271347284, 13903.930480347584, 14074.366653500592, 14261.928690356559, 4605.953940460586, 4751.738654374008, 4758.976205118969, 4759.262314341283, 4760.09558183944, 4209.200251952135, 4634.88762696952, 4712.458067322267, 4748.5251474852585, 4753.762311884409, 4663.673542852001, 4709.670323077386, 4712.175369310374, 4713.976430117862, 4714.387129673138, 4518.3082288491005, 4654.162250794856, 4657.3246879202325, 4706.5293470653005, 4708.081924024782, 4595.333676616905, 4596.241620132674, 4605.332226827091, 4607.746573938432, 4608.838690645811, 12475.563355282522, 12656.620301390973, 12676.68308292291, 12681.619551413472, 12802.743945121045, 1768.45177994822, 1877.8873267603851, 2104.0005997151384, 2106.410205142547, 2362.2795047510504, 8881.733547993506, 9113.179813816789, 9261.583228754722, 9410.811783764355, 9581.281403894665, 1704.9318027278894, 1750.3523696232476, 1788.7585176001203, 1793.9820601793945, 1794.102948525737, 67017.32982670159, 69289.65355173242, 69782.0, 71249.64375178143, 71600.28399715989, 9667.274954378308, 9874.901250987397, 9903.25725570585, 10083.848742268872, 10221.488925553842, 13642.454301827816, 13755.0, 13794.241316727675, 13798.793018104736, 13809.0, 6375.139356186855, 6566.179227596551, 6769.120014398195, 6770.966145169341, 7128.536645118051, 6156.830202261556, 6181.289151747516, 6302.432781049728, 6515.967420162963, 6716.764913228014, 4214.494260688737, 4292.763897985641, 4323.524412314643, 4339.175556644227, 4346.956530434719, 1750.054970316015, 1808.8191180881806, 1849.5098798818324, 1895.8672892897364, 1896.4500294914596, 675.166169780321, 798.9241022103007, 806.1736719862188, 808.7978005498626, 910.2763303173992, 16324.75512867308, 16552.668946621, 17170.055646939538, 17260.223289951984, 17455.912720436318, 792.5522080024767, 819.4223072733708, 827.6234313387515, 851.9957400212959, 860.7159637319669, 9254.722358329127, 9324.11420915012, 9526.380785248937, 9597.472139032281, 9837.769759003553, 271738.0, 273965.0, 274821.0, 365497.0, 367116.0, 48507.75746121317, 49388.75305623449, 52140.21789673158, 57491.42508574944, 82804.58597706973, 900.243795212025, 905.6739573753443, 930.2139691960283, 935.5696379665294, 938.0, 15662.530124096294, 15753.133577652881, 16343.427980020642, 16390.590235244214, 16709.832901671314, 27470.862645686644, 31570.0, 36322.63677363192, 39772.005699857735, 39970.0, 49015.26477102775, 51014.97970040504, 54483.182752257955, 54683.72658136684, 54818.725906370215, 7655.5023923447025, 7694.499857509244, 7782.455228133977, 7803.985481887318, 7982.960085199769, 9877.703668889944, 11712.648620541397, 12097.57658481949, 12703.36483175819, 12729.872701272869, 10205.183585313305, 10340.741481462723, 10373.0, 10476.319039262424, 11042.613508527164, 7563.432742544222, 8112.310453611509, 10096.69709908704, 10113.262986529699, 10506.264561480622, 7692.538447693157, 7744.225577442211, 7956.363490920597, 8024.2376974187355, 8110.0, 11432.771344572895, 11491.367974761133, 11503.367314797431, 11932.463039163085, 11940.70148246266, 10632.84050739224, 10684.732881678021, 10697.51861166265, 10713.089387401846, 10935.671929842118], "value": 7756.601378733602, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1301.4273719563391, 1338.3642769684402, 1395.6510872281929, 1608.3164655021612, 1644.4080131152775, 6567.802965911018, 6832.658367081651, 7261.164966028909, 7851.685932562697, 7852.725154619582, 7313.378362839165, 7624.313811756941, 7649.0, 7847.411444141684, 7859.29266366027, 2196.9780302196973, 2221.511267521151, 2225.6772767948637, 2233.698450709158, 2235.0, 1849.4359220437761, 2074.7510298764096, 2141.4004078857943, 2145.7961493658154, 2177.205320058183, 2160.8811515366647, 2185.1368709359795, 2188.277868303464, 2188.8686678799245, 2198.912043518257, 2141.7322834645747, 2158.6546152615583, 2172.576347612216, 2174.9021294041804, 2177.771334009922, 2140.2937030779804, 2141.764405915348, 2142.2073832681854, 2142.775008624103, 2181.029441898356, 7690.769276921701, 7866.921330786676, 7867.055953285585, 7914.920850791476, 7924.722634707758, 4475.351074094271, 4610.469795973472, 4612.792424340879, 4616.653750968657, 4623.42207224097, 2783.860806959644, 2927.0487091695286, 2965.5996440480476, 2978.4043191361598, 2986.4027194561177, 881.4711173296035, 912.6988093928987, 928.1600151862575, 930.0234753508789, 932.421898422982, 10587.205959552988, 10594.682159535148, 10599.417032063233, 10609.045185933302, 10639.734006649818, 3798.810059497014, 3905.8047097645003, 4143.91712165755, 4160.521540022875, 4186.602272784081, 9129.406588571721, 9139.680111196076, 9148.496832674267, 9153.038930912291, 9156.679516217033, 4588.770561471913, 4590.311453282035, 4594.241950078266, 4596.287575425815, 4599.05719327537, 4147.357159640261, 4193.643540299109, 4204.3903633972905, 4204.684648651363, 4206.831726730897, 3530.3645343838357, 3599.8200089995394, 3600.6399360063783, 3623.528941237675, 3631.055925459347, 1024.7489365105596, 1080.702806728156, 1116.7040734205443, 1128.9379084150287, 1143.519721716881, 777.9961100194463, 791.5765065689828, 791.8693415586479, 804.5615139748851, 808.599743127144, 4908.2637604358915, 4929.433115191727, 5057.241413787961, 5219.164933610604, 5365.436629153961, 765.7740966414921, 779.6647441600053, 792.4136139256908, 801.3989507869098, 844.7170197983621, 3354.5639066921144, 3466.5666791651042, 3486.8430920608635, 3492.5983511895947, 3539.610642829287, 9334.579943902541, 9668.274879384075, 9741.220702343937, 9744.46405447693, 9752.024797520333, 9662.227021838236, 9668.516574171263, 9672.661456848977, 9679.70960871175, 9688.176504997155, 6993.615351155735, 7013.298670133048, 7152.642367881585, 7259.12890453149, 7268.527545709512, 4945.18404463274, 4992.375953005874, 5030.0945829749535, 5061.190209566452, 5062.493750624908, 8601.0, 8606.870896936425, 8699.086595907338, 8721.215090641872, 8793.780155496164, 8717.651293948426, 8724.214820666171, 8799.560021998746, 8803.955980220058, 8888.511131887808, 6081.209442772411, 6169.691515424301, 6222.502199823913, 6230.003199488241, 6235.75056997715, 7211.67547460376, 7239.674214660458, 7254.492185547172, 7258.056452661121, 7409.962605235379, 5528.336599608073, 6097.969510152599, 6681.799546013627, 6702.966485167543, 6722.0, 6631.436327911989, 6643.435307998874, 6676.866462670817, 6678.131842860397, 6702.128723266139, 6125.418085281891, 6228.532860035425, 6232.781852635136, 6233.127362169209, 6241.875162496815, 4363.432753741993, 4414.668899832461, 4437.400950871591, 4450.621697155778, 4482.036362182146, 4464.754438505914, 4466.5086840446875, 4550.522195169459, 4573.0, 4609.87945545053], "value": 4051.4880079931727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.82259821508876, 294.7538805097743, 297.4081577660456, 300.19098529463076, 301.9199912023313, 4908.435529914056, 4934.654574179809, 4956.925646115302, 4965.925511117336, 4971.701697898124, 4876.7808047988, 4911.680740751857, 4916.37759539352, 4925.852224433273, 4934.679245849017, 3612.5303710517596, 3731.8693845715406, 3748.1941382602736, 3770.6417890300377, 3796.373598356268, 3718.869839555616, 3828.559715632696, 3878.1080351519167, 3880.0881792778673, 3883.359245724452, 3796.545143919034, 3807.9619203807883, 3821.331267028273, 3824.9426258606145, 3830.8467661293516, 3569.1077230692326, 3764.8682296119505, 3778.6599206071587, 3787.829547670348, 3839.8656047038357, 3296.917577060569, 3301.8018918864705, 3306.8015919044933, 3308.983455082733, 3325.3515564464938, 4782.904341913177, 4794.35276237707, 4795.376601041842, 4808.326834243208, 4812.7352995585225, 4324.091940692459, 4333.696641235115, 4340.262155433584, 4346.9347959780625, 4350.8042138103865, 5000.699958002531, 5112.795488180468, 5149.201873709581, 5156.355455568054, 5180.948190518122, 3575.964240357589, 3577.588577313616, 3581.820908954568, 3586.4082426399614, 3598.208394153282, 5383.703896285702, 5416.377116631598, 5424.864378390572, 5433.1035379162395, 5435.211894275308, 3609.1879327151705, 3688.3176612326806, 3711.2391959648207, 3711.9443208351895, 3715.702743780491, 3746.4942232798767, 3758.473813666089, 3759.3609086455367, 3759.774413535197, 3768.660820526111, 4313.986213239886, 4323.351497275434, 4327.6105150536605, 4331.682549835823, 4335.588119128678, 5002.874928126753, 5148.897022059537, 5160.451864440653, 5166.147585648326, 5174.741262936838, 1268.8160216768517, 1289.548657969703, 1289.5615490733196, 1290.8773666501668, 1293.424426130363, 779.6296759039411, 802.2619190344897, 802.6829402386024, 814.4909431605247, 823.3495538524618, 4339.674524410681, 4401.405810215644, 4507.774611269423, 4523.140603285366, 4534.0, 5277.577793776489, 5278.97360513195, 5286.312779338738, 5287.947120528823, 5297.788088476418, 4518.401918896582, 4530.524294948983, 4542.318652202196, 4543.727376357429, 4556.3621093047, 2639.274199595127, 2758.806883518175, 2789.4421115776927, 2793.7904657150793, 2804.733550312717, 4929.112759703216, 4951.4897956123, 4980.427250866197, 4987.825426110141, 4997.225430058346, 5104.872378190575, 5376.1935709643085, 5380.838574842761, 5393.325834270716, 5405.729713514309, 4984.800607975713, 5003.949960500421, 5011.649184557046, 5012.04771093491, 5012.899742005213, 5275.736213189249, 5282.234076059027, 5283.577313814809, 5299.311089558333, 5306.646805064838, 4935.876603084952, 5176.534111929945, 5183.81856635016, 5192.9740351299515, 5198.6880787152895, 5033.295338652665, 5034.697918124924, 5037.319961805109, 5040.319556859777, 5047.520485553867, 3672.2288319453214, 3689.815509224474, 3706.1475860551686, 3709.7217708672497, 3716.5168528091176, 5073.568746656428, 5287.233351164136, 5294.12646913255, 5297.205419187153, 5307.752678120716, 5100.923486147636, 5104.744762761921, 5127.846164615067, 5138.280640710229, 5145.765016395963, 5093.4651861554, 5101.617378696538, 5141.480674469558, 5152.819651312186, 5154.201098829687, 4897.240927656219, 5006.4743201963265, 5009.0482808266315, 5055.014272216882, 5089.160288552347, 4926.827561035347, 5006.549410553068, 5114.33513643224, 5160.251763494197, 5206.167013277857, 4944.332515110414, 5018.899622007466, 5053.519915608012, 5062.392512898476, 5074.16276314404], "value": 3844.618579245002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.1907353682833, 613.8311964209842, 650.3431534150507, 681.3152781454637, 693.2097408953792, 12015.0, 12626.621201363974, 12975.67560810979, 12976.54582089627, 13015.869841301585, 12215.38923053846, 12397.07021973351, 12401.0, 12403.317817520032, 12409.131360804748, 593.2969431223992, 714.2429025233243, 732.8424388756416, 737.5685224143856, 738.1658725640019, 603.6921170203184, 612.4487960835239, 612.6783438694677, 708.19973430024, 736.2784471218209, 761.3566536276837, 765.3838659878795, 765.6975494679598, 766.1074847802304, 766.2452484302958, 761.2805898425989, 761.9276168763959, 762.7139822566537, 763.427429427929, 763.4617594595796, 456.84467281124245, 465.5739997901916, 468.40746455733523, 561.390890883392, 561.4666067236101, 10005.799884002352, 11791.587294444738, 12336.5065397384, 12348.135630505869, 12883.098183127186, 12467.93766031173, 13123.0, 13150.934245328808, 13425.798613020812, 13629.795553066711, 10284.897151028468, 10342.172626189962, 10347.94826025865, 10401.583936642524, 10702.0, 3270.16610764256, 3340.265141668829, 3365.5961284645755, 3365.9663403366144, 3439.5356626855305, 64216.67891660558, 64317.35682643207, 65085.3491465084, 65838.3416165837, 67683.3231667682, 9250.95374523123, 9263.814723705487, 9306.813863722824, 9418.576164072632, 9466.479343636167, 18233.27066917335, 18418.894866307797, 18424.907875460805, 18854.71717924232, 19096.522586935436, 6781.084553585301, 6812.080369150201, 6887.345702158287, 6949.548279361826, 7112.92887071133, 3708.165662725865, 3753.3324679733255, 3765.566959799658, 3778.7354885157774, 3903.843846246175, 2836.9148925532268, 2936.295289130636, 2951.9704802951696, 2955.2316397736745, 2957.3050333171686, 521.4290352064484, 546.8824202796418, 548.8847342058212, 558.9804356847491, 569.1718549510452, 483.54546726077376, 487.7536843893811, 527.944565820591, 570.0451743329988, 589.0957380421119, 8165.004434271238, 8221.424500284922, 8627.137286271323, 8699.391042626956, 8791.736247912573, 495.2892599120202, 531.9573635674122, 554.4400155842628, 584.1325631437312, 585.7569108819878, 3719.590845007047, 3756.492873462102, 3773.8867833965023, 3845.73079884411, 4031.9798401008393, 98019.0, 99006.0, 100139.49930250448, 101810.4909475448, 102010.48994754978, 50807.0, 79749.60751177474, 80479.0, 80799.5960020196, 82114.0, 73269.2673073273, 77838.22161778423, 77951.61024194956, 78938.0, 79571.0, 7454.366378857858, 7785.2094018374955, 7799.087849276046, 7936.603169841369, 8076.152004039492, 42590.0, 43203.0, 43370.349444758984, 44496.55503445054, 44999.0, 57430.0, 63215.6839215801, 64560.67719661372, 66980.0, 67977.0, 9289.012556223737, 9321.056965341826, 9659.323847330901, 9681.273904457335, 9859.802803944025, 11041.83437248458, 11060.004599586076, 11163.218574700019, 11428.371439570903, 11801.0, 10364.740881478023, 10491.318064325795, 10600.734981625521, 10697.14422846186, 11064.28082174656, 9632.181264592587, 10318.380897146195, 10333.379997200193, 10768.784624307627, 11134.443277836237, 10467.476626168509, 10714.303570267908, 10909.509072091934, 10993.945030275117, 11770.352630605057, 9059.85889157593, 9145.5884485197, 9398.812023759623, 9450.102240287162, 9741.220702343653, 9520.333576649846, 9761.951190244003, 9803.362781419184, 9905.0, 9993.300468967103], "value": 6279.405731360452, "unit": "score"}], "name": "dromaeo_css", "value": 6208.145970998288, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 129.0, 115.0, 114.0, 115.0, 114.0, 113.0, 114.0, 114.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 155.0, 186.0, 125.0, 155.0, 154.0, 124.0, 185.0, 122.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 161.0, 164.0, 163.0, 162.0, 161.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 76.0, 164.0, 77.0, 76.0, 76.0, 74.0, 165.0, 77.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 147.0, 84.0, 84.0, 84.0, 130.0, 84.0, 83.0, 136.0], "value": 100.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 97.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 63.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 89.0, 85.0, 82.0, 89.0, 83.0, 86.0, 87.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 132.0, 132.0, 142.0, 133.0, 140.0, 145.0, 148.0, 181.0, 142.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 173.0, 172.0, 169.0, 178.0, 171.0, 170.0, 170.0, 170.0, 171.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 69.0, 70.0, 76.0, 68.0, 69.0, 69.0, 68.0, 68.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1577.2, "alertThreshold": 2.0}]} 12:34:22 INFO - Return code: 0 12:34:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:22 INFO - # TBPL SUCCESS # 12:34:22 INFO - Running post-action listener: _resource_record_post_action 12:34:22 INFO - [mozharness: 2016-08-26 19:34:22.452837Z] Finished run-tests step (success) 12:34:22 INFO - Running post-run listener: _resource_record_post_run 12:34:22 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 12288; Write bytes: 408391680; Read time: 196; Write time: 238164 12:34:22 INFO - TinderboxPrint: CPU usage
13.0% 12:34:22 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 196 12:34:22 INFO - TinderboxPrint: I/O write bytes / time
408,391,680 / 238,164 12:34:22 INFO - TinderboxPrint: CPU idle
8,329.6 (87.0%) 12:34:22 INFO - TinderboxPrint: CPU user
1,200.4 (12.5%) 12:34:22 INFO - TinderboxPrint: Swap in / out
0 / 0 12:34:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 114462720; Read time: 112; Write time: 83344 12:34:22 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282599424; Read time: 68; Write time: 152308 12:34:23 INFO - Running post-run listener: _upload_blobber_files 12:34:23 INFO - Blob upload gear active. 12:34:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:23 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 12:34:23 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'] 12:34:23 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 12:34:23 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:34:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:24 INFO - (blobuploader) - INFO - Done attempting. 12:34:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:27 INFO - (blobuploader) - INFO - Done attempting. 12:34:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:34:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:28 INFO - (blobuploader) - INFO - Done attempting. 12:34:28 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:28 INFO - Return code: 0 12:34:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cf848378692defb4937350d895867189c8624d9c770544399185e56859b88ebdc54af59feb2eb951a0119086f246873e4973118708078159a55915ce5bf710e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/516563e09699a37e88f9627d9b8ad9b4cb2015a2626881da8c1b6de0013ca096874b1f98580318546c6e9b6542245d7b26a462f1adb0cf27bd816717fe8d16be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e17926a75ae490e0efae0181b0b619fad6588249d20a21e2815590cbae0ce63e42721e024de6ce99c5891a199cb018b7de3b24382a2682bd15114b92cd95a19"} 12:34:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:28 INFO - Contents: 12:34:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cf848378692defb4937350d895867189c8624d9c770544399185e56859b88ebdc54af59feb2eb951a0119086f246873e4973118708078159a55915ce5bf710e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/516563e09699a37e88f9627d9b8ad9b4cb2015a2626881da8c1b6de0013ca096874b1f98580318546c6e9b6542245d7b26a462f1adb0cf27bd816717fe8d16be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e17926a75ae490e0efae0181b0b619fad6588249d20a21e2815590cbae0ce63e42721e024de6ce99c5891a199cb018b7de3b24382a2682bd15114b92cd95a19"} 12:34:28 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:28 INFO - Copying logs to upload dir... 12:34:28 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.210624 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-26 12:34:28.618926) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 12:34:28.622424) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872 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/cf848378692defb4937350d895867189c8624d9c770544399185e56859b88ebdc54af59feb2eb951a0119086f246873e4973118708078159a55915ce5bf710e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/516563e09699a37e88f9627d9b8ad9b4cb2015a2626881da8c1b6de0013ca096874b1f98580318546c6e9b6542245d7b26a462f1adb0cf27bd816717fe8d16be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e17926a75ae490e0efae0181b0b619fad6588249d20a21e2815590cbae0ce63e42721e024de6ce99c5891a199cb018b7de3b24382a2682bd15114b92cd95a19"} build_url:https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/Q_0ioCE9S3qhURWjAeHZMA/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/cf848378692defb4937350d895867189c8624d9c770544399185e56859b88ebdc54af59feb2eb951a0119086f246873e4973118708078159a55915ce5bf710e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/516563e09699a37e88f9627d9b8ad9b4cb2015a2626881da8c1b6de0013ca096874b1f98580318546c6e9b6542245d7b26a462f1adb0cf27bd816717fe8d16be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e17926a75ae490e0efae0181b0b619fad6588249d20a21e2815590cbae0ce63e42721e024de6ce99c5891a199cb018b7de3b24382a2682bd15114b92cd95a19"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 12:34:28.647594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 12:34:28.647921) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472236682.287236-1002196872 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 12:34:28.730425) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 12:34:28.730765) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 12:34:28.731078) ========= ========= Total master_lag: 0.18 =========