builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1471324229.16 results: success (0) buildid: 20160815203026 builduid: b9e84d86e2d64467a7481a6eb6c634ec revision: c9d9d817ae305b37464b7e5b222c1113c0646fd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.160183) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.160745) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.161034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.204955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.205375) ========= 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=ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-15 22:10:29-- 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% 225M=0s 2016-08-15 22:10:29 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105823 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.334684) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.334994) ========= 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=ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017187 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.392224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:29.392587) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c9d9d817ae305b37464b7e5b222c1113c0646fd0 --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 c9d9d817ae305b37464b7e5b222c1113c0646fd0 --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=ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-15 22:10:29,585 truncating revision to first 12 chars 2016-08-15 22:10:29,585 Setting DEBUG logging. 2016-08-15 22:10:29,585 attempt 1/10 2016-08-15 22:10:29,585 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c9d9d817ae30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 22:10:29,945 unpacking tar archive at: fx-team-c9d9d817ae30/testing/mozharness/ program finished with exit code 0 elapsedTime=0.711904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:30.120331) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:30.120885) ========= script_repo_revision: c9d9d817ae305b37464b7e5b222c1113c0646fd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:30.121595) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:30.122079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 22:10:30.140605) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-15 22:10:30.140965) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:10:30 INFO - MultiFileLogger online at 20160815 22:10:30 in /builds/slave/test 22:10:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:10:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:10:30 INFO - {'append_to_log': False, 22:10:30 INFO - 'base_work_dir': '/builds/slave/test', 22:10:30 INFO - 'blob_upload_branch': 'Fx-Team', 22:10:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:10:30 INFO - 'branch': 'Fx-Team', 22:10:30 INFO - 'buildbot_json_path': 'buildprops.json', 22:10:30 INFO - 'config_files': ('talos/linux_config.py',), 22:10:30 INFO - 'default_actions': ('clobber', 22:10:30 INFO - 'read-buildbot-config', 22:10:30 INFO - 'download-and-extract', 22:10:30 INFO - 'populate-webroot', 22:10:30 INFO - 'create-virtualenv', 22:10:30 INFO - 'install', 22:10:30 INFO - 'run-tests'), 22:10:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:10:30 INFO - 'download_minidump_stackwalk': True, 22:10:30 INFO - 'download_symbols': 'ondemand', 22:10:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:10:30 INFO - 'tooltool.py': '/tools/tooltool.py', 22:10:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:10:30 INFO - '/tools/misc-python/virtualenv.py')}, 22:10:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:10:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:10:30 INFO - 'installer_path': 'installer.exe', 22:10:30 INFO - 'log_level': 'info', 22:10:30 INFO - 'log_name': 'talos', 22:10:30 INFO - 'log_to_console': True, 22:10:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:10:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:10:30 INFO - 'opt_config_files': (), 22:10:30 INFO - 'pip_index': False, 22:10:30 INFO - 'sps_profile': False, 22:10:30 INFO - 'sps_profile_interval': 0, 22:10:30 INFO - 'suite': 'dromaeojs', 22:10:30 INFO - 'system_bits': '32', 22:10:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:10:30 INFO - 'title': 'talos-linux64-ix-035', 22:10:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:10:30 INFO - 'use_talos_json': True, 22:10:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:10:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:10:30 INFO - 'work_dir': 'build'} 22:10:30 INFO - [mozharness: 2016-08-16 05:10:30.437008Z] Running clobber step. 22:10:30 INFO - Running pre-action listener: _resource_record_pre_action 22:10:30 INFO - Running main action method: clobber 22:10:30 INFO - rmtree: /builds/slave/test/build 22:10:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:10:30 INFO - Running post-action listener: _resource_record_post_action 22:10:30 INFO - [mozharness: 2016-08-16 05:10:30.904281Z] Finished clobber step (success) 22:10:30 INFO - [mozharness: 2016-08-16 05:10:30.904408Z] Running read-buildbot-config step. 22:10:30 INFO - Running pre-action listener: _resource_record_pre_action 22:10:30 INFO - Running main action method: read_buildbot_config 22:10:30 INFO - Using buildbot properties: 22:10:30 INFO - { 22:10:30 INFO - "project": "", 22:10:30 INFO - "product": "firefox", 22:10:30 INFO - "script_repo_revision": "production", 22:10:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:10:30 INFO - "repository": "", 22:10:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:10:30 INFO - "buildid": "20160815203026", 22:10:30 INFO - "pgo_build": "True", 22:10:30 INFO - "basedir": "/builds/slave/test", 22:10:30 INFO - "buildnumber": 1462, 22:10:30 INFO - "slavename": "talos-linux64-ix-035", 22:10:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:10:30 INFO - "platform": "ubuntu64_hw", 22:10:30 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:10:30 INFO - "branch": "fx-team", 22:10:30 INFO - "repo_path": "integration/fx-team", 22:10:30 INFO - "revision": "c9d9d817ae305b37464b7e5b222c1113c0646fd0", 22:10:30 INFO - "stage_platform": "linux64-pgo", 22:10:30 INFO - "builduid": "b9e84d86e2d64467a7481a6eb6c634ec", 22:10:30 INFO - "slavebuilddir": "test" 22:10:30 INFO - } 22:10:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:10:30 INFO - Running post-action listener: _resource_record_post_action 22:10:30 INFO - [mozharness: 2016-08-16 05:10:30.906836Z] Finished read-buildbot-config step (success) 22:10:30 INFO - [mozharness: 2016-08-16 05:10:30.906925Z] Running download-and-extract step. 22:10:30 INFO - Running pre-action listener: _resource_record_pre_action 22:10:30 INFO - Running main action method: download_and_extract 22:10:30 INFO - mkdir: /builds/slave/test/build/tests 22:10:30 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')]} 22:10:30 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:10:30 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:31 INFO - Downloaded 1361 bytes. 22:10:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:10:31 INFO - Using the following test package requirements: 22:10:31 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:10:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:10:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'jsshell-linux-x86_64.zip'], 22:10:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:10:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:10:31 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:10:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:10:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:10:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:10:31 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')]} 22:10:31 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:10:31 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:32 INFO - Downloaded 21837625 bytes. 22:10:32 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 22:10:36 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 22:10:36 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')]} 22:10:36 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:10:36 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:37 INFO - Downloaded 21837625 bytes. 22:10:37 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 22:10:42 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')]} 22:10:42 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:42 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:43 INFO - Downloaded 14413719 bytes. 22:10:43 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 22:10:43 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')]} 22:10:43 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:10:43 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:10:45 INFO - Downloaded 59436343 bytes. 22:10:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:10:45 INFO - mkdir: /builds/slave/test/properties 22:10:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:10:45 INFO - Writing to file /builds/slave/test/properties/build_url 22:10:45 INFO - Contents: 22:10:45 INFO - build_url:https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:10:45 INFO - Running post-action listener: _resource_record_post_action 22:10:45 INFO - Running post-action listener: set_extra_try_arguments 22:10:45 INFO - [mozharness: 2016-08-16 05:10:45.834694Z] Finished download-and-extract step (success) 22:10:45 INFO - [mozharness: 2016-08-16 05:10:45.834919Z] Running populate-webroot step. 22:10:45 INFO - Running pre-action listener: _resource_record_pre_action 22:10:45 INFO - Running main action method: populate_webroot 22:10:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:10:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:10:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:10:45 INFO - '--tppagecycles', 22:10:45 INFO - '7'], 22:10:45 INFO - 'tests': ['tsvgm']}}, 22:10:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:10:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:10:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:10:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:10:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['damp', 'tps']}, 22:10:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'tests': ['damp', 'tps']}, 22:10:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['dromaeo_dom']}, 22:10:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:10:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['basic_compositor_video']}, 22:10:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:10:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['a11yr', 22:10:45 INFO - 'ts_paint', 22:10:45 INFO - 'tpaint', 22:10:45 INFO - 'sessionrestore', 22:10:45 INFO - 'sessionrestore_no_auto_restore', 22:10:45 INFO - 'tabpaint']}, 22:10:45 INFO - 'other-e10s': {'tests': ['a11yr', 22:10:45 INFO - 'ts_paint', 22:10:45 INFO - 'tpaint', 22:10:45 INFO - 'sessionrestore', 22:10:45 INFO - 'sessionrestore_no_auto_restore', 22:10:45 INFO - 'tabpaint']}, 22:10:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:10:45 INFO - 'ts_paint', 22:10:45 INFO - 'tpaint', 22:10:45 INFO - 'sessionrestore', 22:10:45 INFO - 'sessionrestore_no_auto_restore', 22:10:45 INFO - 'tabpaint']}, 22:10:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:10:45 INFO - 'ts_paint', 22:10:45 INFO - 'tpaint', 22:10:45 INFO - 'sessionrestore', 22:10:45 INFO - 'sessionrestore_no_auto_restore', 22:10:45 INFO - 'tabpaint']}, 22:10:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['a11yr', 22:10:45 INFO - 'ts_paint', 22:10:45 INFO - 'tpaint', 22:10:45 INFO - 'sessionrestore', 22:10:45 INFO - 'sessionrestore_no_auto_restore', 22:10:45 INFO - 'tabpaint']}, 22:10:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['a11yr', 22:10:45 INFO - 'ts_paint', 22:10:45 INFO - 'tpaint', 22:10:45 INFO - 'sessionrestore', 22:10:45 INFO - 'sessionrestore_no_auto_restore', 22:10:45 INFO - 'tabpaint']}, 22:10:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['tsvgx', 22:10:45 INFO - 'tsvgr_opacity', 22:10:45 INFO - 'tart', 22:10:45 INFO - 'tscrollx', 22:10:45 INFO - 'cart']}, 22:10:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:10:45 INFO - 'tsvgr_opacity', 22:10:45 INFO - 'tart', 22:10:45 INFO - 'tscrollx', 22:10:45 INFO - 'cart']}, 22:10:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'talos_options': ['--disable-e10s'], 22:10:45 INFO - 'tests': ['tp5o']}, 22:10:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'tests': ['tp5o']}, 22:10:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'talos_options': ['--disable-e10s', 22:10:45 INFO - '--xperf_path', 22:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:45 INFO - 'tests': ['tp5n']}, 22:10:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:45 INFO - 'talos_options': ['--xperf_path', 22:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:45 INFO - 'tests': ['tp5n']}}, 22:10:45 INFO - 'talos.zip': {'path': '', 22:10:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:10:45 INFO - Running post-action listener: _resource_record_post_action 22:10:45 INFO - [mozharness: 2016-08-16 05:10:45.854228Z] Finished populate-webroot step (success) 22:10:45 INFO - [mozharness: 2016-08-16 05:10:45.854317Z] Running create-virtualenv step. 22:10:45 INFO - Running pre-action listener: _resource_record_pre_action 22:10:45 INFO - Running main action method: create_virtualenv 22:10:45 INFO - Creating virtualenv /builds/slave/test/build/venv 22:10:45 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 22:10:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:10:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:10:45 INFO - Using real prefix '/usr' 22:10:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:10:46 INFO - Installing distribute.............................................................................................................................................................................................done. 22:10:48 INFO - Installing pip.................done. 22:10:48 INFO - Return code: 0 22:10:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:10:48 INFO - Using env: {'DISPLAY': ':0', 22:10:48 INFO - 'HOME': '/home/cltbld', 22:10:48 INFO - 'LANG': 'en_US.UTF-8', 22:10:48 INFO - 'LANGUAGE': 'en_US:en', 22:10:48 INFO - 'LOGNAME': 'cltbld', 22:10:48 INFO - 'MAIL': '/var/mail/cltbld', 22:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:48 INFO - 'MOZ_NO_REMOTE': '1', 22:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:48 INFO - 'NO_EM_RESTART': '1', 22:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:48 INFO - 'PWD': '/builds/slave/test', 22:10:48 INFO - 'SHELL': '/bin/bash', 22:10:48 INFO - 'SHLVL': '1', 22:10:48 INFO - 'TERM': 'linux', 22:10:48 INFO - 'TMOUT': '86400', 22:10:48 INFO - 'USER': 'cltbld', 22:10:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:48 INFO - '_': '/tools/buildbot/bin/python'} 22:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:48 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 22:10:48 INFO - Cleaning up... 22:10:48 INFO - Return code: 0 22:10:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:10:48 INFO - Using env: {'DISPLAY': ':0', 22:10:48 INFO - 'HOME': '/home/cltbld', 22:10:48 INFO - 'LANG': 'en_US.UTF-8', 22:10:48 INFO - 'LANGUAGE': 'en_US:en', 22:10:48 INFO - 'LOGNAME': 'cltbld', 22:10:48 INFO - 'MAIL': '/var/mail/cltbld', 22:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:48 INFO - 'MOZ_NO_REMOTE': '1', 22:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:48 INFO - 'NO_EM_RESTART': '1', 22:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:48 INFO - 'PWD': '/builds/slave/test', 22:10:48 INFO - 'SHELL': '/bin/bash', 22:10:48 INFO - 'SHLVL': '1', 22:10:48 INFO - 'TERM': 'linux', 22:10:48 INFO - 'TMOUT': '86400', 22:10:48 INFO - 'USER': 'cltbld', 22:10:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:48 INFO - '_': '/tools/buildbot/bin/python'} 22:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:48 INFO - Downloading/unpacking psutil>=3.1.1 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:51 INFO - Installing collected packages: psutil 22:10:51 INFO - Running setup.py install for psutil 22:10:51 INFO - building 'psutil._psutil_linux' extension 22:10:51 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 22:10:51 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 22:10:51 INFO - building 'psutil._psutil_posix' extension 22:10:51 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 22:10:51 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 22:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:51 INFO - Successfully installed psutil 22:10:51 INFO - Cleaning up... 22:10:51 INFO - Return code: 0 22:10:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:10:51 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')]} 22:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:51 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')]} 22:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:51 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:10:51 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 22:10:51 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 22:10:51 INFO - Using env: {'DISPLAY': ':0', 22:10:51 INFO - 'HOME': '/home/cltbld', 22:10:51 INFO - 'LANG': 'en_US.UTF-8', 22:10:51 INFO - 'LANGUAGE': 'en_US:en', 22:10:51 INFO - 'LOGNAME': 'cltbld', 22:10:51 INFO - 'MAIL': '/var/mail/cltbld', 22:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:51 INFO - 'MOZ_NO_REMOTE': '1', 22:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:51 INFO - 'NO_EM_RESTART': '1', 22:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:51 INFO - 'PWD': '/builds/slave/test', 22:10:51 INFO - 'SHELL': '/bin/bash', 22:10:51 INFO - 'SHLVL': '1', 22:10:51 INFO - 'TERM': 'linux', 22:10:51 INFO - 'TMOUT': '86400', 22:10:51 INFO - 'USER': 'cltbld', 22:10:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:51 INFO - '_': '/tools/buildbot/bin/python'} 22:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:10:54 INFO - Installing collected packages: mozsystemmonitor 22:10:54 INFO - Running setup.py install for mozsystemmonitor 22:10:54 INFO - Successfully installed mozsystemmonitor 22:10:54 INFO - Cleaning up... 22:10:54 INFO - Return code: 0 22:10:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:10:54 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')]} 22:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:54 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')]} 22:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:54 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:10:54 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 22:10:54 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 22:10:54 INFO - Using env: {'DISPLAY': ':0', 22:10:54 INFO - 'HOME': '/home/cltbld', 22:10:54 INFO - 'LANG': 'en_US.UTF-8', 22:10:54 INFO - 'LANGUAGE': 'en_US:en', 22:10:54 INFO - 'LOGNAME': 'cltbld', 22:10:54 INFO - 'MAIL': '/var/mail/cltbld', 22:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:54 INFO - 'MOZ_NO_REMOTE': '1', 22:10:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:54 INFO - 'NO_EM_RESTART': '1', 22:10:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:54 INFO - 'PWD': '/builds/slave/test', 22:10:54 INFO - 'SHELL': '/bin/bash', 22:10:54 INFO - 'SHLVL': '1', 22:10:54 INFO - 'TERM': 'linux', 22:10:54 INFO - 'TMOUT': '86400', 22:10:54 INFO - 'USER': 'cltbld', 22:10:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:54 INFO - '_': '/tools/buildbot/bin/python'} 22:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:55 INFO - Downloading/unpacking jsonschema==2.5.1 22:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:10:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:57 INFO - Downloading functools32-3.2.3-2.tar.gz 22:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:10:57 INFO - warning: no files found matching '*.txt' 22:10:57 INFO - no previously-included directories found matching 'build' 22:10:57 INFO - no previously-included directories found matching 'dist' 22:10:57 INFO - no previously-included directories found matching '.git*' 22:10:57 INFO - Installing collected packages: jsonschema, functools32 22:10:57 INFO - Running setup.py install for functools32 22:10:57 INFO - warning: no files found matching '*.txt' 22:10:57 INFO - no previously-included directories found matching 'build' 22:10:57 INFO - no previously-included directories found matching 'dist' 22:10:57 INFO - no previously-included directories found matching '.git*' 22:10:57 INFO - Successfully installed jsonschema functools32 22:10:57 INFO - Cleaning up... 22:10:58 INFO - Return code: 0 22:10:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:58 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:10:58 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 22:10:58 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 22:10:58 INFO - Using env: {'DISPLAY': ':0', 22:10:58 INFO - 'HOME': '/home/cltbld', 22:10:58 INFO - 'LANG': 'en_US.UTF-8', 22:10:58 INFO - 'LANGUAGE': 'en_US:en', 22:10:58 INFO - 'LOGNAME': 'cltbld', 22:10:58 INFO - 'MAIL': '/var/mail/cltbld', 22:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:58 INFO - 'MOZ_NO_REMOTE': '1', 22:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:58 INFO - 'NO_EM_RESTART': '1', 22:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:58 INFO - 'PWD': '/builds/slave/test', 22:10:58 INFO - 'SHELL': '/bin/bash', 22:10:58 INFO - 'SHLVL': '1', 22:10:58 INFO - 'TERM': 'linux', 22:10:58 INFO - 'TMOUT': '86400', 22:10:58 INFO - 'USER': 'cltbld', 22:10:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:58 INFO - '_': '/tools/buildbot/bin/python'} 22:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:10:58 INFO - Cleaning up... 22:10:58 INFO - Return code: 0 22:10:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:58 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:10:58 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 22:10:58 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 22:10:58 INFO - Using env: {'DISPLAY': ':0', 22:10:58 INFO - 'HOME': '/home/cltbld', 22:10:58 INFO - 'LANG': 'en_US.UTF-8', 22:10:58 INFO - 'LANGUAGE': 'en_US:en', 22:10:58 INFO - 'LOGNAME': 'cltbld', 22:10:58 INFO - 'MAIL': '/var/mail/cltbld', 22:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:58 INFO - 'MOZ_NO_REMOTE': '1', 22:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:58 INFO - 'NO_EM_RESTART': '1', 22:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:58 INFO - 'PWD': '/builds/slave/test', 22:10:58 INFO - 'SHELL': '/bin/bash', 22:10:58 INFO - 'SHLVL': '1', 22:10:58 INFO - 'TERM': 'linux', 22:10:58 INFO - 'TMOUT': '86400', 22:10:58 INFO - 'USER': 'cltbld', 22:10:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:58 INFO - '_': '/tools/buildbot/bin/python'} 22:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:58 INFO - Downloading/unpacking blobuploader==1.2.4 22:10:58 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 22:10:58 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 22:10:58 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 22:10:58 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 22:10:58 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 22:10:58 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 22:11:00 INFO - Downloading blobuploader-1.2.4.tar.gz 22:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:11:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:11:00 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 22:11:00 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 22:11:00 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 22:11:00 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 22:11:00 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 22:11:00 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 22:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:11:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:01 INFO - Downloading docopt-0.6.1.tar.gz 22:11:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:11:01 INFO - Installing collected packages: blobuploader, requests, docopt 22:11:01 INFO - Running setup.py install for blobuploader 22:11:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:11:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:11:01 INFO - Running setup.py install for requests 22:11:01 INFO - Running setup.py install for docopt 22:11:01 INFO - Successfully installed blobuploader requests docopt 22:11:01 INFO - Cleaning up... 22:11:02 INFO - Return code: 0 22:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:02 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:11:02 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 22:11:02 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 22:11:02 INFO - Using env: {'DISPLAY': ':0', 22:11:02 INFO - 'HOME': '/home/cltbld', 22:11:02 INFO - 'LANG': 'en_US.UTF-8', 22:11:02 INFO - 'LANGUAGE': 'en_US:en', 22:11:02 INFO - 'LOGNAME': 'cltbld', 22:11:02 INFO - 'MAIL': '/var/mail/cltbld', 22:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:02 INFO - 'MOZ_NO_REMOTE': '1', 22:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:02 INFO - 'NO_EM_RESTART': '1', 22:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:02 INFO - 'PWD': '/builds/slave/test', 22:11:02 INFO - 'SHELL': '/bin/bash', 22:11:02 INFO - 'SHLVL': '1', 22:11:02 INFO - 'TERM': 'linux', 22:11:02 INFO - 'TMOUT': '86400', 22:11:02 INFO - 'USER': 'cltbld', 22:11:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:02 INFO - '_': '/tools/buildbot/bin/python'} 22:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:02 INFO - Running setup.py (path:/tmp/pip-siHSqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:02 INFO - Running setup.py (path:/tmp/pip-8B0ysi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:02 INFO - Running setup.py (path:/tmp/pip-x5czg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:02 INFO - Running setup.py (path:/tmp/pip-aopyDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:02 INFO - Running setup.py (path:/tmp/pip-hrckoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:02 INFO - Running setup.py (path:/tmp/pip-JB8y5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:02 INFO - Running setup.py (path:/tmp/pip-vVDXmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:02 INFO - Running setup.py (path:/tmp/pip-scdYY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:02 INFO - Running setup.py (path:/tmp/pip-zGX9fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:02 INFO - Running setup.py (path:/tmp/pip-WBMSUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:02 INFO - Running setup.py (path:/tmp/pip-Vk6Vd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:03 INFO - Running setup.py (path:/tmp/pip-HBpPZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:03 INFO - Running setup.py (path:/tmp/pip-IFhdcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:03 INFO - Running setup.py (path:/tmp/pip-hHB1D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:03 INFO - Running setup.py (path:/tmp/pip-uTV_qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:03 INFO - Running setup.py (path:/tmp/pip-d_Deeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:03 INFO - Running setup.py (path:/tmp/pip-ZGW6Jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:11:03 INFO - Running setup.py install for manifestparser 22:11:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:11:03 INFO - Running setup.py install for mozcrash 22:11:03 INFO - Running setup.py install for mozdebug 22:11:03 INFO - Running setup.py install for mozdevice 22:11:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:11:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:11:03 INFO - Running setup.py install for mozfile 22:11:03 INFO - Running setup.py install for mozhttpd 22:11:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for mozinfo 22:11:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for mozInstall 22:11:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for mozleak 22:11:04 INFO - Running setup.py install for mozlog 22:11:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for moznetwork 22:11:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for mozprocess 22:11:04 INFO - Running setup.py install for mozprofile 22:11:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for mozrunner 22:11:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:11:04 INFO - Running setup.py install for mozscreenshot 22:11:05 INFO - Running setup.py install for moztest 22:11:05 INFO - Running setup.py install for mozversion 22:11:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:11:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:11:05 INFO - Cleaning up... 22:11:05 INFO - Return code: 0 22:11:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:05 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:11:05 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 22:11:05 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 22:11:05 INFO - Using env: {'DISPLAY': ':0', 22:11:05 INFO - 'HOME': '/home/cltbld', 22:11:05 INFO - 'LANG': 'en_US.UTF-8', 22:11:05 INFO - 'LANGUAGE': 'en_US:en', 22:11:05 INFO - 'LOGNAME': 'cltbld', 22:11:05 INFO - 'MAIL': '/var/mail/cltbld', 22:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:05 INFO - 'MOZ_NO_REMOTE': '1', 22:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:05 INFO - 'NO_EM_RESTART': '1', 22:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:05 INFO - 'PWD': '/builds/slave/test', 22:11:05 INFO - 'SHELL': '/bin/bash', 22:11:05 INFO - 'SHLVL': '1', 22:11:05 INFO - 'TERM': 'linux', 22:11:05 INFO - 'TMOUT': '86400', 22:11:05 INFO - 'USER': 'cltbld', 22:11:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:05 INFO - '_': '/tools/buildbot/bin/python'} 22:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:05 INFO - Running setup.py (path:/tmp/pip-g3sEdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:05 INFO - Running setup.py (path:/tmp/pip-k6QGm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:05 INFO - Running setup.py (path:/tmp/pip-hsjPr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:05 INFO - Running setup.py (path:/tmp/pip-yOR3So-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:05 INFO - Running setup.py (path:/tmp/pip-w13uc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:05 INFO - Running setup.py (path:/tmp/pip-4KMce0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:05 INFO - Running setup.py (path:/tmp/pip-BrPeLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:05 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)) 22:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:06 INFO - Running setup.py (path:/tmp/pip-zSzciO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:06 INFO - Running setup.py (path:/tmp/pip-sWENwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:06 INFO - Running setup.py (path:/tmp/pip-bdfaRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:06 INFO - Running setup.py (path:/tmp/pip-JUskcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:06 INFO - Running setup.py (path:/tmp/pip-GX9j8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:06 INFO - Running setup.py (path:/tmp/pip-q_wcab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:06 INFO - Running setup.py (path:/tmp/pip-sLD9bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:06 INFO - Running setup.py (path:/tmp/pip-xhC80m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:06 INFO - Running setup.py (path:/tmp/pip-oDZo89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:06 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)) 22:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:06 INFO - Running setup.py (path:/tmp/pip-QVMTtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:06 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)) 22:11:06 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)) 22:11:06 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)) 22:11:06 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)) 22:11:06 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)) 22:11:06 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)) 22:11:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:06 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 22:11:06 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 22:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:08 INFO - Downloading blessings-1.6.tar.gz 22:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:11:08 INFO - Installing collected packages: blessings 22:11:08 INFO - Running setup.py install for blessings 22:11:08 INFO - Successfully installed blessings 22:11:08 INFO - Cleaning up... 22:11:09 INFO - Return code: 0 22:11:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:09 INFO - Reading from file tmpfile_stdout 22:11:09 INFO - Current package versions: 22:11:09 INFO - argparse == 1.2.1 22:11:09 INFO - blessings == 1.6 22:11:09 INFO - blobuploader == 1.2.4 22:11:09 INFO - docopt == 0.6.1 22:11:09 INFO - functools32 == 3.2.3-2 22:11:09 INFO - jsonschema == 2.5.1 22:11:09 INFO - manifestparser == 1.1 22:11:09 INFO - mozInstall == 1.12 22:11:09 INFO - mozcrash == 0.17 22:11:09 INFO - mozdebug == 0.1 22:11:09 INFO - mozdevice == 0.48 22:11:09 INFO - mozfile == 1.2 22:11:09 INFO - mozhttpd == 0.7 22:11:09 INFO - mozinfo == 0.9 22:11:09 INFO - mozleak == 0.1 22:11:09 INFO - mozlog == 3.3 22:11:09 INFO - moznetwork == 0.27 22:11:09 INFO - mozprocess == 0.23 22:11:09 INFO - mozprofile == 0.28 22:11:09 INFO - mozrunner == 6.12 22:11:09 INFO - mozscreenshot == 0.1 22:11:09 INFO - mozsystemmonitor == 0.3 22:11:09 INFO - moztest == 0.7 22:11:09 INFO - mozversion == 1.4 22:11:09 INFO - psutil == 3.1.1 22:11:09 INFO - requests == 1.2.3 22:11:09 INFO - wsgiref == 0.1.2 22:11:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:09 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 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:11:09 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 22:11:09 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 22:11:09 INFO - Using env: {'DISPLAY': ':0', 22:11:09 INFO - 'HOME': '/home/cltbld', 22:11:09 INFO - 'LANG': 'en_US.UTF-8', 22:11:09 INFO - 'LANGUAGE': 'en_US:en', 22:11:09 INFO - 'LOGNAME': 'cltbld', 22:11:09 INFO - 'MAIL': '/var/mail/cltbld', 22:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:09 INFO - 'MOZ_NO_REMOTE': '1', 22:11:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:09 INFO - 'NO_EM_RESTART': '1', 22:11:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:09 INFO - 'PWD': '/builds/slave/test', 22:11:09 INFO - 'SHELL': '/bin/bash', 22:11:09 INFO - 'SHLVL': '1', 22:11:09 INFO - 'TERM': 'linux', 22:11:09 INFO - 'TMOUT': '86400', 22:11:09 INFO - 'USER': 'cltbld', 22:11:09 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:09 INFO - '_': '/tools/buildbot/bin/python'} 22:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 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)) 22:11:09 INFO - Cleaning up... 22:11:09 INFO - Return code: 0 22:11:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7580f55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dc850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, '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': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', '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 22:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:11:09 INFO - Using env: {'DISPLAY': ':0', 22:11:09 INFO - 'HOME': '/home/cltbld', 22:11:09 INFO - 'LANG': 'en_US.UTF-8', 22:11:09 INFO - 'LANGUAGE': 'en_US:en', 22:11:09 INFO - 'LOGNAME': 'cltbld', 22:11:09 INFO - 'MAIL': '/var/mail/cltbld', 22:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:09 INFO - 'MOZ_NO_REMOTE': '1', 22:11:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:09 INFO - 'NO_EM_RESTART': '1', 22:11:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:09 INFO - 'PWD': '/builds/slave/test', 22:11:09 INFO - 'SHELL': '/bin/bash', 22:11:09 INFO - 'SHLVL': '1', 22:11:09 INFO - 'TERM': 'linux', 22:11:09 INFO - 'TMOUT': '86400', 22:11:09 INFO - 'USER': 'cltbld', 22:11:09 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:09 INFO - '_': '/tools/buildbot/bin/python'} 22:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:11:09 INFO - Cleaning up... 22:11:09 INFO - Return code: 0 22:11:09 INFO - Running post-action listener: _resource_record_post_action 22:11:09 INFO - Running post-action listener: _start_resource_monitoring 22:11:09 INFO - Starting resource monitoring. 22:11:09 INFO - [mozharness: 2016-08-16 05:11:09.704081Z] Finished create-virtualenv step (success) 22:11:09 INFO - [mozharness: 2016-08-16 05:11:09.704622Z] Running install step. 22:11:09 INFO - Running pre-action listener: _resource_record_pre_action 22:11:09 INFO - Running main action method: install 22:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:09 INFO - Reading from file tmpfile_stdout 22:11:09 INFO - Detecting whether we're running mozinstall >=1.0... 22:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:11:09 INFO - Reading from file tmpfile_stdout 22:11:09 INFO - Output received: 22:11:09 INFO - Usage: mozinstall [options] installer 22:11:09 INFO - Options: 22:11:09 INFO - -h, --help show this help message and exit 22:11:09 INFO - -d DEST, --destination=DEST 22:11:09 INFO - Directory to install application into. [default: 22:11:09 INFO - "/builds/slave/test"] 22:11:09 INFO - --app=APP Application being installed. [default: firefox] 22:11:09 INFO - mkdir: /builds/slave/test/build/application 22:11:09 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'] 22:11:09 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 22:11:26 INFO - Reading from file tmpfile_stdout 22:11:26 INFO - Output received: 22:11:26 INFO - /builds/slave/test/build/application/firefox/firefox 22:11:26 INFO - Running post-action listener: _resource_record_post_action 22:11:26 INFO - [mozharness: 2016-08-16 05:11:26.895659Z] Finished install step (success) 22:11:26 INFO - [mozharness: 2016-08-16 05:11:26.895905Z] Running run-tests step. 22:11:26 INFO - Running pre-action listener: _resource_record_pre_action 22:11:26 INFO - Running main action method: run_tests 22:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:11:26 INFO - Python 2.7.3 22:11:26 INFO - Return code: 0 22:11:26 INFO - grabbing minidump binary from tooltool 22:11:26 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')]} 22:11:26 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 0x21d7370>, '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 0x21da8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dad80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:11:26 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 22:11:26 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 22:11:26 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 22:11:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:11:26 INFO - Return code: 0 22:11:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:11:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:11:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:11:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:11:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 22:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 22:11:26 INFO - Using env: {'DISPLAY': ':0', 22:11:26 INFO - 'HOME': '/home/cltbld', 22:11:26 INFO - 'LANG': 'en_US.UTF-8', 22:11:26 INFO - 'LANGUAGE': 'en_US:en', 22:11:26 INFO - 'LOGNAME': 'cltbld', 22:11:26 INFO - 'MAIL': '/var/mail/cltbld', 22:11:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:11:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:26 INFO - 'MOZ_NO_REMOTE': '1', 22:11:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:26 INFO - 'NO_EM_RESTART': '1', 22:11:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:26 INFO - 'PWD': '/builds/slave/test', 22:11:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:11:26 INFO - 'SHELL': '/bin/bash', 22:11:26 INFO - 'SHLVL': '1', 22:11:26 INFO - 'TERM': 'linux', 22:11:26 INFO - 'TMOUT': '86400', 22:11:26 INFO - 'USER': 'cltbld', 22:11:26 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460', 22:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:26 INFO - '_': '/tools/buildbot/bin/python'} 22:11:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 22:11:27 INFO - mozversion application_buildid: 20160815203026 22:11:27 INFO - mozversion application_changeset: c9d9d817ae305b37464b7e5b222c1113c0646fd0 22:11:27 INFO - mozversion application_display_name: Nightly 22:11:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:11:27 INFO - mozversion application_name: Firefox 22:11:27 INFO - mozversion application_remotingname: firefox 22:11:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:11:27 INFO - mozversion application_vendor: Mozilla 22:11:27 INFO - mozversion application_version: 51.0a1 22:11:27 INFO - mozversion platform_buildid: 20160815203026 22:11:27 INFO - mozversion platform_changeset: c9d9d817ae305b37464b7e5b222c1113c0646fd0 22:11:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:11:27 INFO - mozversion platform_version: 51.0a1 22:11:27 INFO - using testdate: 1471324287 22:11:27 INFO - actual date: 1471324287 22:11:27 INFO - starting webserver on 'localhost:44431' 22:11:27 INFO - SUITE-START | Running 2 tests 22:11:27 INFO - TEST-START | dromaeo_css 22:11:27 INFO - operating with platform_type : linux_ 22:11:27 INFO - Initialising browser for dromaeo_css test... 22:11:27 INFO - TEST-INFO | started process 3751 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoBWMaZ/profile http://localhost:44431/getInfo.html) 22:11:30 INFO - PROCESS | 3751 | __metrics Screen width/height:1600/1200 22:11:30 INFO - PROCESS | 3751 | colorDepth:24 22:11:30 INFO - PROCESS | 3751 | Browser inner width/height: 1024/697 22:11:30 INFO - PROCESS | 3751 | __metrics 22:11:31 INFO - PROCESS | 3751 | 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" 22:11:31 INFO - PROCESS | 3751 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:11:31 INFO - PROCESS | 3751 | console.error: PushService: 22:11:31 INFO - PROCESS | 3751 | stateChangeProcessEnqueue: Error transitioning state 22:11:31 INFO - PROCESS | 3751 | UnknownError 22:11:32 INFO - TEST-INFO | 3751: exit 0 22:11:32 INFO - Browser initialized. 22:11:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:11:32 INFO - TEST-INFO | started process 3832 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoBWMaZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:44 INFO - PROCESS | 3832 | RSS: Main: 156241920 22:11:44 INFO - PROCESS | 3832 | 22:12:35 INFO - PROCESS | 3832 | 1471324355714 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] 22:14:44 INFO - PROCESS | 3832 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44431/tests/dromaeo/cssquery-ext.html) 22:14:45 INFO - PROCESS | 3832 | RSS: Main: 233930752 22:14:45 INFO - PROCESS | 3832 | 22:17:48 INFO - PROCESS | 3832 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-ext.html (next: http://localhost:44431/tests/dromaeo/cssquery-jquery.html) 22:17:48 INFO - PROCESS | 3832 | RSS: Main: 244666368 22:17:48 INFO - PROCESS | 3832 | 22:20:50 INFO - PROCESS | 3832 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44431/tests/dromaeo/cssquery-mootools.html) 22:20:51 INFO - PROCESS | 3832 | RSS: Main: 251023360 22:20:51 INFO - PROCESS | 3832 | 22:23:53 INFO - PROCESS | 3832 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44431/tests/dromaeo/cssquery-prototype.html) 22:23:54 INFO - PROCESS | 3832 | RSS: Main: 237113344 22:23:54 INFO - PROCESS | 3832 | 22:26:56 INFO - PROCESS | 3832 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44431/tests/dromaeo/cssquery-yui.html) 22:26:57 INFO - PROCESS | 3832 | RSS: Main: 185196544 22:26:57 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-yui.html (next: http://localhost:44431/tests/dromaeo/cssquery-dojo.html) 22:30:00 INFO - PROCESS | 3832 | RSS: Main: 284094464 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | __start_tp_report 22:30:00 INFO - PROCESS | 3832 | _x_x_mozilla_page_load 22:30:00 INFO - PROCESS | 3832 | _x_x_mozilla_page_load_details 22:30:00 INFO - PROCESS | 3832 | |i|pagename|runs| 22:30:00 INFO - PROCESS | 3832 | |0;dojo.html;3033.651130120036;3069.7237248647616;3070.3398769264604;3091.799033062851;3096.876124955002;5096.668716533422;5166.793328266865;5276.709780962045;5362.4637536246355;5448.37343705474;5194.974025129878;5238.711870847102;5271.841844744664;5281.2342210379475;5352.2239275305055;4754.976225118879;4770.594499467532;4833.589144922686;4846.830360937368;4931.038447502739;4861.052094841507;4880.9993951240085;4884.096442158194;4900.411950565919;4916.237983112624;4615.284630882202;4678.345031695565;4716.962268300968;4723.173444647173;4828.5412885775795;4740.2889566564945;4753.3582966299455;4754.643401744883;4819.1325561398935;4864.100141473803;4779.831564041079;4783.760811959423;4791.7124972501415;4792.30511575823;4844.612431005511;5191.662541934762;5197.844064678028;5260.736963151827;5268.367795864482;5278.604104692164;5583.609147359686;5623.690697011662;5631.211630371751;5633.352164501094;5645.717714114319;5646.435356464321;5647.8588035299035;5650.604457687963;5658.632188907749;5666.57500687446;5098.362704661918;5251.291075704769;5327;5328.067588172094;5362.383325917492;5875.383084776123;5884.205632239678;5884.970575147183;5887;5890.911636325459;5621.156826476062;5633.887322253532;5653.858653533694;5656.85857853557;5657.830265092054;5479.589030822704;5483.177523371526;5536.058869992111;5536.723163841792;5733.111367738007;5542.39033706292;5553.0004599171725;5556.027695153332;5556.388797232301;5597;5517.806876759294;5519.889602207933;5564.805231816867;5565.777368905279;5568.860778480571;4222.894427639334;4256.148770245902;4260.190563792869;4302.870913872589;4359.629431498358;3857.61423857612;3887.8056097195026;3889.4943657324934;3890.5914878937306;3890.8054597270025;5364.302640656768;5369.4630536946;5386.057439947994;5389.299391079213;5430.049741295257;5711.457411545896;5717.142428635738;5717.8570535736935;5752.539796816244;5756.194132821408;5240.737963101829;5441.727913604304;5450.482204190595;5453.209284653703;5456.972715136399;4820.373351464357;4862.635302352338;4908.165611845995;4919.680220785637;4952.232403977389;5887.234659494238;5898.94101058984;5903.557233207526;5904.822855314347;5905.734241959122;5397.325334333208;5637.802676906289;5641.464060914206;5641.830745077655;5654.575906807088;5729.971350143389;5857.736401862012;5893.204417403767;5944.078667806497;5991.580589358878;5589.413111623384;5607;5607.691576963306;5610.85972850682;5614.157876318585;5470.452954704657;5687.090065589486;5699.829005129853;5702.743376547983;5705.172749951317;5390.730463476889;5435.701036443033;5440.945590544044;5457.808976685797;5502.422245664147;5396;5442.809501667422;5446.264754258283;5451.890962180813;5453.291072160752;5215.452377500308;5395.028894798974;5402.297701298806;5402.783888644411;5450.291462110059;5474.124140137559;5528.336599608073;5539.362973258127;5565.415631358649;5582.023145949362;5444.074507333684;5446.945530544803;5447.945520544902;5449.264349312792;5452.91820622683 22:30:00 INFO - PROCESS | 3832 | |1;ext.html;596.6897213449007;613.3437222172278;623.7130919776903;629.7071861584366;630.8864404407204;32341.029769106935;42513.362299565495;43308.783456082674;43404.56595434045;47652.76173619127;45941.31088033682;46343.304850427274;46385.768071159684;46396.30405543919;46434.76782616082;8900.332475064364;9049.2308153807;9053.864192037125;9055.728328150133;9083.182513573776;8995.595198216097;9036.819263614723;9044.3668943174;9047.819043619156;9089.272858171364;9122.954385228099;9189.632414703405;9194.908050919472;9198.816023679523;9200.861987070199;9092.136247056518;9133.08669133088;9134.81730365389;9143.862842057375;9145.314101442385;8894.59974301158;8998.910010899872;9007.369484136114;9009.234215091725;9010.23413009895;42135.578644213776;42371.15257694829;42494.57505424937;42691.359629605584;42698.57301426977;3396.1509622594353;3456.879009234665;3561.1631266652316;3981.3828856527284;4154.937675934863;33325.00024999254;33491;33577.83211083953;33581.32837343239;33922;4205.390218418343;4331.285337919239;4524.773761311954;4530.116627257686;4531.207038768235;350192.2490387557;350502;351233;351849;352096;56261.718691406284;56573.71713141408;56647.716761415926;56664;56675.43324566784;60538.39461605416;60715.69642151761;60806.39193608096;60828;60843;15569.143697096542;15770.369185232692;15776.68446631061;15777.526674199562;15787.842121578868;13043.673908152183;13070.215787052808;13109.60671179866;13111.868881311255;13112.213267203999;15814.841851581567;16716.164191790605;16746;16789.748153777702;16806.747898781527;5959.702014899238;6097.512199024068;6114.877702446015;6168.1056246843955;6264.843378915472;804.0913767442869;870.7561882672861;958.496789185683;1041.6614600254948;1176.5352685689104;31606.36787264242;33056.50415243773;33093.83453082719;33195.50206746901;33944.830275848464;1122.910167186623;1142.2118738070706;1178.9941050294865;1196.7965445874222;1202.1164444133556;16708.162102168753;17469.912650436665;17743.93536387821;17788.733169002477;17987.640247194984;559044;563443;563727;563750;564605;58630.413695863346;58691.41308586945;58837.705811471526;58966.70516647389;59183.704081479314;1271.497758385451;1370.3011464153353;1397.5667543061684;1433.78493226019;1437.4393986345333;31697.39262577011;33337.33325333528;34350.312993739484;34411.82794086014;34650;49644.25533616926;50609.24086138637;50693.746531267105;50938.490615093375;50942;328947;329861;329956;330164;330619;28207.294817629725;28424.431511369243;28472.15278472219;28664.713352866205;30118.698813012466;49079.26381104358;49274.50725492842;49462;49867.50132498628;49903.75048124736;40772.927612740306;41542.37686434646;42576.57423425726;42701.16743517397;43701.78149109234;42036.789816050725;42291.788541057096;42662.506812261425;43126.78436607797;43469;41527.792361038;42110.57889421067;42510;43179.78410108055;43195;34211.48682769711;36911.44632830456;37029.81485092557;37269;38029.42955855609;27381.315467112683;27550.44899102047;27672.308192295353;27891;27934.44131117407 22:30:00 INFO - PROCESS | 3832 | |2;jquery.html;382.6384067056633;397.5587098320863;404.4741835613701;406.228166483681;409.4400334724699;14391.568252952415;18422.631547369063;19732.9013354933;19741.605167896632;19845.20619175233;12393.62819115428;12546.623601291976;12553.497860085608;12608.05439592032;12613.24320540766;3950.8419663213435;5328.893422131565;5458.317710286215;5474.890502189954;5478.972605136969;5750.281214848144;5951.434613711712;6026.0659597762415;6124.387561243884;6131.202943617323;6000.6099603525845;6095.756169753205;6102.633841969474;6537.5423720339595;6588.4729221662155;6641.269460359356;6687.063811066455;6691.83270418241;6696.923953574359;6697.129373181479;5129;5394.163904594794;5427.782888684448;5476.972615136899;5485.259489968843;13839.238841863693;13944.16335019902;14177.574672759834;18496.797708148926;24068.51862962731;2313.5835549601234;2364.75170107138;2411.8311718179734;2418.8669623170717;2668.3996100877343;9438.95280523593;9849.458279794608;9864.654737084167;9971.451570163637;10526.157907367393;1864;1865.4776662534512;1879.492537015012;1909.6562618728624;1929.9614007719908;78177.60911195521;79485.60257198734;80113.59943200304;81083.59458202671;82600.58699706523;11307.208495405243;11309.886901131047;11365.659030229106;11476.368799716096;11563.132765042656;16029;16037.438689645807;16065.357385704441;16085.517434476984;16100.919495402448;8015.358771298283;8151.388645851584;8378.916210837813;8430.789230269169;8482.830343393098;7818.335441487424;7826.7651970440975;7883.211678832071;8015.679372825139;8030.558319292495;4583.197940360424;4716.457607375196;4856.68431551948;4917.65576409655;5093.592512598983;2009.5689005742304;2040.4082815983495;2059.5983783162437;2072.616565935307;2076.595063962513;892.2103938014774;1034.3276870034388;1076.1444651502075;1090.4658856304288;1092.4810714910354;19904.50238744043;20293.797062029487;20389.184432622827;20436.182552697726;20917.26789562358;973.5132433783108;1017.2878984710584;1043.045613263855;1080.5785743560018;1104.1608377632897;10543.525541350658;11632.592859250055;11644.41777911118;11712.472938717778;11716.648500544998;489198;490614;495195;496066;519217;86909;87551.56224218837;90851;91468.54265728629;92074.53962730143;987.3286165407592;992.7468495533593;1066.1737153705785;1066.2376400873259;1069.3797597393493;17322.740158897373;18046.458606241307;18081.457556273333;18354.541136471697;18733.157007934948;40352.19295614013;40385;42885;44473.33290000588;45410;59431.4341139533;59711.40288597059;60102;60851;61835;8334.999800023794;8675.826483470422;8867.68963086289;8923.286137109146;9141.817163656822;7477.214892436434;11312.6040588579;11869.694333623122;13867.653308667364;14048.648783780487;12131.636050918487;12257.570985015482;12314.50741970347;12317.630471085882;12361.690957726129;12054.879451205376;12163.2702037878;12171.573994910135;12203.281504339113;12595.496180152692;12323.44544495482;12494.625161244814;12633.93683031579;12675.30285834288;12758.553450629184;8576.914230857612;8659.220670139717;8732;8792;8955.865662014945;9565.760855978657;11774.3524106175;12068.224308408939;12154.270743755404;12263.448144833683 22:30:00 INFO - PROCESS | 3832 | |3;mootools.html;1337.0774165825574;1484.1391992644265;1518.2181176694;1627.462937230714;1771.1852547828007;8579.485230886152;8770.42992205507;8955.46267223967;9191.264698824094;9192.90807091929;8956.46261224327;9090.500022498776;9101.63593456261;9108.681196158135;9128.771780705485;2414.9396265093424;2431.3192306154306;2434.8417352872093;2447.3759191406166;2452.460458699092;2397.2927986244013;2397.7961873240797;2434.379233295507;2436.4517983453757;2439.256026911791;2368.289513146049;2413.263954493879;2418.516296740659;2418.854868707874;2421.2131057406327;2267.2178098555974;2268.7617800130997;2280.931572052841;2284.5316710074394;2340.5084932164095;2277.3054218463526;2302.8848557572223;2303.8041766449874;2304.9025386897843;2310.572544079334;8959.41763785352;9029.909700902972;9068.591913363915;9107.726768196899;9150.450972941644;5256.290400795881;5459.262999495057;5477.287952566141;5484.040292948718;5486.286782718261;3559.768615040014;3568.4468907319406;3603.297357015383;3625.873094441682;3631.2192878530977;1227.8035514317667;1240.4851986425622;1240.6340129661771;1271.7138643805163;1273.9552469597577;12735.872641273561;12785.872141278562;12843.42204600786;12845.100842940905;12864.163829351062;4395.1429471252795;4555.658325625592;4686.976565117221;4698.459677137173;4712.104700107037;10013.949930250303;10022.949885250673;10027.548760305805;10032.097111260022;10036.849447258297;5356.625036247426;5377.650452720561;5382.461753824587;5387;5391.056565101092;4376.431063961664;4786.8085276589245;4942.233953737176;4967.130752118364;5006.924896126561;3804.1250512382308;3930.665893399093;3970.463987361727;3973.3642617181113;4062.4921884764403;1182.7811854806716;1190.357207108152;1192.272713644676;1201.375284851872;1213.1750409721299;1015.2284263959444;1022.4683164754287;1026.4662375564665;1033.338663255517;1045.29442626227;6108.267007959073;6117.235345581802;6150.692465376714;6161.383861613893;6341.270753863365;1041.958321667125;1060.3690803971624;1095.6165342130191;1102.9062529685066;1109.3011402816173;4388.8713973722715;4400.669949753781;4456.665750068758;4506.301523263899;4705.1765940960195;11446.255993360404;11647.242929209575;11724.531018759324;11753.236039657395;11767.058635309155;10759;11669.299842009506;11669.299842009506;11672.066234701204;11678.357690327113;8403.621837017348;8595.871061934202;8646.308295336357;8653.653853845775;8655.394122411497;6234.345393733593;6271.184745982993;6295.685215739286;6340.461060809883;6560.8359791005605;10147.64483243083;10285.691429257135;10446.320989135682;10567.63013294531;10589.470526473491;10125.797484050425;10304.948475257575;10444.112250458797;10459.21555883327;10751.408672523086;7464.216257293123;7929.088154862265;8006.679732810622;8161.632726527202;8264.752057438287;9014.233790127913;9084.772880678036;9086.182243597843;9092.772680683036;9093;7758.185390533913;7885.605719714106;7896.921030789618;7948.682052718058;8399.28606068491;7891.171427000082;7955.323797477279;8047.83904321922;8083.878741818759;8220.671173153007;7031.43748500129;7229.313215244543;7331.670074846538;7346.449016323691;7355.595442250514;5285.4714528548375;5299.576033917355;5309.814156504503;5374.0864053111845;5468.3711373191;5241.109011467984;5328.653637513549;5361.624686271924;5363.973180134074;5546.389897111395 22:30:00 INFO - PROCESS | 3832 | |4;prototype.html;324.4030982991297;330.1432781930889;336.82148461315506;339.46703675229867;340.2701205913316;5201.982849947031;5212.76542555585;5243.528082472581;5252;5267.947320526793;4918.655694101414;4972.875678108049;5088.4402715701335;5103.183490641498;5195.818146364878;4072.3687828386646;4091.3044782387065;4091.89770255743;4092.693048021403;4112.321466957948;4103.384492326145;4106.384042393635;4106.815193316308;4111.60939710727;4113.609207125333;3834.3481608126685;3919.7256192066566;4053.9797301013455;4058.269511487931;4077.225327187825;4056.6146216109423;4062.410950412174;4073.226087043482;4073.999999999985;4076.675080598802;3636.4908213332715;3640.872569460056;3643.6720695137565;3644.4146796143764;3646.7264955128207;4833.564979151894;5024.874378140539;5041.143005689041;5065.366829146356;5074.325472594035;4587.3577699121815;4592.265237561974;4593.770311484413;4597.37935378723;4605.516420775837;5409.5672346212605;5414.512693857571;5419.918701219485;5420.810271640474;5446.346438427375;3796.468494410785;3875.875995961168;3912.804359782028;3915.647591716759;3923.8430462781453;5534.501894829485;5571.1086226203615;5594.552435805126;5595;5597.972010139923;3980.383040628707;3985.4619626350072;3991.820368083443;3991.880243592697;3995.682504147587;3950.328444164499;4037.8990525237105;4038.5961403859965;4051.6353528182603;4053.7162398631813;4544.931826022612;4573.771311434415;4617;4648.349231107648;4655.626590155912;5397.70312632809;5400.973814975142;5402.513773760302;5450.1931505274815;5465.344158700981;1390.5967269491937;1399.2094466626324;1400.7198560287984;1411.640031791907;1414.0808474491662;933.2440723014245;972.2367941166292;976.8144052629978;980.1962390839443;983.2134292565919;5195.376554813371;5288.0745869472685;5288.233206185082;5289.095925466829;5321.255024296602;5489.2589500417735;5518.558515318845;5559.277293951841;5560.749766260528;5582.692951887685;5127.487251274917;5146.948530514647;5198.090334191501;5200.193969934666;5223.839577055903;2869.7704183665255;2875.4680384129;2875.8705858236012;2878.56821476776;2887.321479452327;5551.278333816659;5706.686132262683;5710.885782284331;5718.513926316226;5722.399148089474;5694.316681998104;5709.857253568611;5711.400302968129;5712.0860662293835;5717.599768016199;5797.188393624896;5829.183914252008;5832.679202643811;5859.677717725566;5860.531157507475;5428.941356435457;5515.972420137874;5552.640127945688;5552.750126244248;5565.55295623149;5536;5536.280283563111;5544.390117087037;5544.445555444423;5544.473275038865;5611.775528978795;5799.391063938226;5819.5053420459735;5848.619839710575;5873.060310350324;3928.744631598937;3983.709367113863;3986.760794352348;4003.899902502461;4004.559498455226;5247.842564723064;5467.507924286833;5551.555875529867;5572.470615291703;5594.692291923983;5427.348718153847;5430.104032834538;5447.591430642638;5456.836294911159;5470.972645136749;5406.4052954175795;5430.321209848769;5450.400455949763;5469.781208751605;5470.8085217017215;5201.843944681666;5290.841274761763;5343.969291669141;5382.703951282717;5386.622936394415;5407.161889907071;5407.459254074561;5447.992121457384;5450.618456708151;5469.316335458067;5096.974515127401;5295.391030031596;5342.6260161788305;5343.7862485500145;5375.489328513785 22:30:00 INFO - PROCESS | 3832 | |5;yui.html;774.4424014709414;779.8791187365956;811.9106898241192;822.3996482567727;828.3125006244817;15196.696066078686;15624.984376015565;15722.764158537631;15731.134787586678;15773.684526309497;16429.178541072957;16940.49178524643;17154.57113572158;17157.399491017815;17219.483415497525;811.3103861717523;831.2477208126655;834.2200042959821;834.3075247544524;834.5743670727911;675.3449154320301;781.7615627233691;798.9560574168419;799.6081919859256;811.5698679699739;880.7886107334226;881.066069965835;882.6469412235125;883.0860059838069;883.196291374849;872.8734333521604;873.3711727556149;873.9431936924111;874.1215078845751;874.1695389380083;510.9821156259513;614.9907751383732;619.5260625621403;622.3682961793783;628.2021832272987;14741.410343586243;14763.92618036903;14784.408623655041;14785.55643330691;14790.18653974031;13528.729425411535;13741.175529468264;13976.161430314112;14176.716465670732;14580.70838583233;12956.48174073036;13062.216267024009;13157.144785588906;13220.933895330558;13627.318634068357;4557.863264102082;4697.88255293617;4754.66717329787;4805.879853003668;4826.879328016793;88141.55929220378;88184.55907720485;88393.55803220943;89484;90436;11234.438278086063;11407.771844563062;11474.655760327203;11708;11732.354720490284;21380.144794208056;21461.248856289956;21580.46048848791;21589.568208636054;21685.89157054236;8638.17937295956;8731.869021964676;8835.381523293436;8836.513991730517;8946.552672366355;4595.540445955377;4873.512648735169;4935.457099719028;4999.875003124878;5154.329937108152;3264.7877887937207;3382.52644629752;3387.458006718962;3390.372781035467;3394.813285269334;590.8257064166081;594.3165359836137;603.5473394953784;605.7032054293364;606.6057062909056;680.3876511139915;684.7911387027004;693.8889777635554;694.0560837261321;721.2102747491489;8969.609710494884;9013.18881300673;9217.400868943498;9259.629614815332;9291.745614341977;634.552640388525;640.6220330005319;655.2988302516358;670.0234308789092;716.6846587501483;4361.978190109029;4401.933970990438;4413.293872980372;4461.620762235247;4483.955160448354;99606;101463.24393231155;103036.48481757543;121190.39404803095;125230;100813;104741.47629261806;105108;105130;106197.46901265444;54414.101290963255;55859;56274.718626406604;90517;90593.54703226441;9962.940789375903;9966.216216216111;10684.786304273715;10698.679039628823;10711.785764284827;39487;49768.502314977835;50850.49149508458;51235;51311.71720707012;72670;77995;78245;79189.20810791818;79356.60321698355;12770.744585108432;12921.547745828851;12998.415071321624;13065.301510803534;13706.040577159503;14096.50662226817;14878.330475128862;14934.477293294682;15101.773473398127;16826.747598786274;14359.784603230752;14776.817854571824;15326;15656.21718914027;16111.194440278174;13980.300984950509;14051.36768845384;14944.701105977601;15399.923000385375;15423.922880385526;14956.25218739037;15002.174880381684;15467.303971321093;16066.116363600115;16122.113283769037;12023.158378913742;12399.876001239872;12437.191582547106;12939.695983061223;13346.06577539563;11151.498182581641;12149.574764883188;12904.741905162033;12950.740985180431;13034.869651303365 22:30:00 INFO - PROCESS | 3832 | __end_tp_report 22:30:00 INFO - PROCESS | 3832 | __start_cc_report 22:30:00 INFO - PROCESS | 3832 | _x_x_mozilla_cycle_collect,1012 22:30:00 INFO - PROCESS | 3832 | __end_cc_report 22:30:00 INFO - PROCESS | 3832 | __startTimestamp1471325400418__endTimestamp 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | ------- Summary: start ------- 22:30:00 INFO - PROCESS | 3832 | Number of tests: 6 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | [#0] dojo.html Cycles:165 Average:5244.51 Median:5446.26 stddev:596.02 (10.9%) stddev-sans-first:572.06 22:30:00 INFO - PROCESS | 3832 | Values: 3033.7 3069.7 3070.3 3091.8 3096.9 5096.7 5166.8 5276.7 5362.5 5448.4 5195.0 5238.7 5271.8 5281.2 5352.2 4755.0 4770.6 4833.6 4846.8 4931.0 4861.1 4881.0 4884.1 4900.4 4916.2 4615.3 4678.3 4717.0 4723.2 4828.5 4740.3 4753.4 4754.6 4819.1 4864.1 4779.8 4783.8 4791.7 4792.3 4844.6 5191.7 5197.8 5260.7 5268.4 5278.6 5583.6 5623.7 5631.2 5633.4 5645.7 5646.4 5647.9 5650.6 5658.6 5666.6 5098.4 5251.3 5327.0 5328.1 5362.4 5875.4 5884.2 5885.0 5887.0 5890.9 5621.2 5633.9 5653.9 5656.9 5657.8 5479.6 5483.2 5536.1 5536.7 5733.1 5542.4 5553.0 5556.0 5556.4 5597.0 5517.8 5519.9 5564.8 5565.8 5568.9 4222.9 4256.1 4260.2 4302.9 4359.6 3857.6 3887.8 3889.5 3890.6 3890.8 5364.3 5369.5 5386.1 5389.3 5430.0 5711.5 5717.1 5717.9 5752.5 5756.2 5240.7 5441.7 5450.5 5453.2 5457.0 4820.4 4862.6 4908.2 4919.7 4952.2 5887.2 5898.9 5903.6 5904.8 5905.7 5397.3 5637.8 5641.5 5641.8 5654.6 5730.0 5857.7 5893.2 5944.1 5991.6 5589.4 5607.0 5607.7 5610.9 5614.2 5470.5 5687.1 5699.8 5702.7 5705.2 5390.7 5435.7 5440.9 5457.8 5502.4 5396.0 5442.8 5446.3 5451.9 5453.3 5215.5 5395.0 5402.3 5402.8 5450.3 5474.1 5528.3 5539.4 5565.4 5582.0 5444.1 5446.9 5447.9 5449.3 5452.9 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | [#1] ext.html Cycles:180 Average:58290.59 Median:42619.54 stddev:113478.68 (266.3%) stddev-sans-first:113714.34 22:30:00 INFO - PROCESS | 3832 | Values: 596.7 613.3 623.7 629.7 630.9 32341.0 42513.4 43308.8 43404.6 47652.8 45941.3 46343.3 46385.8 46396.3 46434.8 8900.3 9049.2 9053.9 9055.7 9083.2 8995.6 9036.8 9044.4 9047.8 9089.3 9123.0 9189.6 9194.9 9198.8 9200.9 9092.1 9133.1 9134.8 9143.9 9145.3 8894.6 8998.9 9007.4 9009.2 9010.2 42135.6 42371.2 42494.6 42691.4 42698.6 3396.2 3456.9 3561.2 3981.4 4154.9 33325.0 33491.0 33577.8 33581.3 33922.0 4205.4 4331.3 4524.8 4530.1 4531.2 350192.2 350502.0 351233.0 351849.0 352096.0 56261.7 56573.7 56647.7 56664.0 56675.4 60538.4 60715.7 60806.4 60828.0 60843.0 15569.1 15770.4 15776.7 15777.5 15787.8 13043.7 13070.2 13109.6 13111.9 13112.2 15814.8 16716.2 16746.0 16789.7 16806.7 5959.7 6097.5 6114.9 6168.1 6264.8 804.1 870.8 958.5 1041.7 1176.5 31606.4 33056.5 33093.8 33195.5 33944.8 1122.9 1142.2 1179.0 1196.8 1202.1 16708.2 17469.9 17743.9 17788.7 17987.6 559044.0 563443.0 563727.0 563750.0 564605.0 58630.4 58691.4 58837.7 58966.7 59183.7 1271.5 1370.3 1397.6 1433.8 1437.4 31697.4 33337.3 34350.3 34411.8 34650.0 49644.3 50609.2 50693.7 50938.5 50942.0 328947.0 329861.0 329956.0 330164.0 330619.0 28207.3 28424.4 28472.2 28664.7 30118.7 49079.3 49274.5 49462.0 49867.5 49903.8 40772.9 41542.4 42576.6 42701.2 43701.8 42036.8 42291.8 42662.5 43126.8 43469.0 41527.8 42110.6 42510.0 43179.8 43195.0 34211.5 36911.4 37029.8 37269.0 38029.4 27381.3 27550.4 27672.3 27891.0 27934.4 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | [#2] jquery.html Cycles:180 Average:29020.31 Median:2166.74 stddev:82090.72 (3788.7%) stddev-sans-first:82292.85 22:30:00 INFO - PROCESS | 3832 | Values: 382.6 397.6 404.5 406.2 409.4 14391.6 18422.6 19732.9 19741.6 19845.2 12393.6 12546.6 12553.5 12608.1 12613.2 3950.8 5328.9 5458.3 5474.9 5479.0 5750.3 5951.4 6026.1 6124.4 6131.2 6000.6 6095.8 6102.6 6537.5 6588.5 6641.3 6687.1 6691.8 6696.9 6697.1 5129.0 5394.2 5427.8 5477.0 5485.3 13839.2 13944.2 14177.6 18496.8 24068.5 2313.6 2364.8 2411.8 2418.9 2668.4 9439.0 9849.5 9864.7 9971.5 10526.2 1864.0 1865.5 1879.5 1909.7 1930.0 78177.6 79485.6 80113.6 81083.6 82600.6 11307.2 11309.9 11365.7 11476.4 11563.1 16029.0 16037.4 16065.4 16085.5 16100.9 8015.4 8151.4 8378.9 8430.8 8482.8 7818.3 7826.8 7883.2 8015.7 8030.6 4583.2 4716.5 4856.7 4917.7 5093.6 2009.6 2040.4 2059.6 2072.6 2076.6 892.2 1034.3 1076.1 1090.5 1092.5 19904.5 20293.8 20389.2 20436.2 20917.3 973.5 1017.3 1043.0 1080.6 1104.2 10543.5 11632.6 11644.4 11712.5 11716.6 489198.0 490614.0 495195.0 496066.0 519217.0 86909.0 87551.6 90851.0 91468.5 92074.5 987.3 992.7 1066.2 1066.2 1069.4 17322.7 18046.5 18081.5 18354.5 18733.2 40352.2 40385.0 42885.0 44473.3 45410.0 59431.4 59711.4 60102.0 60851.0 61835.0 8335.0 8675.8 8867.7 8923.3 9141.8 7477.2 11312.6 11869.7 13867.7 14048.6 12131.6 12257.6 12314.5 12317.6 12361.7 12054.9 12163.3 12171.6 12203.3 12595.5 12323.4 12494.6 12633.9 12675.3 12758.6 8576.9 8659.2 8732.0 8792.0 8955.9 9565.8 11774.4 12068.2 12154.3 12263.4 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | [#3] mootools.html Cycles:180 Average:6007.61 Median:4382.65 stddev:3421.62 (78.1%) stddev-sans-first:3413.21 22:30:00 INFO - PROCESS | 3832 | Values: 1337.1 1484.1 1518.2 1627.5 1771.2 8579.5 8770.4 8955.5 9191.3 9192.9 8956.5 9090.5 9101.6 9108.7 9128.8 2414.9 2431.3 2434.8 2447.4 2452.5 2397.3 2397.8 2434.4 2436.5 2439.3 2368.3 2413.3 2418.5 2418.9 2421.2 2267.2 2268.8 2280.9 2284.5 2340.5 2277.3 2302.9 2303.8 2304.9 2310.6 8959.4 9029.9 9068.6 9107.7 9150.5 5256.3 5459.3 5477.3 5484.0 5486.3 3559.8 3568.4 3603.3 3625.9 3631.2 1227.8 1240.5 1240.6 1271.7 1274.0 12735.9 12785.9 12843.4 12845.1 12864.2 4395.1 4555.7 4687.0 4698.5 4712.1 10013.9 10022.9 10027.5 10032.1 10036.8 5356.6 5377.7 5382.5 5387.0 5391.1 4376.4 4786.8 4942.2 4967.1 5006.9 3804.1 3930.7 3970.5 3973.4 4062.5 1182.8 1190.4 1192.3 1201.4 1213.2 1015.2 1022.5 1026.5 1033.3 1045.3 6108.3 6117.2 6150.7 6161.4 6341.3 1042.0 1060.4 1095.6 1102.9 1109.3 4388.9 4400.7 4456.7 4506.3 4705.2 11446.3 11647.2 11724.5 11753.2 11767.1 10759.0 11669.3 11669.3 11672.1 11678.4 8403.6 8595.9 8646.3 8653.7 8655.4 6234.3 6271.2 6295.7 6340.5 6560.8 10147.6 10285.7 10446.3 10567.6 10589.5 10125.8 10304.9 10444.1 10459.2 10751.4 7464.2 7929.1 8006.7 8161.6 8264.8 9014.2 9084.8 9086.2 9092.8 9093.0 7758.2 7885.6 7896.9 7948.7 8399.3 7891.2 7955.3 8047.8 8083.9 8220.7 7031.4 7229.3 7331.7 7346.4 7355.6 5285.5 5299.6 5309.8 5374.1 5468.4 5241.1 5328.7 5361.6 5364.0 5546.4 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | [#4] prototype.html Cycles:180 Average:4577.77 Median:5245.69 stddev:1337.84 (25.5%) stddev-sans-first:1302.94 22:30:00 INFO - PROCESS | 3832 | Values: 324.4 330.1 336.8 339.5 340.3 5202.0 5212.8 5243.5 5252.0 5267.9 4918.7 4972.9 5088.4 5103.2 5195.8 4072.4 4091.3 4091.9 4092.7 4112.3 4103.4 4106.4 4106.8 4111.6 4113.6 3834.3 3919.7 4054.0 4058.3 4077.2 4056.6 4062.4 4073.2 4074.0 4076.7 3636.5 3640.9 3643.7 3644.4 3646.7 4833.6 5024.9 5041.1 5065.4 5074.3 4587.4 4592.3 4593.8 4597.4 4605.5 5409.6 5414.5 5419.9 5420.8 5446.3 3796.5 3875.9 3912.8 3915.6 3923.8 5534.5 5571.1 5594.6 5595.0 5598.0 3980.4 3985.5 3991.8 3991.9 3995.7 3950.3 4037.9 4038.6 4051.6 4053.7 4544.9 4573.8 4617.0 4648.3 4655.6 5397.7 5401.0 5402.5 5450.2 5465.3 1390.6 1399.2 1400.7 1411.6 1414.1 933.2 972.2 976.8 980.2 983.2 5195.4 5288.1 5288.2 5289.1 5321.3 5489.3 5518.6 5559.3 5560.7 5582.7 5127.5 5146.9 5198.1 5200.2 5223.8 2869.8 2875.5 2875.9 2878.6 2887.3 5551.3 5706.7 5710.9 5718.5 5722.4 5694.3 5709.9 5711.4 5712.1 5717.6 5797.2 5829.2 5832.7 5859.7 5860.5 5428.9 5516.0 5552.6 5552.8 5565.6 5536.0 5536.3 5544.4 5544.4 5544.5 5611.8 5799.4 5819.5 5848.6 5873.1 3928.7 3983.7 3986.8 4003.9 4004.6 5247.8 5467.5 5551.6 5572.5 5594.7 5427.3 5430.1 5447.6 5456.8 5471.0 5406.4 5430.3 5450.4 5469.8 5470.8 5201.8 5290.8 5344.0 5382.7 5386.6 5407.2 5407.5 5448.0 5450.6 5469.3 5097.0 5295.4 5342.6 5343.8 5375.5 22:30:00 INFO - PROCESS | 3832 | 22:30:00 INFO - PROCESS | 3832 | [#5] yui.html Cycles:180 Average:21054.49 Median:4437.46 stddev:29939.00 (674.7%) stddev-sans-first:29984.27 22:30:00 INFO - PROCESS | 3832 | Values: 774.4 779.9 811.9 822.4 828.3 15196.7 15625.0 15722.8 15731.1 15773.7 16429.2 16940.5 17154.6 17157.4 17219.5 811.3 831.2 834.2 834.3 834.6 675.3 781.8 799.0 799.6 811.6 880.8 881.1 882.6 883.1 883.2 872.9 873.4 873.9 874.1 874.2 511.0 615.0 619.5 622.4 628.2 14741.4 14763.9 14784.4 14785.6 14790.2 13528.7 13741.2 13976.2 14176.7 14580.7 12956.5 13062.2 13157.1 13220.9 13627.3 4557.9 4697.9 4754.7 4805.9 4826.9 88141.6 88184.6 88393.6 89484.0 90436.0 11234.4 11407.8 11474.7 11708.0 11732.4 21380.1 21461.2 21580.5 21589.6 21685.9 8638.2 8731.9 8835.4 8836.5 8946.6 4595.5 4873.5 4935.5 4999.9 5154.3 3264.8 3382.5 3387.5 3390.4 3394.8 590.8 594.3 603.5 605.7 606.6 680.4 684.8 693.9 694.1 721.2 8969.6 9013.2 9217.4 9259.6 9291.7 634.6 640.6 655.3 670.0 716.7 4362.0 4401.9 4413.3 4461.6 4484.0 99606.0 101463.2 103036.5 121190.4 125230.0 100813.0 104741.5 105108.0 105130.0 106197.5 54414.1 55859.0 56274.7 90517.0 90593.5 9962.9 9966.2 10684.8 10698.7 10711.8 39487.0 49768.5 50850.5 51235.0 51311.7 72670.0 77995.0 78245.0 79189.2 79356.6 12770.7 12921.5 12998.4 13065.3 13706.0 14096.5 14878.3 14934.5 15101.8 16826.7 14359.8 14776.8 15326.0 15656.2 16111.2 13980.3 14051.4 14944.7 15399.9 15423.9 14956.3 15002.2 15467.3 16066.1 16122.1 12023.2 12399.9 12437.2 12939.7 13346.1 11151.5 12149.6 12904.7 12950.7 13034.9 22:30:00 INFO - PROCESS | 3832 | -------- Summary: end -------- 22:30:00 INFO - PROCESS | 3832 | 22:30:01 INFO - TEST-INFO | 3832: exit 0 22:30:01 INFO - TEST-OK | dromaeo_css | took 1114054ms 22:30:01 INFO - TEST-START | kraken 22:30:01 INFO - operating with platform_type : linux_ 22:30:01 INFO - Initialising browser for kraken test... 22:30:01 INFO - TEST-INFO | started process 4139 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjJkIAF/profile http://localhost:44431/getInfo.html) 22:30:05 INFO - PROCESS | 4139 | __metrics Screen width/height:1600/1200 22:30:05 INFO - PROCESS | 4139 | colorDepth:24 22:30:05 INFO - PROCESS | 4139 | Browser inner width/height: 1024/697 22:30:05 INFO - PROCESS | 4139 | __metrics 22:30:05 INFO - PROCESS | 4139 | 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" 22:30:05 INFO - PROCESS | 4139 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:30:06 INFO - TEST-INFO | 4139: exit 0 22:30:06 INFO - Browser initialized. 22:30:06 INFO - Running cycle 1/1 for kraken test... 22:30:06 INFO - TEST-INFO | started process 4224 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjJkIAF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:18 INFO - PROCESS | 4224 | RSS: Main: 155668480 22:30:18 INFO - PROCESS | 4224 | 22:30:20 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-beat-detection) 22:30:21 INFO - PROCESS | 4224 | RSS: Main: 204124160 22:30:21 INFO - PROCESS | 4224 | 22:30:24 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-dft) 22:30:24 INFO - PROCESS | 4224 | RSS: Main: 194650112 22:30:24 INFO - PROCESS | 4224 | 22:30:27 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-fft) 22:30:28 INFO - PROCESS | 4224 | RSS: Main: 275988480 22:30:28 INFO - PROCESS | 4224 | 22:30:30 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-oscillator) 22:30:31 INFO - PROCESS | 4224 | RSS: Main: 186802176 22:30:31 INFO - PROCESS | 4224 | 22:30:33 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44431/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:30:33 INFO - PROCESS | 4224 | RSS: Main: 191152128 22:30:33 INFO - PROCESS | 4224 | 22:30:36 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44431/tests/kraken/driver.html?testName=imaging-darkroom) 22:30:37 INFO - PROCESS | 4224 | RSS: Main: 226222080 22:30:37 INFO - PROCESS | 4224 | 22:30:40 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44431/tests/kraken/driver.html?testName=imaging-desaturate) 22:30:41 INFO - PROCESS | 4224 | RSS: Main: 226430976 22:30:41 INFO - PROCESS | 4224 | 22:30:44 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44431/tests/kraken/driver.html?testName=json-parse-financial) 22:30:45 INFO - PROCESS | 4224 | RSS: Main: 226172928 22:30:45 INFO - PROCESS | 4224 | 22:30:46 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44431/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:30:47 INFO - PROCESS | 4224 | RSS: Main: 189227008 22:30:47 INFO - PROCESS | 4224 | 22:30:48 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:30:49 INFO - PROCESS | 4224 | RSS: Main: 196673536 22:30:49 INFO - PROCESS | 4224 | 22:30:51 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:30:52 INFO - PROCESS | 4224 | RSS: Main: 194015232 22:30:52 INFO - PROCESS | 4224 | 22:30:54 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:30:55 INFO - PROCESS | 4224 | RSS: Main: 187478016 22:30:55 INFO - PROCESS | 4224 | 22:30:58 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:30:58 INFO - PROCESS | 4224 | RSS: Main: 191754240 22:30:58 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44431/tests/kraken/driver.html?testName=ai-astar) 22:31:00 INFO - PROCESS | 4224 | RSS: Main: 242704384 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | __start_tp_report 22:31:00 INFO - PROCESS | 4224 | _x_x_mozilla_page_load 22:31:00 INFO - PROCESS | 4224 | _x_x_mozilla_page_load_details 22:31:00 INFO - PROCESS | 4224 | |i|pagename|runs| 22:31:00 INFO - PROCESS | 4224 | |0;ai-astar;103;112;111;111;105;111;111;111;113;111 22:31:00 INFO - PROCESS | 4224 | |1;audio-beat-detection;140;117;120;182;118;119;153;124;157;119 22:31:00 INFO - PROCESS | 4224 | |2;audio-dft;162;161;162;162;161;159;161;162;159;160 22:31:00 INFO - PROCESS | 4224 | |3;audio-fft;76;75;75;104;76;75;76;76;105;75 22:31:00 INFO - PROCESS | 4224 | |4;audio-oscillator;84;84;125;85;84;85;118;85;84;125 22:31:00 INFO - PROCESS | 4224 | |5;imaging-gaussian-blur;116;117;116;117;116;117;116;117;117;117 22:31:00 INFO - PROCESS | 4224 | |6;imaging-darkroom;146;145;145;146;146;146;145;146;146;147 22:31:00 INFO - PROCESS | 4224 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;96;95 22:31:00 INFO - PROCESS | 4224 | |8;json-parse-financial;52;51;51;51;61;54;52;52;52;50 22:31:00 INFO - PROCESS | 4224 | |9;json-stringify-tinderbox;63;62;62;62;62;62;62;67;63;63 22:31:00 INFO - PROCESS | 4224 | |10;stanford-crypto-aes;79;78;77;78;86;81;79;78;87;78 22:31:00 INFO - PROCESS | 4224 | |11;stanford-crypto-ccm;123;123;123;121;120;129;134;127;139;137 22:31:00 INFO - PROCESS | 4224 | |12;stanford-crypto-pbkdf2;162;161;160;159;160;163;165;160;168;164 22:31:00 INFO - PROCESS | 4224 | |13;stanford-crypto-sha256-iterative;64;65;66;64;63;68;62;64;64;66 22:31:00 INFO - PROCESS | 4224 | __end_tp_report 22:31:00 INFO - PROCESS | 4224 | __start_cc_report 22:31:00 INFO - PROCESS | 4224 | _x_x_mozilla_cycle_collect,1650 22:31:00 INFO - PROCESS | 4224 | __end_cc_report 22:31:00 INFO - PROCESS | 4224 | __startTimestamp1471325460668__endTimestamp 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | ------- Summary: start ------- 22:31:00 INFO - PROCESS | 4224 | Number of tests: 14 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.21 (2.9%) stddev-sans-first:2.24 22:31:00 INFO - PROCESS | 4224 | Values: 103.0 112.0 111.0 111.0 105.0 111.0 111.0 111.0 113.0 111.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:132.00 stddev:22.39 (17.0%) stddev-sans-first:23.67 22:31:00 INFO - PROCESS | 4224 | Values: 140.0 117.0 120.0 182.0 118.0 119.0 153.0 124.0 157.0 119.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.50 stddev:1.20 (0.7%) stddev-sans-first:1.20 22:31:00 INFO - PROCESS | 4224 | Values: 162.0 161.0 162.0 162.0 161.0 159.0 161.0 162.0 159.0 160.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.50 stddev:12.24 (16.2%) stddev-sans-first:12.83 22:31:00 INFO - PROCESS | 4224 | Values: 76.0 75.0 75.0 104.0 76.0 75.0 76.0 76.0 105.0 75.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:84.00 stddev:18.57 (22.1%) stddev-sans-first:19.19 22:31:00 INFO - PROCESS | 4224 | Values: 84.0 84.0 125.0 85.0 84.0 85.0 118.0 85.0 84.0 125.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:31:00 INFO - PROCESS | 4224 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 22:31:00 INFO - PROCESS | 4224 | Values: 146.0 145.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 147.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:31:00 INFO - PROCESS | 4224 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.00 stddev:3.13 (6.0%) stddev-sans-first:3.32 22:31:00 INFO - PROCESS | 4224 | Values: 52.0 51.0 51.0 51.0 61.0 54.0 52.0 52.0 52.0 50.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:62.50 stddev:1.55 (2.5%) stddev-sans-first:1.64 22:31:00 INFO - PROCESS | 4224 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 62.0 67.0 63.0 63.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.00 stddev:3.54 (4.5%) stddev-sans-first:3.73 22:31:00 INFO - PROCESS | 4224 | Values: 79.0 78.0 77.0 78.0 86.0 81.0 79.0 78.0 87.0 78.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:128.00 stddev:6.88 (5.4%) stddev-sans-first:7.10 22:31:00 INFO - PROCESS | 4224 | Values: 123.0 123.0 123.0 121.0 120.0 129.0 134.0 127.0 139.0 137.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:162.50 stddev:2.82 (1.7%) stddev-sans-first:2.99 22:31:00 INFO - PROCESS | 4224 | Values: 162.0 161.0 160.0 159.0 160.0 163.0 165.0 160.0 168.0 164.0 22:31:00 INFO - PROCESS | 4224 | 22:31:00 INFO - PROCESS | 4224 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:1.71 (2.7%) stddev-sans-first:1.80 22:31:00 INFO - PROCESS | 4224 | Values: 64.0 65.0 66.0 64.0 63.0 68.0 62.0 64.0 64.0 66.0 22:31:00 INFO - PROCESS | 4224 | -------- Summary: end -------- 22:31:00 INFO - PROCESS | 4224 | 22:31:01 INFO - TEST-INFO | 4224: exit 0 22:31:01 INFO - TEST-OK | kraken | took 60454ms 22:31:01 INFO - SUITE-END | took 1174s 22:31:01 INFO - Completed test suite (00:19:34) 22:31:01 INFO - javascript benchmark 22:31:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.651130120036, 3069.7237248647616, 3070.3398769264604, 3091.799033062851, 3096.876124955002, 5096.668716533422, 5166.793328266865, 5276.709780962045, 5362.4637536246355, 5448.37343705474, 5194.974025129878, 5238.711870847102, 5271.841844744664, 5281.2342210379475, 5352.2239275305055, 4754.976225118879, 4770.594499467532, 4833.589144922686, 4846.830360937368, 4931.038447502739, 4861.052094841507, 4880.9993951240085, 4884.096442158194, 4900.411950565919, 4916.237983112624, 4615.284630882202, 4678.345031695565, 4716.962268300968, 4723.173444647173, 4828.5412885775795, 4740.2889566564945, 4753.3582966299455, 4754.643401744883, 4819.1325561398935, 4864.100141473803, 4779.831564041079, 4783.760811959423, 4791.7124972501415, 4792.30511575823, 4844.612431005511, 5191.662541934762, 5197.844064678028, 5260.736963151827, 5268.367795864482, 5278.604104692164, 5583.609147359686, 5623.690697011662, 5631.211630371751, 5633.352164501094, 5645.717714114319, 5646.435356464321, 5647.8588035299035, 5650.604457687963, 5658.632188907749, 5666.57500687446, 5098.362704661918, 5251.291075704769, 5327.0, 5328.067588172094, 5362.383325917492, 5875.383084776123, 5884.205632239678, 5884.970575147183, 5887.0, 5890.911636325459, 5621.156826476062, 5633.887322253532, 5653.858653533694, 5656.85857853557, 5657.830265092054, 5479.589030822704, 5483.177523371526, 5536.058869992111, 5536.723163841792, 5733.111367738007, 5542.39033706292, 5553.0004599171725, 5556.027695153332, 5556.388797232301, 5597.0, 5517.806876759294, 5519.889602207933, 5564.805231816867, 5565.777368905279, 5568.860778480571, 4222.894427639334, 4256.148770245902, 4260.190563792869, 4302.870913872589, 4359.629431498358, 3857.61423857612, 3887.8056097195026, 3889.4943657324934, 3890.5914878937306, 3890.8054597270025, 5364.302640656768, 5369.4630536946, 5386.057439947994, 5389.299391079213, 5430.049741295257, 5711.457411545896, 5717.142428635738, 5717.8570535736935, 5752.539796816244, 5756.194132821408, 5240.737963101829, 5441.727913604304, 5450.482204190595, 5453.209284653703, 5456.972715136399, 4820.373351464357, 4862.635302352338, 4908.165611845995, 4919.680220785637, 4952.232403977389, 5887.234659494238, 5898.94101058984, 5903.557233207526, 5904.822855314347, 5905.734241959122, 5397.325334333208, 5637.802676906289, 5641.464060914206, 5641.830745077655, 5654.575906807088, 5729.971350143389, 5857.736401862012, 5893.204417403767, 5944.078667806497, 5991.580589358878, 5589.413111623384, 5607.0, 5607.691576963306, 5610.85972850682, 5614.157876318585, 5470.452954704657, 5687.090065589486, 5699.829005129853, 5702.743376547983, 5705.172749951317, 5390.730463476889, 5435.701036443033, 5440.945590544044, 5457.808976685797, 5502.422245664147, 5396.0, 5442.809501667422, 5446.264754258283, 5451.890962180813, 5453.291072160752, 5215.452377500308, 5395.028894798974, 5402.297701298806, 5402.783888644411, 5450.291462110059, 5474.124140137559, 5528.336599608073, 5539.362973258127, 5565.415631358649, 5582.023145949362, 5444.074507333684, 5446.945530544803, 5447.945520544902, 5449.264349312792, 5452.91820622683], "value": 5204.521679896914, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.6897213449007, 613.3437222172278, 623.7130919776903, 629.7071861584366, 630.8864404407204, 32341.029769106935, 42513.362299565495, 43308.783456082674, 43404.56595434045, 47652.76173619127, 45941.31088033682, 46343.304850427274, 46385.768071159684, 46396.30405543919, 46434.76782616082, 8900.332475064364, 9049.2308153807, 9053.864192037125, 9055.728328150133, 9083.182513573776, 8995.595198216097, 9036.819263614723, 9044.3668943174, 9047.819043619156, 9089.272858171364, 9122.954385228099, 9189.632414703405, 9194.908050919472, 9198.816023679523, 9200.861987070199, 9092.136247056518, 9133.08669133088, 9134.81730365389, 9143.862842057375, 9145.314101442385, 8894.59974301158, 8998.910010899872, 9007.369484136114, 9009.234215091725, 9010.23413009895, 42135.578644213776, 42371.15257694829, 42494.57505424937, 42691.359629605584, 42698.57301426977, 3396.1509622594353, 3456.879009234665, 3561.1631266652316, 3981.3828856527284, 4154.937675934863, 33325.00024999254, 33491.0, 33577.83211083953, 33581.32837343239, 33922.0, 4205.390218418343, 4331.285337919239, 4524.773761311954, 4530.116627257686, 4531.207038768235, 350192.2490387557, 350502.0, 351233.0, 351849.0, 352096.0, 56261.718691406284, 56573.71713141408, 56647.716761415926, 56664.0, 56675.43324566784, 60538.39461605416, 60715.69642151761, 60806.39193608096, 60828.0, 60843.0, 15569.143697096542, 15770.369185232692, 15776.68446631061, 15777.526674199562, 15787.842121578868, 13043.673908152183, 13070.215787052808, 13109.60671179866, 13111.868881311255, 13112.213267203999, 15814.841851581567, 16716.164191790605, 16746.0, 16789.748153777702, 16806.747898781527, 5959.702014899238, 6097.512199024068, 6114.877702446015, 6168.1056246843955, 6264.843378915472, 804.0913767442869, 870.7561882672861, 958.496789185683, 1041.6614600254948, 1176.5352685689104, 31606.36787264242, 33056.50415243773, 33093.83453082719, 33195.50206746901, 33944.830275848464, 1122.910167186623, 1142.2118738070706, 1178.9941050294865, 1196.7965445874222, 1202.1164444133556, 16708.162102168753, 17469.912650436665, 17743.93536387821, 17788.733169002477, 17987.640247194984, 559044.0, 563443.0, 563727.0, 563750.0, 564605.0, 58630.413695863346, 58691.41308586945, 58837.705811471526, 58966.70516647389, 59183.704081479314, 1271.497758385451, 1370.3011464153353, 1397.5667543061684, 1433.78493226019, 1437.4393986345333, 31697.39262577011, 33337.33325333528, 34350.312993739484, 34411.82794086014, 34650.0, 49644.25533616926, 50609.24086138637, 50693.746531267105, 50938.490615093375, 50942.0, 328947.0, 329861.0, 329956.0, 330164.0, 330619.0, 28207.294817629725, 28424.431511369243, 28472.15278472219, 28664.713352866205, 30118.698813012466, 49079.26381104358, 49274.50725492842, 49462.0, 49867.50132498628, 49903.75048124736, 40772.927612740306, 41542.37686434646, 42576.57423425726, 42701.16743517397, 43701.78149109234, 42036.789816050725, 42291.788541057096, 42662.506812261425, 43126.78436607797, 43469.0, 41527.792361038, 42110.57889421067, 42510.0, 43179.78410108055, 43195.0, 34211.48682769711, 36911.44632830456, 37029.81485092557, 37269.0, 38029.42955855609, 27381.315467112683, 27550.44899102047, 27672.308192295353, 27891.0, 27934.44131117407], "value": 20098.151793367288, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.6384067056633, 397.5587098320863, 404.4741835613701, 406.228166483681, 409.4400334724699, 14391.568252952415, 18422.631547369063, 19732.9013354933, 19741.605167896632, 19845.20619175233, 12393.62819115428, 12546.623601291976, 12553.497860085608, 12608.05439592032, 12613.24320540766, 3950.8419663213435, 5328.893422131565, 5458.317710286215, 5474.890502189954, 5478.972605136969, 5750.281214848144, 5951.434613711712, 6026.0659597762415, 6124.387561243884, 6131.202943617323, 6000.6099603525845, 6095.756169753205, 6102.633841969474, 6537.5423720339595, 6588.4729221662155, 6641.269460359356, 6687.063811066455, 6691.83270418241, 6696.923953574359, 6697.129373181479, 5129.0, 5394.163904594794, 5427.782888684448, 5476.972615136899, 5485.259489968843, 13839.238841863693, 13944.16335019902, 14177.574672759834, 18496.797708148926, 24068.51862962731, 2313.5835549601234, 2364.75170107138, 2411.8311718179734, 2418.8669623170717, 2668.3996100877343, 9438.95280523593, 9849.458279794608, 9864.654737084167, 9971.451570163637, 10526.157907367393, 1864.0, 1865.4776662534512, 1879.492537015012, 1909.6562618728624, 1929.9614007719908, 78177.60911195521, 79485.60257198734, 80113.59943200304, 81083.59458202671, 82600.58699706523, 11307.208495405243, 11309.886901131047, 11365.659030229106, 11476.368799716096, 11563.132765042656, 16029.0, 16037.438689645807, 16065.357385704441, 16085.517434476984, 16100.919495402448, 8015.358771298283, 8151.388645851584, 8378.916210837813, 8430.789230269169, 8482.830343393098, 7818.335441487424, 7826.7651970440975, 7883.211678832071, 8015.679372825139, 8030.558319292495, 4583.197940360424, 4716.457607375196, 4856.68431551948, 4917.65576409655, 5093.592512598983, 2009.5689005742304, 2040.4082815983495, 2059.5983783162437, 2072.616565935307, 2076.595063962513, 892.2103938014774, 1034.3276870034388, 1076.1444651502075, 1090.4658856304288, 1092.4810714910354, 19904.50238744043, 20293.797062029487, 20389.184432622827, 20436.182552697726, 20917.26789562358, 973.5132433783108, 1017.2878984710584, 1043.045613263855, 1080.5785743560018, 1104.1608377632897, 10543.525541350658, 11632.592859250055, 11644.41777911118, 11712.472938717778, 11716.648500544998, 489198.0, 490614.0, 495195.0, 496066.0, 519217.0, 86909.0, 87551.56224218837, 90851.0, 91468.54265728629, 92074.53962730143, 987.3286165407592, 992.7468495533593, 1066.1737153705785, 1066.2376400873259, 1069.3797597393493, 17322.740158897373, 18046.458606241307, 18081.457556273333, 18354.541136471697, 18733.157007934948, 40352.19295614013, 40385.0, 42885.0, 44473.33290000588, 45410.0, 59431.4341139533, 59711.40288597059, 60102.0, 60851.0, 61835.0, 8334.999800023794, 8675.826483470422, 8867.68963086289, 8923.286137109146, 9141.817163656822, 7477.214892436434, 11312.6040588579, 11869.694333623122, 13867.653308667364, 14048.648783780487, 12131.636050918487, 12257.570985015482, 12314.50741970347, 12317.630471085882, 12361.690957726129, 12054.879451205376, 12163.2702037878, 12171.573994910135, 12203.281504339113, 12595.496180152692, 12323.44544495482, 12494.625161244814, 12633.93683031579, 12675.30285834288, 12758.553450629184, 8576.914230857612, 8659.220670139717, 8732.0, 8792.0, 8955.865662014945, 9565.760855978657, 11774.3524106175, 12068.224308408939, 12154.270743755404, 12263.448144833683], "value": 9208.427018937464, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.0774165825574, 1484.1391992644265, 1518.2181176694, 1627.462937230714, 1771.1852547828007, 8579.485230886152, 8770.42992205507, 8955.46267223967, 9191.264698824094, 9192.90807091929, 8956.46261224327, 9090.500022498776, 9101.63593456261, 9108.681196158135, 9128.771780705485, 2414.9396265093424, 2431.3192306154306, 2434.8417352872093, 2447.3759191406166, 2452.460458699092, 2397.2927986244013, 2397.7961873240797, 2434.379233295507, 2436.4517983453757, 2439.256026911791, 2368.289513146049, 2413.263954493879, 2418.516296740659, 2418.854868707874, 2421.2131057406327, 2267.2178098555974, 2268.7617800130997, 2280.931572052841, 2284.5316710074394, 2340.5084932164095, 2277.3054218463526, 2302.8848557572223, 2303.8041766449874, 2304.9025386897843, 2310.572544079334, 8959.41763785352, 9029.909700902972, 9068.591913363915, 9107.726768196899, 9150.450972941644, 5256.290400795881, 5459.262999495057, 5477.287952566141, 5484.040292948718, 5486.286782718261, 3559.768615040014, 3568.4468907319406, 3603.297357015383, 3625.873094441682, 3631.2192878530977, 1227.8035514317667, 1240.4851986425622, 1240.6340129661771, 1271.7138643805163, 1273.9552469597577, 12735.872641273561, 12785.872141278562, 12843.42204600786, 12845.100842940905, 12864.163829351062, 4395.1429471252795, 4555.658325625592, 4686.976565117221, 4698.459677137173, 4712.104700107037, 10013.949930250303, 10022.949885250673, 10027.548760305805, 10032.097111260022, 10036.849447258297, 5356.625036247426, 5377.650452720561, 5382.461753824587, 5387.0, 5391.056565101092, 4376.431063961664, 4786.8085276589245, 4942.233953737176, 4967.130752118364, 5006.924896126561, 3804.1250512382308, 3930.665893399093, 3970.463987361727, 3973.3642617181113, 4062.4921884764403, 1182.7811854806716, 1190.357207108152, 1192.272713644676, 1201.375284851872, 1213.1750409721299, 1015.2284263959444, 1022.4683164754287, 1026.4662375564665, 1033.338663255517, 1045.29442626227, 6108.267007959073, 6117.235345581802, 6150.692465376714, 6161.383861613893, 6341.270753863365, 1041.958321667125, 1060.3690803971624, 1095.6165342130191, 1102.9062529685066, 1109.3011402816173, 4388.8713973722715, 4400.669949753781, 4456.665750068758, 4506.301523263899, 4705.1765940960195, 11446.255993360404, 11647.242929209575, 11724.531018759324, 11753.236039657395, 11767.058635309155, 10759.0, 11669.299842009506, 11669.299842009506, 11672.066234701204, 11678.357690327113, 8403.621837017348, 8595.871061934202, 8646.308295336357, 8653.653853845775, 8655.394122411497, 6234.345393733593, 6271.184745982993, 6295.685215739286, 6340.461060809883, 6560.8359791005605, 10147.64483243083, 10285.691429257135, 10446.320989135682, 10567.63013294531, 10589.470526473491, 10125.797484050425, 10304.948475257575, 10444.112250458797, 10459.21555883327, 10751.408672523086, 7464.216257293123, 7929.088154862265, 8006.679732810622, 8161.632726527202, 8264.752057438287, 9014.233790127913, 9084.772880678036, 9086.182243597843, 9092.772680683036, 9093.0, 7758.185390533913, 7885.605719714106, 7896.921030789618, 7948.682052718058, 8399.28606068491, 7891.171427000082, 7955.323797477279, 8047.83904321922, 8083.878741818759, 8220.671173153007, 7031.43748500129, 7229.313215244543, 7331.670074846538, 7346.449016323691, 7355.595442250514, 5285.4714528548375, 5299.576033917355, 5309.814156504503, 5374.0864053111845, 5468.3711373191, 5241.109011467984, 5328.653637513549, 5361.624686271924, 5363.973180134074, 5546.389897111395], "value": 4808.486243921496, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.4030982991297, 330.1432781930889, 336.82148461315506, 339.46703675229867, 340.2701205913316, 5201.982849947031, 5212.76542555585, 5243.528082472581, 5252.0, 5267.947320526793, 4918.655694101414, 4972.875678108049, 5088.4402715701335, 5103.183490641498, 5195.818146364878, 4072.3687828386646, 4091.3044782387065, 4091.89770255743, 4092.693048021403, 4112.321466957948, 4103.384492326145, 4106.384042393635, 4106.815193316308, 4111.60939710727, 4113.609207125333, 3834.3481608126685, 3919.7256192066566, 4053.9797301013455, 4058.269511487931, 4077.225327187825, 4056.6146216109423, 4062.410950412174, 4073.226087043482, 4073.999999999985, 4076.675080598802, 3636.4908213332715, 3640.872569460056, 3643.6720695137565, 3644.4146796143764, 3646.7264955128207, 4833.564979151894, 5024.874378140539, 5041.143005689041, 5065.366829146356, 5074.325472594035, 4587.3577699121815, 4592.265237561974, 4593.770311484413, 4597.37935378723, 4605.516420775837, 5409.5672346212605, 5414.512693857571, 5419.918701219485, 5420.810271640474, 5446.346438427375, 3796.468494410785, 3875.875995961168, 3912.804359782028, 3915.647591716759, 3923.8430462781453, 5534.501894829485, 5571.1086226203615, 5594.552435805126, 5595.0, 5597.972010139923, 3980.383040628707, 3985.4619626350072, 3991.820368083443, 3991.880243592697, 3995.682504147587, 3950.328444164499, 4037.8990525237105, 4038.5961403859965, 4051.6353528182603, 4053.7162398631813, 4544.931826022612, 4573.771311434415, 4617.0, 4648.349231107648, 4655.626590155912, 5397.70312632809, 5400.973814975142, 5402.513773760302, 5450.1931505274815, 5465.344158700981, 1390.5967269491937, 1399.2094466626324, 1400.7198560287984, 1411.640031791907, 1414.0808474491662, 933.2440723014245, 972.2367941166292, 976.8144052629978, 980.1962390839443, 983.2134292565919, 5195.376554813371, 5288.0745869472685, 5288.233206185082, 5289.095925466829, 5321.255024296602, 5489.2589500417735, 5518.558515318845, 5559.277293951841, 5560.749766260528, 5582.692951887685, 5127.487251274917, 5146.948530514647, 5198.090334191501, 5200.193969934666, 5223.839577055903, 2869.7704183665255, 2875.4680384129, 2875.8705858236012, 2878.56821476776, 2887.321479452327, 5551.278333816659, 5706.686132262683, 5710.885782284331, 5718.513926316226, 5722.399148089474, 5694.316681998104, 5709.857253568611, 5711.400302968129, 5712.0860662293835, 5717.599768016199, 5797.188393624896, 5829.183914252008, 5832.679202643811, 5859.677717725566, 5860.531157507475, 5428.941356435457, 5515.972420137874, 5552.640127945688, 5552.750126244248, 5565.55295623149, 5536.0, 5536.280283563111, 5544.390117087037, 5544.445555444423, 5544.473275038865, 5611.775528978795, 5799.391063938226, 5819.5053420459735, 5848.619839710575, 5873.060310350324, 3928.744631598937, 3983.709367113863, 3986.760794352348, 4003.899902502461, 4004.559498455226, 5247.842564723064, 5467.507924286833, 5551.555875529867, 5572.470615291703, 5594.692291923983, 5427.348718153847, 5430.104032834538, 5447.591430642638, 5456.836294911159, 5470.972645136749, 5406.4052954175795, 5430.321209848769, 5450.400455949763, 5469.781208751605, 5470.8085217017215, 5201.843944681666, 5290.841274761763, 5343.969291669141, 5382.703951282717, 5386.622936394415, 5407.161889907071, 5407.459254074561, 5447.992121457384, 5450.618456708151, 5469.316335458067, 5096.974515127401, 5295.391030031596, 5342.6260161788305, 5343.7862485500145, 5375.489328513785], "value": 4156.938951198994, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.4424014709414, 779.8791187365956, 811.9106898241192, 822.3996482567727, 828.3125006244817, 15196.696066078686, 15624.984376015565, 15722.764158537631, 15731.134787586678, 15773.684526309497, 16429.178541072957, 16940.49178524643, 17154.57113572158, 17157.399491017815, 17219.483415497525, 811.3103861717523, 831.2477208126655, 834.2200042959821, 834.3075247544524, 834.5743670727911, 675.3449154320301, 781.7615627233691, 798.9560574168419, 799.6081919859256, 811.5698679699739, 880.7886107334226, 881.066069965835, 882.6469412235125, 883.0860059838069, 883.196291374849, 872.8734333521604, 873.3711727556149, 873.9431936924111, 874.1215078845751, 874.1695389380083, 510.9821156259513, 614.9907751383732, 619.5260625621403, 622.3682961793783, 628.2021832272987, 14741.410343586243, 14763.92618036903, 14784.408623655041, 14785.55643330691, 14790.18653974031, 13528.729425411535, 13741.175529468264, 13976.161430314112, 14176.716465670732, 14580.70838583233, 12956.48174073036, 13062.216267024009, 13157.144785588906, 13220.933895330558, 13627.318634068357, 4557.863264102082, 4697.88255293617, 4754.66717329787, 4805.879853003668, 4826.879328016793, 88141.55929220378, 88184.55907720485, 88393.55803220943, 89484.0, 90436.0, 11234.438278086063, 11407.771844563062, 11474.655760327203, 11708.0, 11732.354720490284, 21380.144794208056, 21461.248856289956, 21580.46048848791, 21589.568208636054, 21685.89157054236, 8638.17937295956, 8731.869021964676, 8835.381523293436, 8836.513991730517, 8946.552672366355, 4595.540445955377, 4873.512648735169, 4935.457099719028, 4999.875003124878, 5154.329937108152, 3264.7877887937207, 3382.52644629752, 3387.458006718962, 3390.372781035467, 3394.813285269334, 590.8257064166081, 594.3165359836137, 603.5473394953784, 605.7032054293364, 606.6057062909056, 680.3876511139915, 684.7911387027004, 693.8889777635554, 694.0560837261321, 721.2102747491489, 8969.609710494884, 9013.18881300673, 9217.400868943498, 9259.629614815332, 9291.745614341977, 634.552640388525, 640.6220330005319, 655.2988302516358, 670.0234308789092, 716.6846587501483, 4361.978190109029, 4401.933970990438, 4413.293872980372, 4461.620762235247, 4483.955160448354, 99606.0, 101463.24393231155, 103036.48481757543, 121190.39404803095, 125230.0, 100813.0, 104741.47629261806, 105108.0, 105130.0, 106197.46901265444, 54414.101290963255, 55859.0, 56274.718626406604, 90517.0, 90593.54703226441, 9962.940789375903, 9966.216216216111, 10684.786304273715, 10698.679039628823, 10711.785764284827, 39487.0, 49768.502314977835, 50850.49149508458, 51235.0, 51311.71720707012, 72670.0, 77995.0, 78245.0, 79189.20810791818, 79356.60321698355, 12770.744585108432, 12921.547745828851, 12998.415071321624, 13065.301510803534, 13706.040577159503, 14096.50662226817, 14878.330475128862, 14934.477293294682, 15101.773473398127, 16826.747598786274, 14359.784603230752, 14776.817854571824, 15326.0, 15656.21718914027, 16111.194440278174, 13980.300984950509, 14051.36768845384, 14944.701105977601, 15399.923000385375, 15423.922880385526, 14956.25218739037, 15002.174880381684, 15467.303971321093, 16066.116363600115, 16122.113283769037, 12023.158378913742, 12399.876001239872, 12437.191582547106, 12939.695983061223, 13346.06577539563, 11151.498182581641, 12149.574764883188, 12904.741905162033, 12950.740985180431, 13034.869651303365], "value": 7744.053172528286, "unit": "score"}], "name": "dromaeo_css", "value": 7282.038960876174, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 111.0, 111.0, 105.0, 111.0, 111.0, 111.0, 113.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 117.0, 120.0, 182.0, 118.0, 119.0, 153.0, 124.0, 157.0, 119.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 161.0, 159.0, 161.0, 162.0, 159.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 104.0, 76.0, 75.0, 76.0, 76.0, 105.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 125.0, 85.0, 84.0, 85.0, 118.0, 85.0, 84.0, 125.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 61.0, 54.0, 52.0, 52.0, 52.0, 50.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 67.0, 63.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 77.0, 78.0, 86.0, 81.0, 79.0, 78.0, 87.0, 78.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 123.0, 123.0, 121.0, 120.0, 129.0, 134.0, 127.0, 139.0, 137.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 159.0, 160.0, 163.0, 165.0, 160.0, 168.0, 164.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 64.0, 63.0, 68.0, 62.0, 64.0, 64.0, 66.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1490.4, "alertThreshold": 2.0}]} 22:31:01 INFO - Return code: 0 22:31:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:31:01 INFO - # TBPL SUCCESS # 22:31:01 INFO - Running post-action listener: _resource_record_post_action 22:31:01 INFO - [mozharness: 2016-08-16 05:31:01.978794Z] Finished run-tests step (success) 22:31:01 INFO - Running post-run listener: _resource_record_post_run 22:31:02 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410017792; Read time: 8; Write time: 270984 22:31:02 INFO - TinderboxPrint: CPU usage
12.9% 22:31:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:31:02 INFO - TinderboxPrint: I/O write bytes / time
410,017,792 / 270,984 22:31:02 INFO - TinderboxPrint: CPU idle
8,284.5 (87.1%) 22:31:02 INFO - TinderboxPrint: CPU user
1,186.3 (12.5%) 22:31:02 INFO - TinderboxPrint: Swap in / out
0 / 0 22:31:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118042624; Read time: 0; Write time: 87548 22:31:02 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 291586048; Read time: 0; Write time: 183396 22:31:02 INFO - Running post-run listener: _upload_blobber_files 22:31:02 INFO - Blob upload gear active. 22:31:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:31:02 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 22:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:31:03 INFO - (blobuploader) - INFO - Open directory for files ... 22:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:31:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:03 INFO - (blobuploader) - INFO - Done attempting. 22:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:31:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:07 INFO - (blobuploader) - INFO - Done attempting. 22:31:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:31:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:31:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:08 INFO - (blobuploader) - INFO - Done attempting. 22:31:08 INFO - (blobuploader) - INFO - Iteration through files over. 22:31:08 INFO - Return code: 0 22:31:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:31:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:31:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c95fc36c4c0fb2297cb476629eeee1df6fe106fc5187463a2ff59e3725763047eeb519620931cc6fb42276e6024d5d758e77965780ad3a228c4466a6ce1ba0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79f25b372eb4574b76210f602a183acdbe572a61df3e48669f77149e1b4891bcf8c1cbfa596f6be2a83b5889801851fe65bdcf31288a3ead357eccf23c313278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82ca1f4e0760663597b8bbea343f4e0a037f7eef9a61fc4403447274de76a766421bf4367c40ef030f0c91fc1c96a655cd7c3fbd2d9ffa46ca5d625cc0b96f11"} 22:31:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:31:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:31:08 INFO - Contents: 22:31:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c95fc36c4c0fb2297cb476629eeee1df6fe106fc5187463a2ff59e3725763047eeb519620931cc6fb42276e6024d5d758e77965780ad3a228c4466a6ce1ba0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79f25b372eb4574b76210f602a183acdbe572a61df3e48669f77149e1b4891bcf8c1cbfa596f6be2a83b5889801851fe65bdcf31288a3ead357eccf23c313278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82ca1f4e0760663597b8bbea343f4e0a037f7eef9a61fc4403447274de76a766421bf4367c40ef030f0c91fc1c96a655cd7c3fbd2d9ffa46ca5d625cc0b96f11"} 22:31:08 INFO - Running post-run listener: copy_logs_to_upload_dir 22:31:08 INFO - Copying logs to upload dir... 22:31:08 INFO - mkdir: /builds/slave/test/build/upload/logs 22:31:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.109204 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-15 22:31:08.259418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 22:31:08.262861) ========= 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=ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c95fc36c4c0fb2297cb476629eeee1df6fe106fc5187463a2ff59e3725763047eeb519620931cc6fb42276e6024d5d758e77965780ad3a228c4466a6ce1ba0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79f25b372eb4574b76210f602a183acdbe572a61df3e48669f77149e1b4891bcf8c1cbfa596f6be2a83b5889801851fe65bdcf31288a3ead357eccf23c313278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82ca1f4e0760663597b8bbea343f4e0a037f7eef9a61fc4403447274de76a766421bf4367c40ef030f0c91fc1c96a655cd7c3fbd2d9ffa46ca5d625cc0b96f11"} build_url:https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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/sha512/c95fc36c4c0fb2297cb476629eeee1df6fe106fc5187463a2ff59e3725763047eeb519620931cc6fb42276e6024d5d758e77965780ad3a228c4466a6ce1ba0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79f25b372eb4574b76210f602a183acdbe572a61df3e48669f77149e1b4891bcf8c1cbfa596f6be2a83b5889801851fe65bdcf31288a3ead357eccf23c313278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82ca1f4e0760663597b8bbea343f4e0a037f7eef9a61fc4403447274de76a766421bf4367c40ef030f0c91fc1c96a655cd7c3fbd2d9ffa46ca5d625cc0b96f11"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 22:31:08.288445) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:31:08.288795) ========= 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=ed14279e38b05397cf4aa391000001a4-1471322653.911707-631710460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004362 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:31:08.373444) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 22:31:08.373750) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 22:31:08.374032) ========= ========= Total master_lag: 0.18 =========