builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-069 starttime: 1472223204.38 results: success (0) buildid: 20160826063820 builduid: 849d736893ee4dd08e0dded7c8ab111a revision: a65b35c8e5b17c2585968974aef1da67a8c56642 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.385281) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.386212) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.386837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.404288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.404874) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 07:53:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2016-08-26 07:53:25 (42.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.319722 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.742876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.743645) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020745 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.794280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:24.794688) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a65b35c8e5b17c2585968974aef1da67a8c56642 --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 a65b35c8e5b17c2585968974aef1da67a8c56642 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 07:53:25,248 truncating revision to first 12 chars 2016-08-26 07:53:25,248 Setting DEBUG logging. 2016-08-26 07:53:25,248 attempt 1/10 2016-08-26 07:53:25,248 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a65b35c8e5b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 07:53:25,521 unpacking tar archive at: fx-team-a65b35c8e5b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621152 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:25.431109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:25.431546) ========= script_repo_revision: a65b35c8e5b17c2585968974aef1da67a8c56642 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:25.432054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:25.432419) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 07:53:25.442422) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-26 07:53:25.442763) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:53:25 INFO - MultiFileLogger online at 20160826 07:53:25 in /builds/slave/test 07:53:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 07:53:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:53:25 INFO - {'append_to_log': False, 07:53:25 INFO - 'base_work_dir': '/builds/slave/test', 07:53:25 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 07:53:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:53:25 INFO - 'branch': 'Fx-Team-Non-PGO', 07:53:25 INFO - 'buildbot_json_path': 'buildprops.json', 07:53:25 INFO - 'config_files': ('talos/linux_config.py',), 07:53:25 INFO - 'default_actions': ('clobber', 07:53:25 INFO - 'read-buildbot-config', 07:53:25 INFO - 'download-and-extract', 07:53:25 INFO - 'populate-webroot', 07:53:25 INFO - 'create-virtualenv', 07:53:25 INFO - 'install', 07:53:25 INFO - 'run-tests'), 07:53:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:53:25 INFO - 'download_minidump_stackwalk': True, 07:53:25 INFO - 'download_symbols': 'ondemand', 07:53:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:53:25 INFO - 'tooltool.py': '/tools/tooltool.py', 07:53:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:53:25 INFO - '/tools/misc-python/virtualenv.py')}, 07:53:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:53:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:53:25 INFO - 'installer_path': 'installer.exe', 07:53:25 INFO - 'log_level': 'info', 07:53:25 INFO - 'log_name': 'talos', 07:53:25 INFO - 'log_to_console': True, 07:53:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:53:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:53:25 INFO - 'opt_config_files': (), 07:53:25 INFO - 'pip_index': False, 07:53:25 INFO - 'sps_profile': False, 07:53:25 INFO - 'sps_profile_interval': 0, 07:53:25 INFO - 'suite': 'dromaeojs', 07:53:25 INFO - 'system_bits': '32', 07:53:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:53:25 INFO - 'title': 'talos-linux64-ix-069', 07:53:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:53:25 INFO - 'use_talos_json': True, 07:53:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:53:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:53:25 INFO - 'work_dir': 'build'} 07:53:25 INFO - [mozharness: 2016-08-26 14:53:25.994286Z] Running clobber step. 07:53:25 INFO - Running pre-action listener: _resource_record_pre_action 07:53:25 INFO - Running main action method: clobber 07:53:25 INFO - rmtree: /builds/slave/test/build 07:53:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:53:26 INFO - Running post-action listener: _resource_record_post_action 07:53:26 INFO - [mozharness: 2016-08-26 14:53:26.449516Z] Finished clobber step (success) 07:53:26 INFO - [mozharness: 2016-08-26 14:53:26.449620Z] Running read-buildbot-config step. 07:53:26 INFO - Running pre-action listener: _resource_record_pre_action 07:53:26 INFO - Running main action method: read_buildbot_config 07:53:26 INFO - Using buildbot properties: 07:53:26 INFO - { 07:53:26 INFO - "project": "", 07:53:26 INFO - "product": "firefox", 07:53:26 INFO - "script_repo_revision": "production", 07:53:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 07:53:26 INFO - "repository": "", 07:53:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 07:53:26 INFO - "buildid": "20160826063820", 07:53:26 INFO - "pgo_build": "False", 07:53:26 INFO - "basedir": "/builds/slave/test", 07:53:26 INFO - "buildnumber": 2123, 07:53:26 INFO - "slavename": "talos-linux64-ix-069", 07:53:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:53:26 INFO - "platform": "ubuntu64_hw", 07:53:26 INFO - "scheduler": "tests-fx-team-linux64-talos", 07:53:26 INFO - "branch": "fx-team", 07:53:26 INFO - "repo_path": "integration/fx-team", 07:53:26 INFO - "revision": "a65b35c8e5b17c2585968974aef1da67a8c56642", 07:53:26 INFO - "stage_platform": "linux64", 07:53:26 INFO - "builduid": "849d736893ee4dd08e0dded7c8ab111a", 07:53:26 INFO - "slavebuilddir": "test" 07:53:26 INFO - } 07:53:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:53:26 INFO - Running post-action listener: _resource_record_post_action 07:53:26 INFO - [mozharness: 2016-08-26 14:53:26.452088Z] Finished read-buildbot-config step (success) 07:53:26 INFO - [mozharness: 2016-08-26 14:53:26.452179Z] Running download-and-extract step. 07:53:26 INFO - Running pre-action listener: _resource_record_pre_action 07:53:26 INFO - Running main action method: download_and_extract 07:53:26 INFO - mkdir: /builds/slave/test/build/tests 07:53: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')]} 07:53:26 INFO - trying https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:53:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:27 INFO - Downloaded 1361 bytes. 07:53:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:53:27 INFO - Using the following test package requirements: 07:53:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:53:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:53:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:53:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'jsshell-linux-x86_64.zip'], 07:53:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:53:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:53:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:53:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:53:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:53:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:53:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:53:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:53:27 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')]} 07:53:27 INFO - trying https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:53:27 INFO - Downloading https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:27 INFO - Downloaded 20303367 bytes. 07:53:27 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 07:53:32 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 07:53:32 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')]} 07:53:32 INFO - trying https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:53:32 INFO - Downloading https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:33 INFO - Downloaded 20303367 bytes. 07:53:33 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 07:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:37 INFO - trying https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:53:37 INFO - Downloading https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:38 INFO - Downloaded 14413998 bytes. 07:53:38 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 07:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:38 INFO - trying https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:53:38 INFO - Downloading https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/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 07:53:39 INFO - Downloaded 55857502 bytes. 07:53:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:53:39 INFO - mkdir: /builds/slave/test/properties 07:53:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:53:39 INFO - Writing to file /builds/slave/test/properties/build_url 07:53:39 INFO - Contents: 07:53:39 INFO - build_url:https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:53:40 INFO - Running post-action listener: _resource_record_post_action 07:53:40 INFO - Running post-action listener: set_extra_try_arguments 07:53:40 INFO - [mozharness: 2016-08-26 14:53:40.522631Z] Finished download-and-extract step (success) 07:53:40 INFO - [mozharness: 2016-08-26 14:53:40.522862Z] Running populate-webroot step. 07:53:40 INFO - Running pre-action listener: _resource_record_pre_action 07:53:40 INFO - Running main action method: populate_webroot 07:53:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:53:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:53:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:53:40 INFO - '--tppagecycles', 07:53:40 INFO - '7'], 07:53:40 INFO - 'tests': ['tsvgm']}}, 07:53:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:53:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:53:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:53:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:53:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:53:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:53:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['damp', 'tps']}, 07:53:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'tests': ['damp', 'tps']}, 07:53:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['dromaeo_dom']}, 07:53:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:53:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['basic_compositor_video']}, 07:53:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:53:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['a11yr', 07:53:40 INFO - 'ts_paint', 07:53:40 INFO - 'tpaint', 07:53:40 INFO - 'sessionrestore', 07:53:40 INFO - 'sessionrestore_no_auto_restore', 07:53:40 INFO - 'tabpaint']}, 07:53:40 INFO - 'other-e10s': {'tests': ['a11yr', 07:53:40 INFO - 'ts_paint', 07:53:40 INFO - 'tpaint', 07:53:40 INFO - 'sessionrestore', 07:53:40 INFO - 'sessionrestore_no_auto_restore', 07:53:40 INFO - 'tabpaint']}, 07:53:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:53:40 INFO - 'ts_paint', 07:53:40 INFO - 'tpaint', 07:53:40 INFO - 'sessionrestore', 07:53:40 INFO - 'sessionrestore_no_auto_restore', 07:53:40 INFO - 'tabpaint']}, 07:53:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:53:40 INFO - 'ts_paint', 07:53:40 INFO - 'tpaint', 07:53:40 INFO - 'sessionrestore', 07:53:40 INFO - 'sessionrestore_no_auto_restore', 07:53:40 INFO - 'tabpaint']}, 07:53:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['a11yr', 07:53:40 INFO - 'ts_paint', 07:53:40 INFO - 'tpaint', 07:53:40 INFO - 'sessionrestore', 07:53:40 INFO - 'sessionrestore_no_auto_restore', 07:53:40 INFO - 'tabpaint']}, 07:53:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['a11yr', 07:53:40 INFO - 'ts_paint', 07:53:40 INFO - 'tpaint', 07:53:40 INFO - 'sessionrestore', 07:53:40 INFO - 'sessionrestore_no_auto_restore', 07:53:40 INFO - 'tabpaint']}, 07:53:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['tsvgx', 07:53:40 INFO - 'tsvgr_opacity', 07:53:40 INFO - 'tart', 07:53:40 INFO - 'tscrollx', 07:53:40 INFO - 'cart']}, 07:53:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:53:40 INFO - 'tsvgr_opacity', 07:53:40 INFO - 'tart', 07:53:40 INFO - 'tscrollx', 07:53:40 INFO - 'cart']}, 07:53:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'talos_options': ['--disable-e10s'], 07:53:40 INFO - 'tests': ['tp5o']}, 07:53:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'tests': ['tp5o']}, 07:53:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'talos_options': ['--disable-e10s', 07:53:40 INFO - '--xperf_path', 07:53:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:53:40 INFO - 'tests': ['tp5n']}, 07:53:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:53:40 INFO - 'talos_options': ['--xperf_path', 07:53:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:53:40 INFO - 'tests': ['tp5n']}}, 07:53:40 INFO - 'talos.zip': {'path': '', 07:53:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:53:40 INFO - Running post-action listener: _resource_record_post_action 07:53:40 INFO - [mozharness: 2016-08-26 14:53:40.545262Z] Finished populate-webroot step (success) 07:53:40 INFO - [mozharness: 2016-08-26 14:53:40.545353Z] Running create-virtualenv step. 07:53:40 INFO - Running pre-action listener: _resource_record_pre_action 07:53:40 INFO - Running main action method: create_virtualenv 07:53:40 INFO - Creating virtualenv /builds/slave/test/build/venv 07:53:40 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 07:53:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:53:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:53:40 INFO - Using real prefix '/usr' 07:53:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:53:41 INFO - Installing distribute.............................................................................................................................................................................................done. 07:53:43 INFO - Installing pip.................done. 07:53:43 INFO - Return code: 0 07:53:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:53: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')]} 07:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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')]} 07:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:43 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53:43 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 07:53:43 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 07:53:43 INFO - Using env: {'DISPLAY': ':0', 07:53:43 INFO - 'HOME': '/home/cltbld', 07:53:43 INFO - 'LANG': 'en_US.UTF-8', 07:53:43 INFO - 'LANGUAGE': 'en_US:en', 07:53:43 INFO - 'LOGNAME': 'cltbld', 07:53:43 INFO - 'MAIL': '/var/mail/cltbld', 07:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:43 INFO - 'MOZ_NO_REMOTE': '1', 07:53:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:43 INFO - 'NO_EM_RESTART': '1', 07:53:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:43 INFO - 'PWD': '/builds/slave/test', 07:53:43 INFO - 'SHELL': '/bin/bash', 07:53:43 INFO - 'SHLVL': '1', 07:53:43 INFO - 'TERM': 'linux', 07:53:43 INFO - 'TMOUT': '86400', 07:53:43 INFO - 'USER': 'cltbld', 07:53:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:43 INFO - '_': '/tools/buildbot/bin/python'} 07:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:43 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 07:53:43 INFO - Cleaning up... 07:53:43 INFO - Return code: 0 07:53:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:53: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')]} 07:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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')]} 07:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:43 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53:43 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 07:53:43 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 07:53:43 INFO - Using env: {'DISPLAY': ':0', 07:53:43 INFO - 'HOME': '/home/cltbld', 07:53:43 INFO - 'LANG': 'en_US.UTF-8', 07:53:43 INFO - 'LANGUAGE': 'en_US:en', 07:53:43 INFO - 'LOGNAME': 'cltbld', 07:53:43 INFO - 'MAIL': '/var/mail/cltbld', 07:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:43 INFO - 'MOZ_NO_REMOTE': '1', 07:53:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:43 INFO - 'NO_EM_RESTART': '1', 07:53:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:43 INFO - 'PWD': '/builds/slave/test', 07:53:43 INFO - 'SHELL': '/bin/bash', 07:53:43 INFO - 'SHLVL': '1', 07:53:43 INFO - 'TERM': 'linux', 07:53:43 INFO - 'TMOUT': '86400', 07:53:43 INFO - 'USER': 'cltbld', 07:53:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:43 INFO - '_': '/tools/buildbot/bin/python'} 07:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:43 INFO - Downloading/unpacking psutil>=3.1.1 07:53:43 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 07:53:43 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 07:53:43 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 07:53:43 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 07:53:43 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 07:53:43 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 07:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:53:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:53:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:53:45 INFO - Installing collected packages: psutil 07:53:45 INFO - Running setup.py install for psutil 07:53:45 INFO - building 'psutil._psutil_linux' extension 07:53:45 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 07:53:45 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 07:53:45 INFO - building 'psutil._psutil_posix' extension 07:53:45 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 07:53:45 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 07:53:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:53:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:53:46 INFO - Successfully installed psutil 07:53:46 INFO - Cleaning up... 07:53:46 INFO - Return code: 0 07:53:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:53:46 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')]} 07:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:46 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')]} 07:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:46 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53:46 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 07:53:46 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 07:53:46 INFO - Using env: {'DISPLAY': ':0', 07:53:46 INFO - 'HOME': '/home/cltbld', 07:53:46 INFO - 'LANG': 'en_US.UTF-8', 07:53:46 INFO - 'LANGUAGE': 'en_US:en', 07:53:46 INFO - 'LOGNAME': 'cltbld', 07:53:46 INFO - 'MAIL': '/var/mail/cltbld', 07:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:46 INFO - 'MOZ_NO_REMOTE': '1', 07:53:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:46 INFO - 'NO_EM_RESTART': '1', 07:53:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:46 INFO - 'PWD': '/builds/slave/test', 07:53:46 INFO - 'SHELL': '/bin/bash', 07:53:46 INFO - 'SHLVL': '1', 07:53:46 INFO - 'TERM': 'linux', 07:53:46 INFO - 'TMOUT': '86400', 07:53:46 INFO - 'USER': 'cltbld', 07:53:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:46 INFO - '_': '/tools/buildbot/bin/python'} 07:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:53:46 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 07:53:46 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 07:53:46 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 07:53:46 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 07:53:46 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 07:53:46 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 07:53:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:53:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:53:48 INFO - Installing collected packages: mozsystemmonitor 07:53:48 INFO - Running setup.py install for mozsystemmonitor 07:53:48 INFO - Successfully installed mozsystemmonitor 07:53:48 INFO - Cleaning up... 07:53:48 INFO - Return code: 0 07:53:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:53: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')]} 07:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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')]} 07:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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', '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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:53: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 jsonschema==2.5.1 07:53:48 INFO - Using env: {'DISPLAY': ':0', 07:53:48 INFO - 'HOME': '/home/cltbld', 07:53:48 INFO - 'LANG': 'en_US.UTF-8', 07:53:48 INFO - 'LANGUAGE': 'en_US:en', 07:53:48 INFO - 'LOGNAME': 'cltbld', 07:53:48 INFO - 'MAIL': '/var/mail/cltbld', 07:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:48 INFO - 'MOZ_NO_REMOTE': '1', 07:53:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:48 INFO - 'NO_EM_RESTART': '1', 07:53:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:48 INFO - 'PWD': '/builds/slave/test', 07:53:48 INFO - 'SHELL': '/bin/bash', 07:53:48 INFO - 'SHLVL': '1', 07:53:48 INFO - 'TERM': 'linux', 07:53:48 INFO - 'TMOUT': '86400', 07:53:48 INFO - 'USER': 'cltbld', 07:53:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:48 INFO - '_': '/tools/buildbot/bin/python'} 07:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:48 INFO - Downloading/unpacking jsonschema==2.5.1 07:53: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 07:53: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 07:53: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 07:53: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 07:53: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 07:53: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 07:53:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:53:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:50 INFO - Downloading functools32-3.2.3-2.tar.gz 07:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:53:50 INFO - warning: no files found matching '*.txt' 07:53:50 INFO - no previously-included directories found matching 'build' 07:53:50 INFO - no previously-included directories found matching 'dist' 07:53:50 INFO - no previously-included directories found matching '.git*' 07:53:50 INFO - Installing collected packages: jsonschema, functools32 07:53:50 INFO - Running setup.py install for functools32 07:53:50 INFO - warning: no files found matching '*.txt' 07:53:50 INFO - no previously-included directories found matching 'build' 07:53:50 INFO - no previously-included directories found matching 'dist' 07:53:50 INFO - no previously-included directories found matching '.git*' 07:53:50 INFO - Successfully installed jsonschema functools32 07:53:50 INFO - Cleaning up... 07:53:50 INFO - Return code: 0 07:53:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:53:50 INFO - Using env: {'DISPLAY': ':0', 07:53:50 INFO - 'HOME': '/home/cltbld', 07:53:50 INFO - 'LANG': 'en_US.UTF-8', 07:53:50 INFO - 'LANGUAGE': 'en_US:en', 07:53:50 INFO - 'LOGNAME': 'cltbld', 07:53:50 INFO - 'MAIL': '/var/mail/cltbld', 07:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:50 INFO - 'MOZ_NO_REMOTE': '1', 07:53:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:50 INFO - 'NO_EM_RESTART': '1', 07:53:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:50 INFO - 'PWD': '/builds/slave/test', 07:53:50 INFO - 'SHELL': '/bin/bash', 07:53:50 INFO - 'SHLVL': '1', 07:53:50 INFO - 'TERM': 'linux', 07:53:50 INFO - 'TMOUT': '86400', 07:53:50 INFO - 'USER': 'cltbld', 07:53:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:50 INFO - '_': '/tools/buildbot/bin/python'} 07:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:53:51 INFO - Cleaning up... 07:53:51 INFO - Return code: 0 07:53:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:53: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')]} 07:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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')]} 07:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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', '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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:53: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 blobuploader==1.2.4 07:53:51 INFO - Using env: {'DISPLAY': ':0', 07:53:51 INFO - 'HOME': '/home/cltbld', 07:53:51 INFO - 'LANG': 'en_US.UTF-8', 07:53:51 INFO - 'LANGUAGE': 'en_US:en', 07:53:51 INFO - 'LOGNAME': 'cltbld', 07:53:51 INFO - 'MAIL': '/var/mail/cltbld', 07:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:51 INFO - 'MOZ_NO_REMOTE': '1', 07:53:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:51 INFO - 'NO_EM_RESTART': '1', 07:53:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:51 INFO - 'PWD': '/builds/slave/test', 07:53:51 INFO - 'SHELL': '/bin/bash', 07:53:51 INFO - 'SHLVL': '1', 07:53:51 INFO - 'TERM': 'linux', 07:53:51 INFO - 'TMOUT': '86400', 07:53:51 INFO - 'USER': 'cltbld', 07:53:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:51 INFO - '_': '/tools/buildbot/bin/python'} 07:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:51 INFO - Downloading/unpacking blobuploader==1.2.4 07:53:51 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 07:53:51 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 07:53:51 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 07:53:51 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 07:53:51 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 07:53:51 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 07:53:53 INFO - Downloading blobuploader-1.2.4.tar.gz 07:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:53:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:53:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:53 INFO - Downloading docopt-0.6.1.tar.gz 07:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:53:54 INFO - Installing collected packages: blobuploader, requests, docopt 07:53:54 INFO - Running setup.py install for blobuploader 07:53:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:53:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:53:54 INFO - Running setup.py install for requests 07:53:54 INFO - Running setup.py install for docopt 07:53:54 INFO - Successfully installed blobuploader requests docopt 07:53:54 INFO - Cleaning up... 07:53:54 INFO - Return code: 0 07:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:53: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')]} 07:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53: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')]} 07:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:54 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53:54 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 07:53:54 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 07:53:54 INFO - Using env: {'DISPLAY': ':0', 07:53:54 INFO - 'HOME': '/home/cltbld', 07:53:54 INFO - 'LANG': 'en_US.UTF-8', 07:53:54 INFO - 'LANGUAGE': 'en_US:en', 07:53:54 INFO - 'LOGNAME': 'cltbld', 07:53:54 INFO - 'MAIL': '/var/mail/cltbld', 07:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:54 INFO - 'MOZ_NO_REMOTE': '1', 07:53:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:54 INFO - 'NO_EM_RESTART': '1', 07:53:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:54 INFO - 'PWD': '/builds/slave/test', 07:53:54 INFO - 'SHELL': '/bin/bash', 07:53:54 INFO - 'SHLVL': '1', 07:53:54 INFO - 'TERM': 'linux', 07:53:54 INFO - 'TMOUT': '86400', 07:53:54 INFO - 'USER': 'cltbld', 07:53:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:54 INFO - '_': '/tools/buildbot/bin/python'} 07:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:53:54 INFO - Running setup.py (path:/tmp/pip-oBwvDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:53:54 INFO - Running setup.py (path:/tmp/pip-cEWuIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:53:54 INFO - Running setup.py (path:/tmp/pip-9h2bCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:53:55 INFO - Running setup.py (path:/tmp/pip-39zfH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:53:55 INFO - Running setup.py (path:/tmp/pip-wgSi8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:53:55 INFO - Running setup.py (path:/tmp/pip-NemP_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:53:55 INFO - Running setup.py (path:/tmp/pip-nzVIkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:53:55 INFO - Running setup.py (path:/tmp/pip-rI5oqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:53:55 INFO - Running setup.py (path:/tmp/pip-GKISbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:53:55 INFO - Running setup.py (path:/tmp/pip-ipMmFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:53:55 INFO - Running setup.py (path:/tmp/pip-_iYjoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:53:55 INFO - Running setup.py (path:/tmp/pip-EY3sg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:53:55 INFO - Running setup.py (path:/tmp/pip-qbRf_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:53:55 INFO - Running setup.py (path:/tmp/pip-GVutvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:53:55 INFO - Running setup.py (path:/tmp/pip-CA12M9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:53:55 INFO - Running setup.py (path:/tmp/pip-fShk3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:53:55 INFO - Running setup.py (path:/tmp/pip-ilzCnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:53:56 INFO - Running setup.py install for manifestparser 07:53:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Running setup.py install for mozcrash 07:53:56 INFO - Running setup.py install for mozdebug 07:53:56 INFO - Running setup.py install for mozdevice 07:53:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Running setup.py install for mozfile 07:53:56 INFO - Running setup.py install for mozhttpd 07:53:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Running setup.py install for mozinfo 07:53:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Running setup.py install for mozInstall 07:53:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:53:56 INFO - Running setup.py install for mozleak 07:53:56 INFO - Running setup.py install for mozlog 07:53:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Running setup.py install for moznetwork 07:53:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Running setup.py install for mozprocess 07:53:57 INFO - Running setup.py install for mozprofile 07:53:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Running setup.py install for mozrunner 07:53:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Running setup.py install for mozscreenshot 07:53:57 INFO - Running setup.py install for moztest 07:53:57 INFO - Running setup.py install for mozversion 07:53:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:53:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:53:57 INFO - Cleaning up... 07:53:57 INFO - Return code: 0 07:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:53:57 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:53:57 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 07:53:57 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 07:53:57 INFO - Using env: {'DISPLAY': ':0', 07:53:57 INFO - 'HOME': '/home/cltbld', 07:53:57 INFO - 'LANG': 'en_US.UTF-8', 07:53:57 INFO - 'LANGUAGE': 'en_US:en', 07:53:57 INFO - 'LOGNAME': 'cltbld', 07:53:57 INFO - 'MAIL': '/var/mail/cltbld', 07:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:57 INFO - 'MOZ_NO_REMOTE': '1', 07:53:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:53:57 INFO - 'NO_EM_RESTART': '1', 07:53:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:57 INFO - 'PWD': '/builds/slave/test', 07:53:57 INFO - 'SHELL': '/bin/bash', 07:53:57 INFO - 'SHLVL': '1', 07:53:57 INFO - 'TERM': 'linux', 07:53:57 INFO - 'TMOUT': '86400', 07:53:57 INFO - 'USER': 'cltbld', 07:53:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:57 INFO - '_': '/tools/buildbot/bin/python'} 07:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:53:58 INFO - Running setup.py (path:/tmp/pip-M2w0id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:53:58 INFO - Running setup.py (path:/tmp/pip-PjG4XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:53:58 INFO - Running setup.py (path:/tmp/pip-dSdISP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:53:58 INFO - Running setup.py (path:/tmp/pip-tNmLpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:53:58 INFO - Running setup.py (path:/tmp/pip-cFZNh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:53:58 INFO - Running setup.py (path:/tmp/pip-J1rXAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:53:58 INFO - Running setup.py (path:/tmp/pip-vOQZop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:53:58 INFO - Running setup.py (path:/tmp/pip-VgcIkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:53:58 INFO - Running setup.py (path:/tmp/pip-UmIPWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:53:58 INFO - Running setup.py (path:/tmp/pip-q7L5G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:53:58 INFO - Running setup.py (path:/tmp/pip-Vx4Ax7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:53:58 INFO - Running setup.py (path:/tmp/pip-rK08ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:53:58 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)) 07:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:53:58 INFO - Running setup.py (path:/tmp/pip-Hlj8SX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:53:59 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)) 07:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:53:59 INFO - Running setup.py (path:/tmp/pip-6uufsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:53:59 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)) 07:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:53:59 INFO - Running setup.py (path:/tmp/pip-rtu6uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:53:59 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)) 07:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:53:59 INFO - Running setup.py (path:/tmp/pip-7OKurd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:53:59 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)) 07:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:53:59 INFO - Running setup.py (path:/tmp/pip-3M_Id9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:53:59 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)) 07:53:59 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)) 07:53:59 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)) 07:53:59 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)) 07:53:59 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)) 07:53:59 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)) 07:53:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:53:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:01 INFO - Downloading blessings-1.6.tar.gz 07:54:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:54:01 INFO - Installing collected packages: blessings 07:54:01 INFO - Running setup.py install for blessings 07:54:01 INFO - Successfully installed blessings 07:54:01 INFO - Cleaning up... 07:54:01 INFO - Return code: 0 07:54:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:01 INFO - Reading from file tmpfile_stdout 07:54:01 INFO - Current package versions: 07:54:01 INFO - argparse == 1.2.1 07:54:01 INFO - blessings == 1.6 07:54:01 INFO - blobuploader == 1.2.4 07:54:01 INFO - docopt == 0.6.1 07:54:01 INFO - functools32 == 3.2.3-2 07:54:01 INFO - jsonschema == 2.5.1 07:54:01 INFO - manifestparser == 1.1 07:54:01 INFO - mozInstall == 1.12 07:54:01 INFO - mozcrash == 0.17 07:54:01 INFO - mozdebug == 0.1 07:54:01 INFO - mozdevice == 0.48 07:54:01 INFO - mozfile == 1.2 07:54:01 INFO - mozhttpd == 0.7 07:54:01 INFO - mozinfo == 0.9 07:54:01 INFO - mozleak == 0.1 07:54:01 INFO - mozlog == 3.3 07:54:01 INFO - moznetwork == 0.27 07:54:01 INFO - mozprocess == 0.23 07:54:01 INFO - mozprofile == 0.28 07:54:01 INFO - mozrunner == 6.12 07:54:01 INFO - mozscreenshot == 0.1 07:54:01 INFO - mozsystemmonitor == 0.3 07:54:01 INFO - moztest == 0.7 07:54:01 INFO - mozversion == 1.4 07:54:01 INFO - psutil == 3.1.1 07:54:01 INFO - requests == 1.2.3 07:54:01 INFO - wsgiref == 0.1.2 07:54:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:01 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')]} 07:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:01 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')]} 07:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:01 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:54:01 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 07:54:01 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 07:54:01 INFO - Using env: {'DISPLAY': ':0', 07:54:01 INFO - 'HOME': '/home/cltbld', 07:54:01 INFO - 'LANG': 'en_US.UTF-8', 07:54:01 INFO - 'LANGUAGE': 'en_US:en', 07:54:01 INFO - 'LOGNAME': 'cltbld', 07:54:01 INFO - 'MAIL': '/var/mail/cltbld', 07:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:01 INFO - 'MOZ_NO_REMOTE': '1', 07:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:01 INFO - 'NO_EM_RESTART': '1', 07:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:01 INFO - 'PWD': '/builds/slave/test', 07:54:01 INFO - 'SHELL': '/bin/bash', 07:54:01 INFO - 'SHLVL': '1', 07:54:01 INFO - 'TERM': 'linux', 07:54:01 INFO - 'TMOUT': '86400', 07:54:01 INFO - 'USER': 'cltbld', 07:54:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:01 INFO - '_': '/tools/buildbot/bin/python'} 07:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 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)) 07:54:01 INFO - Cleaning up... 07:54:01 INFO - Return code: 0 07:54:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:54:01 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')]} 07:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:01 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')]} 07:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:01 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 0x7f3a7ffcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e22230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', '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 07:54:01 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 07:54:01 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 07:54:01 INFO - Using env: {'DISPLAY': ':0', 07:54:01 INFO - 'HOME': '/home/cltbld', 07:54:01 INFO - 'LANG': 'en_US.UTF-8', 07:54:01 INFO - 'LANGUAGE': 'en_US:en', 07:54:01 INFO - 'LOGNAME': 'cltbld', 07:54:01 INFO - 'MAIL': '/var/mail/cltbld', 07:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:01 INFO - 'MOZ_NO_REMOTE': '1', 07:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:01 INFO - 'NO_EM_RESTART': '1', 07:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:01 INFO - 'PWD': '/builds/slave/test', 07:54:01 INFO - 'SHELL': '/bin/bash', 07:54:01 INFO - 'SHLVL': '1', 07:54:01 INFO - 'TERM': 'linux', 07:54:01 INFO - 'TMOUT': '86400', 07:54:01 INFO - 'USER': 'cltbld', 07:54:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:01 INFO - '_': '/tools/buildbot/bin/python'} 07:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:54:02 INFO - Cleaning up... 07:54:02 INFO - Return code: 0 07:54:02 INFO - Running post-action listener: _resource_record_post_action 07:54:02 INFO - Running post-action listener: _start_resource_monitoring 07:54:02 INFO - Starting resource monitoring. 07:54:02 INFO - [mozharness: 2016-08-26 14:54:02.094687Z] Finished create-virtualenv step (success) 07:54:02 INFO - [mozharness: 2016-08-26 14:54:02.095148Z] Running install step. 07:54:02 INFO - Running pre-action listener: _resource_record_pre_action 07:54:02 INFO - Running main action method: install 07:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:02 INFO - Reading from file tmpfile_stdout 07:54:02 INFO - Detecting whether we're running mozinstall >=1.0... 07:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:54:02 INFO - Reading from file tmpfile_stdout 07:54:02 INFO - Output received: 07:54:02 INFO - Usage: mozinstall [options] installer 07:54:02 INFO - Options: 07:54:02 INFO - -h, --help show this help message and exit 07:54:02 INFO - -d DEST, --destination=DEST 07:54:02 INFO - Directory to install application into. [default: 07:54:02 INFO - "/builds/slave/test"] 07:54:02 INFO - --app=APP Application being installed. [default: firefox] 07:54:02 INFO - mkdir: /builds/slave/test/build/application 07:54:02 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'] 07:54:02 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 07:54:18 INFO - Reading from file tmpfile_stdout 07:54:18 INFO - Output received: 07:54:18 INFO - /builds/slave/test/build/application/firefox/firefox 07:54:18 INFO - Running post-action listener: _resource_record_post_action 07:54:18 INFO - [mozharness: 2016-08-26 14:54:18.542841Z] Finished install step (success) 07:54:18 INFO - [mozharness: 2016-08-26 14:54:18.543131Z] Running run-tests step. 07:54:18 INFO - Running pre-action listener: _resource_record_pre_action 07:54:18 INFO - Running main action method: run_tests 07:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:54:18 INFO - Python 2.7.3 07:54:18 INFO - Return code: 0 07:54:18 INFO - grabbing minidump binary from tooltool 07:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1c090>, '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 0x1e1f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fe40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:54:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:54:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:54:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:54:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:54:18 INFO - Return code: 0 07:54:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:54:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:54:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:54:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:54:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 07:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 07:54:18 INFO - Using env: {'DISPLAY': ':0', 07:54:18 INFO - 'HOME': '/home/cltbld', 07:54:18 INFO - 'LANG': 'en_US.UTF-8', 07:54:18 INFO - 'LANGUAGE': 'en_US:en', 07:54:18 INFO - 'LOGNAME': 'cltbld', 07:54:18 INFO - 'MAIL': '/var/mail/cltbld', 07:54:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:54:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:18 INFO - 'MOZ_NO_REMOTE': '1', 07:54:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:18 INFO - 'NO_EM_RESTART': '1', 07:54:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:18 INFO - 'PWD': '/builds/slave/test', 07:54:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:54:18 INFO - 'SHELL': '/bin/bash', 07:54:18 INFO - 'SHLVL': '1', 07:54:18 INFO - 'TERM': 'linux', 07:54:18 INFO - 'TMOUT': '86400', 07:54:18 INFO - 'USER': 'cltbld', 07:54:18 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389', 07:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:18 INFO - '_': '/tools/buildbot/bin/python'} 07:54:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 07:54:18 INFO - mozversion application_buildid: 20160826063820 07:54:18 INFO - mozversion application_changeset: a65b35c8e5b17c2585968974aef1da67a8c56642 07:54:18 INFO - mozversion application_display_name: Nightly 07:54:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:54:18 INFO - mozversion application_name: Firefox 07:54:18 INFO - mozversion application_remotingname: firefox 07:54:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:54:18 INFO - mozversion application_vendor: Mozilla 07:54:18 INFO - mozversion application_version: 51.0a1 07:54:18 INFO - mozversion platform_buildid: 20160826063820 07:54:18 INFO - mozversion platform_changeset: a65b35c8e5b17c2585968974aef1da67a8c56642 07:54:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:54:18 INFO - mozversion platform_version: 51.0a1 07:54:18 INFO - using testdate: 1472223258 07:54:18 INFO - actual date: 1472223258 07:54:18 INFO - starting webserver on 'localhost:45691' 07:54:18 INFO - SUITE-START | Running 2 tests 07:54:18 INFO - TEST-START | dromaeo_css 07:54:18 INFO - operating with platform_type : linux_ 07:54:18 INFO - Initialising browser for dromaeo_css test... 07:54:18 INFO - TEST-INFO | started process 20721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkB6_nB/profile http://localhost:45691/getInfo.html) 07:54:23 INFO - PROCESS | 20721 | __metrics Screen width/height:1600/1200 07:54:23 INFO - PROCESS | 20721 | colorDepth:24 07:54:23 INFO - PROCESS | 20721 | Browser inner width/height: 1024/697 07:54:23 INFO - PROCESS | 20721 | __metrics 07:54:24 INFO - PROCESS | 20721 | 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" 07:54:24 INFO - PROCESS | 20721 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:54:24 INFO - PROCESS | 20721 | console.error: PushService: 07:54:24 INFO - PROCESS | 20721 | stateChangeProcessEnqueue: Error transitioning state 07:54:24 INFO - PROCESS | 20721 | UnknownError 07:54:25 INFO - TEST-INFO | 20721: exit 0 07:54:25 INFO - Browser initialized. 07:54:25 INFO - Running cycle 1/1 for dromaeo_css test... 07:54:25 INFO - TEST-INFO | started process 20800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkB6_nB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:54:37 INFO - PROCESS | 20800 | RSS: Main: 180912128 07:54:37 INFO - PROCESS | 20800 | 07:55:28 INFO - PROCESS | 20800 | 1472223328677 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] 07:57:42 INFO - PROCESS | 20800 | Cycle 1(1): loaded http://localhost:45691/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45691/tests/dromaeo/cssquery-ext.html) 07:57:43 INFO - PROCESS | 20800 | RSS: Main: 279977984 07:57:43 INFO - PROCESS | 20800 | 08:00:46 INFO - PROCESS | 20800 | Cycle 1(1): loaded http://localhost:45691/tests/dromaeo/cssquery-ext.html (next: http://localhost:45691/tests/dromaeo/cssquery-jquery.html) 08:00:46 INFO - PROCESS | 20800 | RSS: Main: 271560704 08:00:46 INFO - PROCESS | 20800 | 08:03:48 INFO - PROCESS | 20800 | Cycle 1(1): loaded http://localhost:45691/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45691/tests/dromaeo/cssquery-mootools.html) 08:03:49 INFO - PROCESS | 20800 | RSS: Main: 258768896 08:03:49 INFO - PROCESS | 20800 | 08:06:51 INFO - PROCESS | 20800 | Cycle 1(1): loaded http://localhost:45691/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45691/tests/dromaeo/cssquery-prototype.html) 08:06:52 INFO - PROCESS | 20800 | RSS: Main: 218263552 08:06:52 INFO - PROCESS | 20800 | 08:09:55 INFO - PROCESS | 20800 | Cycle 1(1): loaded http://localhost:45691/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45691/tests/dromaeo/cssquery-yui.html) 08:09:55 INFO - PROCESS | 20800 | RSS: Main: 217436160 08:09:55 INFO - PROCESS | 20800 | 08:12:58 INFO - PROCESS | 20800 | Cycle 1(1): loaded http://localhost:45691/tests/dromaeo/cssquery-yui.html (next: http://localhost:45691/tests/dromaeo/cssquery-dojo.html) 08:12:59 INFO - PROCESS | 20800 | RSS: Main: 306483200 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | __start_tp_report 08:12:59 INFO - PROCESS | 20800 | _x_x_mozilla_page_load 08:12:59 INFO - PROCESS | 20800 | _x_x_mozilla_page_load_details 08:12:59 INFO - PROCESS | 20800 | |i|pagename|runs| 08:12:59 INFO - PROCESS | 20800 | |0;dojo.html;2588.4305452800377;2590.7020692620354;2594.3643807267217;2602.063257227398;2628.592568151936;4642.419697537808;4690.319903613974;4709.105269998698;4763.333133361328;4807;4708.52914708529;4748.643851711126;4767.11808315461;4786.21027530457;4842.733649649268;4192.287311157095;4261.147770445908;4321.222180007598;4405.008873003565;4446.110777844444;4355.84754533593;4373.890652733691;4394.362817391477;4401.493828209765;4427.1367083418745;4259.5740425957465;4292.763897985609;4301.225779359714;4339.1972485090355;4390.341448782676;4333.978330108361;4350.58669426404;4376.5623437656295;4422;4438.644908407319;4335;4348.608625223714;4394.780260986938;4402.427684401039;4405.933910991338;4774.64190185734;4805.063012712522;4819.2530157825595;4822.493638167977;4876.414830220397;4937.679050861682;5086.54221120101;5135.152699804527;5148.176291793333;5152.278680984665;5148.819791307286;5169.043726910496;5171.4311425743135;5173.611979101583;5176.8964620707375;4648.790804413809;4805.591524720403;4820.180569303227;4881.560659540658;4884.4871288514905;5182;5229.6339256251695;5286.577073834084;5289.2859463972645;5378.731063446812;5066.771995260222;5077.771500282496;5078.13671675816;5078.415982162099;5143.074246635567;4948.950510494921;5153.4073581538605;5157.793688252503;5163.4062082860955;5166.689998600096;5037.19404895215;5067.265246539305;5086.72023038729;5101.846944591668;5170;5043.899122017612;5053.166227572409;5053.67151135175;5057.570106540912;5112.8721781955;4202.684798640114;4340.175366680321;4354;4355.06366131277;4415.139047885696;3692.649198326155;3764.6800021997888;3782.905427364283;3813.9046523836573;3879.3792993120965;4375.2780791169735;4402.229609818269;4404.339349097661;4407;4463.687541872037;5153.742312884341;5166.5866730661455;5167.974160129251;5169.353830771153;5229.6339256251695;4262.50981137167;4365.083332500147;4397.582229688174;4423.424954755861;4480.171168333869;4611.123886461562;4616.422947131608;4630.259158534618;4633.582977531971;4683.086798074411;4847.200211964986;4855.975720121377;4858.222684370625;4864.391951006124;4916.631252655993;4895.828645997373;5120.283160357628;5179.067767801832;5183.0670479314085;5257.921131182959;4847.175980083323;4868.513148685103;4873.512648735098;4895.388076490439;4928.6057115431395;5092.032513822362;5092.821751238689;5095.490450954875;5113.130767769414;5169.172932330809;4940.431850337113;4972.9254061188385;5083.364579427572;5124.12889808726;5124.5131712487255;5019.598432125495;5020.899582008413;5022.045811295842;5023.372078490189;5074.238864170256;5019.196928491424;5019.899602008012;5026.045051440215;5027.421846487555;5061.898762024813;4818.084563932842;4887.047025829929;4931.654784165074;4942.332785073969;4944.970616338959;5056.241563765465;5057.342545469065;5057.797688092583;5060.367454068241;5105;4917.459079501324;4944.1100601892;4945.925811112908;4949.802007919643;4985.426675932169 08:12:59 INFO - PROCESS | 20800 | |1;ext.html;552.2434265056872;554.1798138754643;560.4031706232862;571.2459553389525;573.3406582430205;27447.45105097897;39072;40350.798246008766;40734.59265407345;43932.341014884805;39926.20147597046;40264.19471610566;40312.395314070236;40338.59661403392;40446;7294.525855819381;7636.961815190916;7762.146163921964;7792.844143117134;7796.649150788228;9750.756231094208;10069.69790906274;10093.041161089684;10416.843747343795;10432.68701938943;10065;10169.491525423737;10173.898261017406;10175.949120254425;10180.083792458678;10005.19958403326;10037.648682296158;10068.04353586408;10071.848922266208;10074.9496252519;9910.157636600898;9917.702468925943;9940.353876997971;9975.251856110748;9983.950080249624;37295.62704372949;37529.81235093807;37567.81216093929;37583.684571039885;37614.62385376138;3010.5333673280675;3128.7340576051065;3130.8904188353526;3289.5230191622322;3377.3245350929665;28652.283692907637;29314.560281595794;29534.40931181386;29593.081383723125;30045.399092018037;3408.6761757633235;3409;3445.620981692012;3471.3230919970615;3474.270403215328;236188;236594;238837;239522;240434.7978260115;30715.69284307173;51683.741581291855;52120.739396303536;52143.478565214624;52163.21755173676;31693.207669807976;33066.504002439986;33171;33224.521218362184;33238.50142247868;13470.057096003382;13583.388747506386;13619.250941198132;13634.1137826041;13641;10062.74843128913;10944.945275273572;11052.392118433403;11116.944415278034;11153.275037122723;7746.295814920709;7816.140224575292;7847.489913155627;7866.646000929857;7875.212478752193;3000.8649610767566;3057.4955132402906;3065.1877252528097;3066.126154046131;3128.2335827722354;816.9223923727236;863.3567991845989;883.0463099852144;996.5216695985822;999.9650012249682;27270.863645681646;27687.30781730473;27713.307167320978;27840.86079569589;28269.858650706614;910.276330317386;956.6269155029544;967.1585720423136;976.4580657734883;989.9406035637885;13911.86088139126;14043.71912561743;14312.892006777502;14387.78418323726;14828.851711482963;360045;364084;364228;364686;366875;47148.764256178496;47718;47765.761171193924;47786.522134778905;47927.520724793;1047.2302857399804;1148.7530181011123;1151.5796734192127;1180.8228765684873;1180.8701042885275;26575.202743917715;28173.43653126885;28676.71323286824;28943.6060365366;29488.852555737085;41486;41825;41871.58128418677;41977.580224197365;42037.159256814084;286064;286389;286527;287463;288702;25164.49671006606;26065.609015864396;26113.869430652725;26433.603495947962;26536;41217.175656486106;41714.58285417107;41801;43043.7847810759;47310.76344618255;36296.45555316725;36502;36613.63386366102;36715;37116.05076502492;35576.154071601944;36519.63480365162;36780.63219367879;37496.81251593724;37530;34900.47649285212;35506.822465887504;35638.28723425569;35742.4638630426;36349;31028.53457198099;32438.189045274055;33123.171920702174;33243.66756332503;33327.66672333342;24176.879115604308;25036.74963250344;25192.016888503174;25305.493890122463;25810.483790324462 08:12:59 INFO - PROCESS | 20800 | |2;jquery.html;327.79349010123616;344.2186237241462;344.2753004924634;349.90027842065007;369.3425702249996;13131.409086591102;15882.523524294274;16103.83896161038;17234;18355.449336519894;14681.926590367033;15232.086074835517;15243.695126097498;16337.754933676004;16692.332306707718;4395.824167033315;5007.874803129933;5138.871528211787;5196.428392876781;5209.505097015796;5100.132977393852;5167.870803229931;5175.24958880962;5177.689338639675;5182.637215394905;4898;5031.421386540558;5063.392392912856;5137.178051511759;5142.10013247682;5020.5983521318385;5063.265826455162;5070.29015937769;5072.721000344961;5073.441921388644;4354.673399495051;4527.456705195397;4531.977340113312;4532.79602417892;4580.381648477446;21055.789442105535;21122.683159752614;21183.152673893026;21344.786552134592;21490.355289341343;1889.4331700489936;1890.5651700109051;2045.2841505473114;2081.906314215864;2157.234181865431;12420.503179872794;13266.933665331611;13382.13016153947;13478.865211347858;13761.449542018307;1698.210332195535;1703.8040625328122;1745.7206846904562;1795.5062357851564;1799.9190036448392;70264.64867675629;70445;71003.28996710018;72251.63874180596;72400.27599723988;8969.820603587892;9067.319951003701;9198.540072996457;9237.307201959746;9516.714498565054;12333.260004399765;12386.265922770819;12396;12418.81371779424;12463.563775268003;7026.894596581055;7248.710051597877;7261.41908647307;7466.25337466249;7507.887381689279;6997.895031574531;7054.6825392857445;7164;7183.173934997438;7241.312075352833;5809.3609702932645;5824.534037277008;5860.560457965669;5903.144044113666;6010.158577799111;1596.880233982456;1605.8474444927713;1681.2014293210627;1700.3336730004664;1775.7780277465317;712.8930660400981;835.778518692547;879.6261588824724;885.1369914333602;890.7149712092198;16674.49976500707;16934.57663558421;17352.739708904377;17597.7360339595;17902.10489475521;810.3233799777134;826.4008593769469;837.1921096142252;854.747849384433;953.142172045151;9220.631174753069;9803.705888823346;9884.258680598983;9962.950185249027;10238.18094552434;369396;375687;376635.2850593189;382052;384180;67388.6630566844;68798;69382.65308673424;69692.65153674301;73107.63446182842;862.1723145779996;880.9075047120089;893.2942975049768;933.6125507914276;940.4545363689044;14347.92826035863;15518.224088795741;15832.28754706064;16278.755818662967;16385.34458621642;30383.240418989702;34707.82646086753;35278.47082293716;35950.460743089396;40162.79918600388;47483;51363;51851.22223166731;51989.7400512995;53468.73265633803;7546.396288297033;7547.660355283915;7825.826126081133;8049.597520124087;8324.125966773401;11462.656120316404;12286.938565307117;12316;12618.936905315415;12794.313194229317;10582.312149710553;10623.681289561326;10743;10764.623238186627;11285.839964809866;10397.272190946562;10640.201984851323;10717.410542420243;10759.3544387337;11008.669739907815;10373.896261037595;10444.42555659446;10528.36829790215;10823.67528974132;11075.390853503135;7777.72777952769;7852.371810255052;7969.481983671275;7987.760367188994;8026.237507436784;10436.478176091014;10472.476376181008;10585.9470702646;10718.570057581499;11084.778304434027 08:12:59 INFO - PROCESS | 20800 | |3;mootools.html;1356.3015047250665;1398.7482253194423;1463.5170393770056;1637.3614290426729;1700.1159397113502;6738.12404387429;7317.59753213573;7450.143233528146;7937.404694647903;7944.165862584425;7489.662965166567;7832.099308579515;7843.33331666809;7846.646900889459;7847.607619619025;2173.1959175105194;2197.5934452126326;2202.0861342542826;2202.8458007939453;2211.7456492503406;1831.7618709567737;2114.95770084599;2120.098957942876;2164.7835216478306;2176.880271585062;2190.244365693833;2216.855904366219;2216.9334919952425;2218.157100301883;2219.844610877239;2119.0676102514844;2121.809037186653;2175.0755928730387;2175.695402643631;2192.079326682781;2123.7557680866744;2125.2242931330106;2151.4836439254623;2157.503774131943;2159.6436587962967;7752.844943101163;7861.528308301463;7864;7871.645775940039;7871.724489642892;4491.595756381941;4587;4610.124076425468;4617.8152873885;4619.399478067863;2888.653361596601;2992.341684829334;3067.662557118715;3068.0642404066753;3081.6456107547697;897.3404547657467;944.8441007233798;945.3429866242964;952.8570714392896;957.9042095790435;10622.681319560426;10661.360318380923;10745.355278683226;10765.407902565355;10776.892231077667;4042.959570404317;4106.281400754856;4186.455760751083;4261.680373971964;4281.507626622916;8945.642174313085;8951.32865035125;8978.057303983118;8986.14631609996;9011;4639.466461356988;4639.907201855944;4641.303804429363;4641.907161856744;4642.721436713808;4125.319322311785;4239.59723826236;4249.086446414036;4251.086016506404;4252.936205956913;3576.481410195507;3626.67892548769;3640.308341415123;3668.6331366863633;3678.9448158277646;1036.99481502592;1094.5020015892749;1122.5565901468874;1146.4898120336536;1170.5610396101463;637.7002808679913;788.5268838696851;795.9641816118288;796.8286818334086;808.5391326943703;4802.327674125625;4844.103840789395;4891.706497610084;4892.19278818998;4912.606991440676;824.8658095119212;860.8493513635088;861;874.8643960186181;895.4269267668698;3349.748768842298;3371.325734853039;3424.6404127566248;3442.7417943654327;3492.5459690240614;9279.211267042378;9631.614735410505;9712.708618741308;9721.319507634398;9737.123658870736;9654.613815447445;9663.033696630422;9666.661666841626;9671.758206044906;9676.516174191262;7273.745418910418;7347.118345798538;7348.375388092072;7349.963250183715;7353.227911069261;5362;5369.838904832861;5509.283793106871;5624.662520248635;5804.767809287581;8787.25308348772;8835.116488351114;8872.24585910205;8932.910670893469;9012.954935225282;8644.740657780276;8989.865152022856;8997.64009439615;9017.819643607223;9114.225290850352;6505.154329937078;6654.600723956578;6670.533062685566;6673.833154171185;6690.83272918181;7232.674529646073;7245;7280.381167600814;7285;7295.416366690546;6470.579412338183;6578.375054369827;6720.6975686093265;6784.253732089367;6879.3120687930805;6613.305602911625;6668.06647069401;6688.297728738413;6698.83252918681;6708.127943367331;5989;6086.695665216633;6087.726052327567;6088.330283668704;6097.969510152421;4547.772611369484;4586.19741545235;4690.061987602561;4691.249400095969;4718.882027949328;4363.323684828857;4388.670849686222;4524.728516289033;4531.546845315442;4679.2045352289515 08:12:59 INFO - PROCESS | 20800 | |4;prototype.html;283.4883036119804;283.655358739132;288.81154051079784;290.12237980110166;290.20628580831436;4826.203676393391;4857.417109946802;4865.0539784086295;4897.3388592539995;4915.533024362689;4797.9760101199445;4822.565969062784;4840.588549973257;4842.467328593861;4883.6093112551;3629.3104310181047;3727.217284370272;3758.135628805376;3759;3759.3045286622064;3712.4616930545053;3818.503594532721;3851.557070936836;3859.5947425520335;3876.9418458723144;3782.03558092686;3794.1083845296334;3794.76670082223;3806.828692708821;3829.2341531693633;3522.203676125169;3697.3159965406217;3805.3911374179997;3824.3116239076953;3828.9808550957346;3205.4710972689477;3209.630892447375;3221.1463962050075;3222.517641884736;3226.2902161524426;4715.976420117911;4721.95278047222;4725.929111063337;4726.385569875929;4742.786574604151;4218;4223.598758117974;4232.407462955158;4236.745795252264;4250.872473825791;4961.280614310905;5031.264213846217;5033;5047;5085.364329458817;3480.7911525308564;3482.512448257246;3484.7212223022098;3485.268093700326;3489.3370259650837;5301.73491325436;5311.203319502067;5329.227262046981;5366.463353664602;5380.677159370451;3575.284943011356;3651.8539258429896;3656.3784156693428;3677.209399978964;3680.5031320771886;3713.4032366082843;3717.646823551758;3728.0493474164455;3735.234276973268;3736.4768932348948;4274.422952901381;4275.551067137968;4290.828366865353;4291.249031419489;4300.268954277718;4925.852224433273;5042.890564075898;5055.974720126449;5089.567386772092;5091.745412729423;1262.5265525427965;1269.447790211261;1279.9296038717773;1280.257450678604;1284.7494738526084;782.6517199846128;805.4120492040843;817.3134566963779;817.6238930092225;823.9629216685264;4257.212415703043;4358.782060896942;4417.403650507141;4504.09918016398;4505.189065968158;5245;5253.159494480865;5259.895421961431;5269.446708095671;5292.285541451854;4450.732956022584;4472.843450479218;4490.191765482179;4493.640508759291;4505.887352816206;2623.396618777692;2739.27425721798;2752.5320695481814;2763.1848604661673;2766.197802637253;4873.1959226727195;4873.6832105913;4905.484924082992;4927.926081108786;4981.6761910476425;5047.466773258733;5304.893902121936;5333.626536191357;5341.465853414705;5347.064263753905;4821.445533763592;4858.5384388483035;4884.975575122102;4886.511348865085;4926.261060840831;5197.636165468532;5203.869903252297;5227.811035268559;5243.23973023917;5284.313039304866;4997.350874211575;5025.221090730796;5135.306733590917;5149.9227511586605;5166.940777140602;4859.271109333628;4918.237548275501;4925.45819959797;4930.580900623487;4933.383327084114;3718.4794128822527;3740.1210715481297;3757.210985693035;3758.774473531597;3770.71719621024;4930.8767280818265;5243.741502039406;5251.36983561975;5268.57851371897;5275.446078161891;5029.5221953914315;5039.773210205476;5043.167877300204;5059.747012649426;5064.417591976822;4990.226514890198;5024.57291130258;5032.84901452957;5038.193888977746;5054.29239906406;4757.35775670294;4931.914978704679;4950;4954.653174277766;4980.22806464984;4902.411710594752;4965.528274813887;5127.846164615067;5154.917467331902;5191.196753752931;4987.351644286219;4997.525235102659;4997.625178111583;5000.874978125576;5024.547790698854 08:12:59 INFO - PROCESS | 20800 | |5;yui.html;592.555707705714;630.8801327747731;633.5137707453451;636.4844475974459;677.9965650836762;11866.821997670042;11868.347240901747;11992.340421276826;12007.3395963222;13097.869021309783;12966.80549791754;13116;13131.73736525269;13131.934340328286;13135.211887286745;615.9969200154015;742.6286856571728;761.1551178192202;761.2767870522997;762.881753328235;738.4904415953002;769.4152444142436;771.5794891783992;774.5352788327015;775.0118598786564;799.8960135182418;801.2388231180373;802.3901834605713;802.4743792815715;802.7310850864967;633.6103296472683;797.6211299632686;798.5847359373123;798.6286376834771;798.648594618366;561.5339268407212;573.2776701356279;576.766409604111;576.8644368573382;579.2354092597773;12333.62999110028;12367.443465044094;12370.938145309306;12381.876181238253;12399.13206075575;10236.846447303296;10406.79186416275;10426.68719938403;11101.722456938642;11133.72165695856;11369.204155709022;11420.771584568261;11459.484323205475;11954.820677689842;11987.520499180022;3885.2423777363424;3944.447777311179;3966.8809935701975;4121.443605113302;4128.153728485653;63573.36426635767;63596.682016590086;63667.363326366605;64646.35353646497;65301;9174.266058715286;9238.538073096319;9484.241260699127;9527.237820974444;9805.607775689035;16988.83011169897;17050.232739526502;17101.059441730824;17116.405554833626;17123.914380428017;6749.628770417576;6750.257471678112;6844.931550684529;6873.621950792754;7038.0146779450915;3743.344914639927;3761.4733937248807;3765.8305376258136;3773.2453509298252;3837.731358804913;2691.8923243070058;2802.7617652499357;2808.5787131930374;2808.6208361871295;2810.3114736889183;474.93113498542516;482.5223029201136;482.8961773218775;486.21719032357873;489.82121525642884;400.51937674790366;483.9395075615548;511.1846604665553;526.9473052694776;533.0857579251602;7506.3244308012545;7610.847783044308;7634.6946122154495;7638.656260468292;7956.045274567089;463.3582488253725;509.95665368443355;519.4908989190538;532.4462558938737;534.8769782949944;3478.130467383154;3527.6295988921306;3688.5020522229693;3709.5177626908867;3715.108373990223;59098;60420.69789651024;103507;104976.47511762545;107997;52208.73895630497;54954.72522637441;66498.66750666215;67513.66243168752;90643;43532.34701479417;50091.749541252786;64443.67778161173;67475.32524674789;69583;7859.84280314402;8179.509229446252;8193.344532437271;8384;8393.286570641563;40433.59566404298;42806.78596606997;42933.785331073144;43477.78261108674;43542.56457435385;56164.15753763779;56518.7174064127;58962;61036.69481652563;61240.69379653073;9769.241880133375;10205.540750666387;10429.11352535043;10770.407627580558;10877.510512027136;12458.31479268649;12494.562690305796;12506.74986500283;12552.497900083894;12731;8647.221750042527;8991.100889910957;11234.382108984084;11623.18637695387;11820.82268765952;8432.072472028194;10455.634052808115;11577.884221157681;11780.823287650863;11989.460474278503;11715.88284117182;12174.207322839211;12264.754704905674;12539.686507837378;12549.623511294676;9177.23719368666;9205.033471485398;9373.484458354857;9443.433393996382;9462.30772680374;8897.377183597086;9234.82717794743;9247.630094796134;9630.229581633312;9817.26370522228 08:12:59 INFO - PROCESS | 20800 | __end_tp_report 08:12:59 INFO - PROCESS | 20800 | __start_cc_report 08:12:59 INFO - PROCESS | 20800 | _x_x_mozilla_cycle_collect,984 08:12:59 INFO - PROCESS | 20800 | __end_cc_report 08:12:59 INFO - PROCESS | 20800 | __startTimestamp1472224379160__endTimestamp 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | ------- Summary: start ------- 08:12:59 INFO - PROCESS | 20800 | Number of tests: 6 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | [#0] dojo.html Cycles:165 Average:4732.27 Median:4884.49 stddev:517.24 (10.6%) stddev-sans-first:490.72 08:12:59 INFO - PROCESS | 20800 | Values: 2588.4 2590.7 2594.4 2602.1 2628.6 4642.4 4690.3 4709.1 4763.3 4807.0 4708.5 4748.6 4767.1 4786.2 4842.7 4192.3 4261.1 4321.2 4405.0 4446.1 4355.8 4373.9 4394.4 4401.5 4427.1 4259.6 4292.8 4301.2 4339.2 4390.3 4334.0 4350.6 4376.6 4422.0 4438.6 4335.0 4348.6 4394.8 4402.4 4405.9 4774.6 4805.1 4819.3 4822.5 4876.4 4937.7 5086.5 5135.2 5148.2 5152.3 5148.8 5169.0 5171.4 5173.6 5176.9 4648.8 4805.6 4820.2 4881.6 4884.5 5182.0 5229.6 5286.6 5289.3 5378.7 5066.8 5077.8 5078.1 5078.4 5143.1 4949.0 5153.4 5157.8 5163.4 5166.7 5037.2 5067.3 5086.7 5101.8 5170.0 5043.9 5053.2 5053.7 5057.6 5112.9 4202.7 4340.2 4354.0 4355.1 4415.1 3692.6 3764.7 3782.9 3813.9 3879.4 4375.3 4402.2 4404.3 4407.0 4463.7 5153.7 5166.6 5168.0 5169.4 5229.6 4262.5 4365.1 4397.6 4423.4 4480.2 4611.1 4616.4 4630.3 4633.6 4683.1 4847.2 4856.0 4858.2 4864.4 4916.6 4895.8 5120.3 5179.1 5183.1 5257.9 4847.2 4868.5 4873.5 4895.4 4928.6 5092.0 5092.8 5095.5 5113.1 5169.2 4940.4 4972.9 5083.4 5124.1 5124.5 5019.6 5020.9 5022.0 5023.4 5074.2 5019.2 5019.9 5026.0 5027.4 5061.9 4818.1 4887.0 4931.7 4942.3 4945.0 5056.2 5057.3 5057.8 5060.4 5105.0 4917.5 4944.1 4945.9 4949.8 4985.4 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | [#1] ext.html Cycles:180 Average:44577.90 Median:3393.00 stddev:79070.75 (2330.4%) stddev-sans-first:79223.47 08:12:59 INFO - PROCESS | 20800 | Values: 552.2 554.2 560.4 571.2 573.3 27447.5 39072.0 40350.8 40734.6 43932.3 39926.2 40264.2 40312.4 40338.6 40446.0 7294.5 7637.0 7762.1 7792.8 7796.6 9750.8 10069.7 10093.0 10416.8 10432.7 10065.0 10169.5 10173.9 10175.9 10180.1 10005.2 10037.6 10068.0 10071.8 10074.9 9910.2 9917.7 9940.4 9975.3 9984.0 37295.6 37529.8 37567.8 37583.7 37614.6 3010.5 3128.7 3130.9 3289.5 3377.3 28652.3 29314.6 29534.4 29593.1 30045.4 3408.7 3409.0 3445.6 3471.3 3474.3 236188.0 236594.0 238837.0 239522.0 240434.8 30715.7 51683.7 52120.7 52143.5 52163.2 31693.2 33066.5 33171.0 33224.5 33238.5 13470.1 13583.4 13619.3 13634.1 13641.0 10062.7 10944.9 11052.4 11116.9 11153.3 7746.3 7816.1 7847.5 7866.6 7875.2 3000.9 3057.5 3065.2 3066.1 3128.2 816.9 863.4 883.0 996.5 1000.0 27270.9 27687.3 27713.3 27840.9 28269.9 910.3 956.6 967.2 976.5 989.9 13911.9 14043.7 14312.9 14387.8 14828.9 360045.0 364084.0 364228.0 364686.0 366875.0 47148.8 47718.0 47765.8 47786.5 47927.5 1047.2 1148.8 1151.6 1180.8 1180.9 26575.2 28173.4 28676.7 28943.6 29488.9 41486.0 41825.0 41871.6 41977.6 42037.2 286064.0 286389.0 286527.0 287463.0 288702.0 25164.5 26065.6 26113.9 26433.6 26536.0 41217.2 41714.6 41801.0 43043.8 47310.8 36296.5 36502.0 36613.6 36715.0 37116.1 35576.2 36519.6 36780.6 37496.8 37530.0 34900.5 35506.8 35638.3 35742.5 36349.0 31028.5 32438.2 33123.2 33243.7 33327.7 24176.9 25036.7 25192.0 25305.5 25810.5 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | [#2] jquery.html Cycles:180 Average:23891.17 Median:22258.74 stddev:62288.90 (279.8%) stddev-sans-first:62438.52 08:12:59 INFO - PROCESS | 20800 | Values: 327.8 344.2 344.3 349.9 369.3 13131.4 15882.5 16103.8 17234.0 18355.4 14681.9 15232.1 15243.7 16337.8 16692.3 4395.8 5007.9 5138.9 5196.4 5209.5 5100.1 5167.9 5175.2 5177.7 5182.6 4898.0 5031.4 5063.4 5137.2 5142.1 5020.6 5063.3 5070.3 5072.7 5073.4 4354.7 4527.5 4532.0 4532.8 4580.4 21055.8 21122.7 21183.2 21344.8 21490.4 1889.4 1890.6 2045.3 2081.9 2157.2 12420.5 13266.9 13382.1 13478.9 13761.4 1698.2 1703.8 1745.7 1795.5 1799.9 70264.6 70445.0 71003.3 72251.6 72400.3 8969.8 9067.3 9198.5 9237.3 9516.7 12333.3 12386.3 12396.0 12418.8 12463.6 7026.9 7248.7 7261.4 7466.3 7507.9 6997.9 7054.7 7164.0 7183.2 7241.3 5809.4 5824.5 5860.6 5903.1 6010.2 1596.9 1605.8 1681.2 1700.3 1775.8 712.9 835.8 879.6 885.1 890.7 16674.5 16934.6 17352.7 17597.7 17902.1 810.3 826.4 837.2 854.7 953.1 9220.6 9803.7 9884.3 9963.0 10238.2 369396.0 375687.0 376635.3 382052.0 384180.0 67388.7 68798.0 69382.7 69692.7 73107.6 862.2 880.9 893.3 933.6 940.5 14347.9 15518.2 15832.3 16278.8 16385.3 30383.2 34707.8 35278.5 35950.5 40162.8 47483.0 51363.0 51851.2 51989.7 53468.7 7546.4 7547.7 7825.8 8049.6 8324.1 11462.7 12286.9 12316.0 12618.9 12794.3 10582.3 10623.7 10743.0 10764.6 11285.8 10397.3 10640.2 10717.4 10759.4 11008.7 10373.9 10444.4 10528.4 10823.7 11075.4 7777.7 7852.4 7969.5 7987.8 8026.2 10436.5 10472.5 10585.9 10718.6 11084.8 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | [#3] mootools.html Cycles:180 Average:5105.89 Median:5365.92 stddev:2865.83 (53.4%) stddev-sans-first:2860.02 08:12:59 INFO - PROCESS | 20800 | Values: 1356.3 1398.7 1463.5 1637.4 1700.1 6738.1 7317.6 7450.1 7937.4 7944.2 7489.7 7832.1 7843.3 7846.6 7847.6 2173.2 2197.6 2202.1 2202.8 2211.7 1831.8 2115.0 2120.1 2164.8 2176.9 2190.2 2216.9 2216.9 2218.2 2219.8 2119.1 2121.8 2175.1 2175.7 2192.1 2123.8 2125.2 2151.5 2157.5 2159.6 7752.8 7861.5 7864.0 7871.6 7871.7 4491.6 4587.0 4610.1 4617.8 4619.4 2888.7 2992.3 3067.7 3068.1 3081.6 897.3 944.8 945.3 952.9 957.9 10622.7 10661.4 10745.4 10765.4 10776.9 4043.0 4106.3 4186.5 4261.7 4281.5 8945.6 8951.3 8978.1 8986.1 9011.0 4639.5 4639.9 4641.3 4641.9 4642.7 4125.3 4239.6 4249.1 4251.1 4252.9 3576.5 3626.7 3640.3 3668.6 3678.9 1037.0 1094.5 1122.6 1146.5 1170.6 637.7 788.5 796.0 796.8 808.5 4802.3 4844.1 4891.7 4892.2 4912.6 824.9 860.8 861.0 874.9 895.4 3349.7 3371.3 3424.6 3442.7 3492.5 9279.2 9631.6 9712.7 9721.3 9737.1 9654.6 9663.0 9666.7 9671.8 9676.5 7273.7 7347.1 7348.4 7350.0 7353.2 5362.0 5369.8 5509.3 5624.7 5804.8 8787.3 8835.1 8872.2 8932.9 9013.0 8644.7 8989.9 8997.6 9017.8 9114.2 6505.2 6654.6 6670.5 6673.8 6690.8 7232.7 7245.0 7280.4 7285.0 7295.4 6470.6 6578.4 6720.7 6784.3 6879.3 6613.3 6668.1 6688.3 6698.8 6708.1 5989.0 6086.7 6087.7 6088.3 6098.0 4547.8 4586.2 4690.1 4691.2 4718.9 4363.3 4388.7 4524.7 4531.5 4679.2 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | [#4] prototype.html Cycles:180 Average:4196.97 Median:4841.53 stddev:1233.40 (25.5%) stddev-sans-first:1201.38 08:12:59 INFO - PROCESS | 20800 | Values: 283.5 283.7 288.8 290.1 290.2 4826.2 4857.4 4865.1 4897.3 4915.5 4798.0 4822.6 4840.6 4842.5 4883.6 3629.3 3727.2 3758.1 3759.0 3759.3 3712.5 3818.5 3851.6 3859.6 3876.9 3782.0 3794.1 3794.8 3806.8 3829.2 3522.2 3697.3 3805.4 3824.3 3829.0 3205.5 3209.6 3221.1 3222.5 3226.3 4716.0 4722.0 4725.9 4726.4 4742.8 4218.0 4223.6 4232.4 4236.7 4250.9 4961.3 5031.3 5033.0 5047.0 5085.4 3480.8 3482.5 3484.7 3485.3 3489.3 5301.7 5311.2 5329.2 5366.5 5380.7 3575.3 3651.9 3656.4 3677.2 3680.5 3713.4 3717.6 3728.0 3735.2 3736.5 4274.4 4275.6 4290.8 4291.2 4300.3 4925.9 5042.9 5056.0 5089.6 5091.7 1262.5 1269.4 1279.9 1280.3 1284.7 782.7 805.4 817.3 817.6 824.0 4257.2 4358.8 4417.4 4504.1 4505.2 5245.0 5253.2 5259.9 5269.4 5292.3 4450.7 4472.8 4490.2 4493.6 4505.9 2623.4 2739.3 2752.5 2763.2 2766.2 4873.2 4873.7 4905.5 4927.9 4981.7 5047.5 5304.9 5333.6 5341.5 5347.1 4821.4 4858.5 4885.0 4886.5 4926.3 5197.6 5203.9 5227.8 5243.2 5284.3 4997.4 5025.2 5135.3 5149.9 5166.9 4859.3 4918.2 4925.5 4930.6 4933.4 3718.5 3740.1 3757.2 3758.8 3770.7 4930.9 5243.7 5251.4 5268.6 5275.4 5029.5 5039.8 5043.2 5059.7 5064.4 4990.2 5024.6 5032.8 5038.2 5054.3 4757.4 4931.9 4950.0 4954.7 4980.2 4902.4 4965.5 5127.8 5154.9 5191.2 4987.4 4997.5 4997.6 5000.9 5024.5 08:12:59 INFO - PROCESS | 20800 | 08:12:59 INFO - PROCESS | 20800 | [#5] yui.html Cycles:180 Average:16180.91 Median:515.34 stddev:22677.28 (4400.5%) stddev-sans-first:22710.69 08:12:59 INFO - PROCESS | 20800 | Values: 592.6 630.9 633.5 636.5 678.0 11866.8 11868.3 11992.3 12007.3 13097.9 12966.8 13116.0 13131.7 13131.9 13135.2 616.0 742.6 761.2 761.3 762.9 738.5 769.4 771.6 774.5 775.0 799.9 801.2 802.4 802.5 802.7 633.6 797.6 798.6 798.6 798.6 561.5 573.3 576.8 576.9 579.2 12333.6 12367.4 12370.9 12381.9 12399.1 10236.8 10406.8 10426.7 11101.7 11133.7 11369.2 11420.8 11459.5 11954.8 11987.5 3885.2 3944.4 3966.9 4121.4 4128.2 63573.4 63596.7 63667.4 64646.4 65301.0 9174.3 9238.5 9484.2 9527.2 9805.6 16988.8 17050.2 17101.1 17116.4 17123.9 6749.6 6750.3 6844.9 6873.6 7038.0 3743.3 3761.5 3765.8 3773.2 3837.7 2691.9 2802.8 2808.6 2808.6 2810.3 474.9 482.5 482.9 486.2 489.8 400.5 483.9 511.2 526.9 533.1 7506.3 7610.8 7634.7 7638.7 7956.0 463.4 510.0 519.5 532.4 534.9 3478.1 3527.6 3688.5 3709.5 3715.1 59098.0 60420.7 103507.0 104976.5 107997.0 52208.7 54954.7 66498.7 67513.7 90643.0 43532.3 50091.7 64443.7 67475.3 69583.0 7859.8 8179.5 8193.3 8384.0 8393.3 40433.6 42806.8 42933.8 43477.8 43542.6 56164.2 56518.7 58962.0 61036.7 61240.7 9769.2 10205.5 10429.1 10770.4 10877.5 12458.3 12494.6 12506.7 12552.5 12731.0 8647.2 8991.1 11234.4 11623.2 11820.8 8432.1 10455.6 11577.9 11780.8 11989.5 11715.9 12174.2 12264.8 12539.7 12549.6 9177.2 9205.0 9373.5 9443.4 9462.3 8897.4 9234.8 9247.6 9630.2 9817.3 08:12:59 INFO - PROCESS | 20800 | -------- Summary: end -------- 08:12:59 INFO - PROCESS | 20800 | 08:13:00 INFO - TEST-INFO | 20800: exit 0 08:13:00 INFO - TEST-OK | dromaeo_css | took 1121377ms 08:13:00 INFO - TEST-START | kraken 08:13:00 INFO - operating with platform_type : linux_ 08:13:00 INFO - Initialising browser for kraken test... 08:13:00 INFO - TEST-INFO | started process 21105 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW2jutd/profile http://localhost:45691/getInfo.html) 08:13:04 INFO - PROCESS | 21105 | __metrics Screen width/height:1600/1200 08:13:04 INFO - PROCESS | 21105 | colorDepth:24 08:13:04 INFO - PROCESS | 21105 | Browser inner width/height: 1024/697 08:13:04 INFO - PROCESS | 21105 | __metrics 08:13:05 INFO - PROCESS | 21105 | 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" 08:13:05 INFO - PROCESS | 21105 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:13:06 INFO - PROCESS | 21105 | console.error: PushService: 08:13:06 INFO - PROCESS | 21105 | stateChangeProcessEnqueue: Error transitioning state 08:13:06 INFO - PROCESS | 21105 | UnknownError 08:13:06 INFO - TEST-INFO | 21105: exit 0 08:13:06 INFO - Browser initialized. 08:13:06 INFO - Running cycle 1/1 for kraken test... 08:13:06 INFO - TEST-INFO | started process 21186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW2jutd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:13:19 INFO - PROCESS | 21186 | RSS: Main: 181317632 08:13:19 INFO - PROCESS | 21186 | 08:13:21 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45691/tests/kraken/driver.html?testName=audio-beat-detection) 08:13:22 INFO - PROCESS | 21186 | RSS: Main: 234577920 08:13:22 INFO - PROCESS | 21186 | 08:13:25 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45691/tests/kraken/driver.html?testName=audio-dft) 08:13:26 INFO - PROCESS | 21186 | RSS: Main: 224473088 08:13:26 INFO - PROCESS | 21186 | 08:13:29 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45691/tests/kraken/driver.html?testName=audio-fft) 08:13:30 INFO - PROCESS | 21186 | RSS: Main: 302620672 08:13:30 INFO - PROCESS | 21186 | 08:13:32 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45691/tests/kraken/driver.html?testName=audio-oscillator) 08:13:32 INFO - PROCESS | 21186 | RSS: Main: 213405696 08:13:32 INFO - PROCESS | 21186 | 08:13:35 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45691/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:13:35 INFO - PROCESS | 21186 | RSS: Main: 215076864 08:13:35 INFO - PROCESS | 21186 | 08:13:38 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45691/tests/kraken/driver.html?testName=imaging-darkroom) 08:13:39 INFO - PROCESS | 21186 | RSS: Main: 253181952 08:13:39 INFO - PROCESS | 21186 | 08:13:42 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45691/tests/kraken/driver.html?testName=imaging-desaturate) 08:13:43 INFO - PROCESS | 21186 | RSS: Main: 252518400 08:13:43 INFO - PROCESS | 21186 | 08:13:46 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45691/tests/kraken/driver.html?testName=json-parse-financial) 08:13:46 INFO - PROCESS | 21186 | RSS: Main: 252563456 08:13:46 INFO - PROCESS | 21186 | 08:13:48 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45691/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:13:49 INFO - PROCESS | 21186 | RSS: Main: 215035904 08:13:49 INFO - PROCESS | 21186 | 08:13:51 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:13:51 INFO - PROCESS | 21186 | RSS: Main: 223272960 08:13:51 INFO - PROCESS | 21186 | 08:13:53 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:13:54 INFO - PROCESS | 21186 | RSS: Main: 222593024 08:13:54 INFO - PROCESS | 21186 | 08:13:57 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:13:58 INFO - PROCESS | 21186 | RSS: Main: 224030720 08:13:58 INFO - PROCESS | 21186 | 08:14:01 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:14:01 INFO - PROCESS | 21186 | RSS: Main: 221679616 08:14:01 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | Cycle 1(1): loaded http://localhost:45691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45691/tests/kraken/driver.html?testName=ai-astar) 08:14:03 INFO - PROCESS | 21186 | RSS: Main: 273154048 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | __start_tp_report 08:14:03 INFO - PROCESS | 21186 | _x_x_mozilla_page_load 08:14:03 INFO - PROCESS | 21186 | _x_x_mozilla_page_load_details 08:14:03 INFO - PROCESS | 21186 | |i|pagename|runs| 08:14:03 INFO - PROCESS | 21186 | |0;ai-astar;114;114;129;114;116;116;117;113;112;115 08:14:03 INFO - PROCESS | 21186 | |1;audio-beat-detection;155;126;151;193;123;153;148;128;177;125 08:14:03 INFO - PROCESS | 21186 | |2;audio-dft;161;161;162;162;162;162;162;161;161;161 08:14:03 INFO - PROCESS | 21186 | |3;audio-fft;76;78;76;157;78;78;76;76;163;76 08:14:03 INFO - PROCESS | 21186 | |4;audio-oscillator;83;84;143;83;84;84;144;84;91;129 08:14:03 INFO - PROCESS | 21186 | |5;imaging-gaussian-blur;117;116;117;116;117;117;117;117;117;117 08:14:03 INFO - PROCESS | 21186 | |6;imaging-darkroom;146;147;146;147;146;145;145;146;146;147 08:14:03 INFO - PROCESS | 21186 | |7;imaging-desaturate;95;95;95;95;96;96;95;95;95;94 08:14:03 INFO - PROCESS | 21186 | |8;json-parse-financial;62;63;62;63;64;63;64;69;63;63 08:14:03 INFO - PROCESS | 21186 | |9;json-stringify-tinderbox;66;65;65;65;65;65;65;66;65;65 08:14:03 INFO - PROCESS | 21186 | |10;stanford-crypto-aes;88;81;83;92;85;81;82;82;81;82 08:14:03 INFO - PROCESS | 21186 | |11;stanford-crypto-ccm;133;135;134;132;135;136;141;135;172;132 08:14:03 INFO - PROCESS | 21186 | |12;stanford-crypto-pbkdf2;172;174;179;173;173;172;174;172;169;170 08:14:03 INFO - PROCESS | 21186 | |13;stanford-crypto-sha256-iterative;69;73;71;69;69;73;70;70;66;68 08:14:03 INFO - PROCESS | 21186 | __end_tp_report 08:14:03 INFO - PROCESS | 21186 | __start_cc_report 08:14:03 INFO - PROCESS | 21186 | _x_x_mozilla_cycle_collect,2026 08:14:03 INFO - PROCESS | 21186 | __end_cc_report 08:14:03 INFO - PROCESS | 21186 | __startTimestamp1472224443755__endTimestamp 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | ------- Summary: start ------- 08:14:03 INFO - PROCESS | 21186 | Number of tests: 14 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#0] ai-astar Cycles:10 Average:116.00 Median:115.50 stddev:4.81 (4.2%) stddev-sans-first:5.04 08:14:03 INFO - PROCESS | 21186 | Values: 114.0 114.0 129.0 114.0 116.0 116.0 117.0 113.0 112.0 115.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:152.00 stddev:23.54 (15.5%) stddev-sans-first:24.83 08:14:03 INFO - PROCESS | 21186 | Values: 155.0 126.0 151.0 193.0 123.0 153.0 148.0 128.0 177.0 125.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 08:14:03 INFO - PROCESS | 21186 | Values: 161.0 161.0 162.0 162.0 162.0 162.0 162.0 161.0 161.0 161.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#3] audio-fft Cycles:10 Average:93.40 Median:76.00 stddev:35.14 (46.2%) stddev-sans-first:36.70 08:14:03 INFO - PROCESS | 21186 | Values: 76.0 78.0 76.0 157.0 78.0 78.0 76.0 76.0 163.0 76.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#4] audio-oscillator Cycles:10 Average:100.90 Median:84.00 stddev:26.46 (31.5%) stddev-sans-first:27.26 08:14:03 INFO - PROCESS | 21186 | Values: 83.0 84.0 143.0 83.0 84.0 84.0 144.0 84.0 91.0 129.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:14:03 INFO - PROCESS | 21186 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 08:14:03 INFO - PROCESS | 21186 | Values: 146.0 147.0 146.0 147.0 146.0 145.0 145.0 146.0 146.0 147.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:14:03 INFO - PROCESS | 21186 | Values: 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 94.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.00 stddev:2.01 (3.2%) stddev-sans-first:2.05 08:14:03 INFO - PROCESS | 21186 | Values: 62.0 63.0 62.0 63.0 64.0 63.0 64.0 69.0 63.0 63.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 08:14:03 INFO - PROCESS | 21186 | Values: 66.0 65.0 65.0 65.0 65.0 65.0 65.0 66.0 65.0 65.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:82.50 stddev:3.65 (4.4%) stddev-sans-first:3.53 08:14:03 INFO - PROCESS | 21186 | Values: 88.0 81.0 83.0 92.0 85.0 81.0 82.0 82.0 81.0 82.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:135.00 stddev:12.05 (8.9%) stddev-sans-first:12.61 08:14:03 INFO - PROCESS | 21186 | Values: 133.0 135.0 134.0 132.0 135.0 136.0 141.0 135.0 172.0 132.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:173.00 stddev:2.70 (1.6%) stddev-sans-first:2.85 08:14:03 INFO - PROCESS | 21186 | Values: 172.0 174.0 179.0 173.0 173.0 172.0 174.0 172.0 169.0 170.0 08:14:03 INFO - PROCESS | 21186 | 08:14:03 INFO - PROCESS | 21186 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:2.15 (3.1%) stddev-sans-first:2.26 08:14:03 INFO - PROCESS | 21186 | Values: 69.0 73.0 71.0 69.0 69.0 73.0 70.0 70.0 66.0 68.0 08:14:03 INFO - PROCESS | 21186 | -------- Summary: end -------- 08:14:03 INFO - PROCESS | 21186 | 08:14:05 INFO - TEST-INFO | 21186: exit 0 08:14:05 INFO - TEST-OK | kraken | took 64938ms 08:14:05 INFO - SUITE-END | took 1186s 08:14:05 INFO - Completed test suite (00:19:46) 08:14:05 INFO - javascript benchmark 08:14:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2588.4305452800377, 2590.7020692620354, 2594.3643807267217, 2602.063257227398, 2628.592568151936, 4642.419697537808, 4690.319903613974, 4709.105269998698, 4763.333133361328, 4807.0, 4708.52914708529, 4748.643851711126, 4767.11808315461, 4786.21027530457, 4842.733649649268, 4192.287311157095, 4261.147770445908, 4321.222180007598, 4405.008873003565, 4446.110777844444, 4355.84754533593, 4373.890652733691, 4394.362817391477, 4401.493828209765, 4427.1367083418745, 4259.5740425957465, 4292.763897985609, 4301.225779359714, 4339.1972485090355, 4390.341448782676, 4333.978330108361, 4350.58669426404, 4376.5623437656295, 4422.0, 4438.644908407319, 4335.0, 4348.608625223714, 4394.780260986938, 4402.427684401039, 4405.933910991338, 4774.64190185734, 4805.063012712522, 4819.2530157825595, 4822.493638167977, 4876.414830220397, 4937.679050861682, 5086.54221120101, 5135.152699804527, 5148.176291793333, 5152.278680984665, 5148.819791307286, 5169.043726910496, 5171.4311425743135, 5173.611979101583, 5176.8964620707375, 4648.790804413809, 4805.591524720403, 4820.180569303227, 4881.560659540658, 4884.4871288514905, 5182.0, 5229.6339256251695, 5286.577073834084, 5289.2859463972645, 5378.731063446812, 5066.771995260222, 5077.771500282496, 5078.13671675816, 5078.415982162099, 5143.074246635567, 4948.950510494921, 5153.4073581538605, 5157.793688252503, 5163.4062082860955, 5166.689998600096, 5037.19404895215, 5067.265246539305, 5086.72023038729, 5101.846944591668, 5170.0, 5043.899122017612, 5053.166227572409, 5053.67151135175, 5057.570106540912, 5112.8721781955, 4202.684798640114, 4340.175366680321, 4354.0, 4355.06366131277, 4415.139047885696, 3692.649198326155, 3764.6800021997888, 3782.905427364283, 3813.9046523836573, 3879.3792993120965, 4375.2780791169735, 4402.229609818269, 4404.339349097661, 4407.0, 4463.687541872037, 5153.742312884341, 5166.5866730661455, 5167.974160129251, 5169.353830771153, 5229.6339256251695, 4262.50981137167, 4365.083332500147, 4397.582229688174, 4423.424954755861, 4480.171168333869, 4611.123886461562, 4616.422947131608, 4630.259158534618, 4633.582977531971, 4683.086798074411, 4847.200211964986, 4855.975720121377, 4858.222684370625, 4864.391951006124, 4916.631252655993, 4895.828645997373, 5120.283160357628, 5179.067767801832, 5183.0670479314085, 5257.921131182959, 4847.175980083323, 4868.513148685103, 4873.512648735098, 4895.388076490439, 4928.6057115431395, 5092.032513822362, 5092.821751238689, 5095.490450954875, 5113.130767769414, 5169.172932330809, 4940.431850337113, 4972.9254061188385, 5083.364579427572, 5124.12889808726, 5124.5131712487255, 5019.598432125495, 5020.899582008413, 5022.045811295842, 5023.372078490189, 5074.238864170256, 5019.196928491424, 5019.899602008012, 5026.045051440215, 5027.421846487555, 5061.898762024813, 4818.084563932842, 4887.047025829929, 4931.654784165074, 4942.332785073969, 4944.970616338959, 5056.241563765465, 5057.342545469065, 5057.797688092583, 5060.367454068241, 5105.0, 4917.459079501324, 4944.1100601892, 4945.925811112908, 4949.802007919643, 4985.426675932169], "value": 4697.609577789873, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.2434265056872, 554.1798138754643, 560.4031706232862, 571.2459553389525, 573.3406582430205, 27447.45105097897, 39072.0, 40350.798246008766, 40734.59265407345, 43932.341014884805, 39926.20147597046, 40264.19471610566, 40312.395314070236, 40338.59661403392, 40446.0, 7294.525855819381, 7636.961815190916, 7762.146163921964, 7792.844143117134, 7796.649150788228, 9750.756231094208, 10069.69790906274, 10093.041161089684, 10416.843747343795, 10432.68701938943, 10065.0, 10169.491525423737, 10173.898261017406, 10175.949120254425, 10180.083792458678, 10005.19958403326, 10037.648682296158, 10068.04353586408, 10071.848922266208, 10074.9496252519, 9910.157636600898, 9917.702468925943, 9940.353876997971, 9975.251856110748, 9983.950080249624, 37295.62704372949, 37529.81235093807, 37567.81216093929, 37583.684571039885, 37614.62385376138, 3010.5333673280675, 3128.7340576051065, 3130.8904188353526, 3289.5230191622322, 3377.3245350929665, 28652.283692907637, 29314.560281595794, 29534.40931181386, 29593.081383723125, 30045.399092018037, 3408.6761757633235, 3409.0, 3445.620981692012, 3471.3230919970615, 3474.270403215328, 236188.0, 236594.0, 238837.0, 239522.0, 240434.7978260115, 30715.69284307173, 51683.741581291855, 52120.739396303536, 52143.478565214624, 52163.21755173676, 31693.207669807976, 33066.504002439986, 33171.0, 33224.521218362184, 33238.50142247868, 13470.057096003382, 13583.388747506386, 13619.250941198132, 13634.1137826041, 13641.0, 10062.74843128913, 10944.945275273572, 11052.392118433403, 11116.944415278034, 11153.275037122723, 7746.295814920709, 7816.140224575292, 7847.489913155627, 7866.646000929857, 7875.212478752193, 3000.8649610767566, 3057.4955132402906, 3065.1877252528097, 3066.126154046131, 3128.2335827722354, 816.9223923727236, 863.3567991845989, 883.0463099852144, 996.5216695985822, 999.9650012249682, 27270.863645681646, 27687.30781730473, 27713.307167320978, 27840.86079569589, 28269.858650706614, 910.276330317386, 956.6269155029544, 967.1585720423136, 976.4580657734883, 989.9406035637885, 13911.86088139126, 14043.71912561743, 14312.892006777502, 14387.78418323726, 14828.851711482963, 360045.0, 364084.0, 364228.0, 364686.0, 366875.0, 47148.764256178496, 47718.0, 47765.761171193924, 47786.522134778905, 47927.520724793, 1047.2302857399804, 1148.7530181011123, 1151.5796734192127, 1180.8228765684873, 1180.8701042885275, 26575.202743917715, 28173.43653126885, 28676.71323286824, 28943.6060365366, 29488.852555737085, 41486.0, 41825.0, 41871.58128418677, 41977.580224197365, 42037.159256814084, 286064.0, 286389.0, 286527.0, 287463.0, 288702.0, 25164.49671006606, 26065.609015864396, 26113.869430652725, 26433.603495947962, 26536.0, 41217.175656486106, 41714.58285417107, 41801.0, 43043.7847810759, 47310.76344618255, 36296.45555316725, 36502.0, 36613.63386366102, 36715.0, 37116.05076502492, 35576.154071601944, 36519.63480365162, 36780.63219367879, 37496.81251593724, 37530.0, 34900.47649285212, 35506.822465887504, 35638.28723425569, 35742.4638630426, 36349.0, 31028.53457198099, 32438.189045274055, 33123.171920702174, 33243.66756332503, 33327.66672333342, 24176.879115604308, 25036.74963250344, 25192.016888503174, 25305.493890122463, 25810.483790324462], "value": 16742.724337915326, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.79349010123616, 344.2186237241462, 344.2753004924634, 349.90027842065007, 369.3425702249996, 13131.409086591102, 15882.523524294274, 16103.83896161038, 17234.0, 18355.449336519894, 14681.926590367033, 15232.086074835517, 15243.695126097498, 16337.754933676004, 16692.332306707718, 4395.824167033315, 5007.874803129933, 5138.871528211787, 5196.428392876781, 5209.505097015796, 5100.132977393852, 5167.870803229931, 5175.24958880962, 5177.689338639675, 5182.637215394905, 4898.0, 5031.421386540558, 5063.392392912856, 5137.178051511759, 5142.10013247682, 5020.5983521318385, 5063.265826455162, 5070.29015937769, 5072.721000344961, 5073.441921388644, 4354.673399495051, 4527.456705195397, 4531.977340113312, 4532.79602417892, 4580.381648477446, 21055.789442105535, 21122.683159752614, 21183.152673893026, 21344.786552134592, 21490.355289341343, 1889.4331700489936, 1890.5651700109051, 2045.2841505473114, 2081.906314215864, 2157.234181865431, 12420.503179872794, 13266.933665331611, 13382.13016153947, 13478.865211347858, 13761.449542018307, 1698.210332195535, 1703.8040625328122, 1745.7206846904562, 1795.5062357851564, 1799.9190036448392, 70264.64867675629, 70445.0, 71003.28996710018, 72251.63874180596, 72400.27599723988, 8969.820603587892, 9067.319951003701, 9198.540072996457, 9237.307201959746, 9516.714498565054, 12333.260004399765, 12386.265922770819, 12396.0, 12418.81371779424, 12463.563775268003, 7026.894596581055, 7248.710051597877, 7261.41908647307, 7466.25337466249, 7507.887381689279, 6997.895031574531, 7054.6825392857445, 7164.0, 7183.173934997438, 7241.312075352833, 5809.3609702932645, 5824.534037277008, 5860.560457965669, 5903.144044113666, 6010.158577799111, 1596.880233982456, 1605.8474444927713, 1681.2014293210627, 1700.3336730004664, 1775.7780277465317, 712.8930660400981, 835.778518692547, 879.6261588824724, 885.1369914333602, 890.7149712092198, 16674.49976500707, 16934.57663558421, 17352.739708904377, 17597.7360339595, 17902.10489475521, 810.3233799777134, 826.4008593769469, 837.1921096142252, 854.747849384433, 953.142172045151, 9220.631174753069, 9803.705888823346, 9884.258680598983, 9962.950185249027, 10238.18094552434, 369396.0, 375687.0, 376635.2850593189, 382052.0, 384180.0, 67388.6630566844, 68798.0, 69382.65308673424, 69692.65153674301, 73107.63446182842, 862.1723145779996, 880.9075047120089, 893.2942975049768, 933.6125507914276, 940.4545363689044, 14347.92826035863, 15518.224088795741, 15832.28754706064, 16278.755818662967, 16385.34458621642, 30383.240418989702, 34707.82646086753, 35278.47082293716, 35950.460743089396, 40162.79918600388, 47483.0, 51363.0, 51851.22223166731, 51989.7400512995, 53468.73265633803, 7546.396288297033, 7547.660355283915, 7825.826126081133, 8049.597520124087, 8324.125966773401, 11462.656120316404, 12286.938565307117, 12316.0, 12618.936905315415, 12794.313194229317, 10582.312149710553, 10623.681289561326, 10743.0, 10764.623238186627, 11285.839964809866, 10397.272190946562, 10640.201984851323, 10717.410542420243, 10759.3544387337, 11008.669739907815, 10373.896261037595, 10444.42555659446, 10528.36829790215, 10823.67528974132, 11075.390853503135, 7777.72777952769, 7852.371810255052, 7969.481983671275, 7987.760367188994, 8026.237507436784, 10436.478176091014, 10472.476376181008, 10585.9470702646, 10718.570057581499, 11084.778304434027], "value": 8227.902749453477, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.3015047250665, 1398.7482253194423, 1463.5170393770056, 1637.3614290426729, 1700.1159397113502, 6738.12404387429, 7317.59753213573, 7450.143233528146, 7937.404694647903, 7944.165862584425, 7489.662965166567, 7832.099308579515, 7843.33331666809, 7846.646900889459, 7847.607619619025, 2173.1959175105194, 2197.5934452126326, 2202.0861342542826, 2202.8458007939453, 2211.7456492503406, 1831.7618709567737, 2114.95770084599, 2120.098957942876, 2164.7835216478306, 2176.880271585062, 2190.244365693833, 2216.855904366219, 2216.9334919952425, 2218.157100301883, 2219.844610877239, 2119.0676102514844, 2121.809037186653, 2175.0755928730387, 2175.695402643631, 2192.079326682781, 2123.7557680866744, 2125.2242931330106, 2151.4836439254623, 2157.503774131943, 2159.6436587962967, 7752.844943101163, 7861.528308301463, 7864.0, 7871.645775940039, 7871.724489642892, 4491.595756381941, 4587.0, 4610.124076425468, 4617.8152873885, 4619.399478067863, 2888.653361596601, 2992.341684829334, 3067.662557118715, 3068.0642404066753, 3081.6456107547697, 897.3404547657467, 944.8441007233798, 945.3429866242964, 952.8570714392896, 957.9042095790435, 10622.681319560426, 10661.360318380923, 10745.355278683226, 10765.407902565355, 10776.892231077667, 4042.959570404317, 4106.281400754856, 4186.455760751083, 4261.680373971964, 4281.507626622916, 8945.642174313085, 8951.32865035125, 8978.057303983118, 8986.14631609996, 9011.0, 4639.466461356988, 4639.907201855944, 4641.303804429363, 4641.907161856744, 4642.721436713808, 4125.319322311785, 4239.59723826236, 4249.086446414036, 4251.086016506404, 4252.936205956913, 3576.481410195507, 3626.67892548769, 3640.308341415123, 3668.6331366863633, 3678.9448158277646, 1036.99481502592, 1094.5020015892749, 1122.5565901468874, 1146.4898120336536, 1170.5610396101463, 637.7002808679913, 788.5268838696851, 795.9641816118288, 796.8286818334086, 808.5391326943703, 4802.327674125625, 4844.103840789395, 4891.706497610084, 4892.19278818998, 4912.606991440676, 824.8658095119212, 860.8493513635088, 861.0, 874.8643960186181, 895.4269267668698, 3349.748768842298, 3371.325734853039, 3424.6404127566248, 3442.7417943654327, 3492.5459690240614, 9279.211267042378, 9631.614735410505, 9712.708618741308, 9721.319507634398, 9737.123658870736, 9654.613815447445, 9663.033696630422, 9666.661666841626, 9671.758206044906, 9676.516174191262, 7273.745418910418, 7347.118345798538, 7348.375388092072, 7349.963250183715, 7353.227911069261, 5362.0, 5369.838904832861, 5509.283793106871, 5624.662520248635, 5804.767809287581, 8787.25308348772, 8835.116488351114, 8872.24585910205, 8932.910670893469, 9012.954935225282, 8644.740657780276, 8989.865152022856, 8997.64009439615, 9017.819643607223, 9114.225290850352, 6505.154329937078, 6654.600723956578, 6670.533062685566, 6673.833154171185, 6690.83272918181, 7232.674529646073, 7245.0, 7280.381167600814, 7285.0, 7295.416366690546, 6470.579412338183, 6578.375054369827, 6720.6975686093265, 6784.253732089367, 6879.3120687930805, 6613.305602911625, 6668.06647069401, 6688.297728738413, 6698.83252918681, 6708.127943367331, 5989.0, 6086.695665216633, 6087.726052327567, 6088.330283668704, 6097.969510152421, 4547.772611369484, 4586.19741545235, 4690.061987602561, 4691.249400095969, 4718.882027949328, 4363.323684828857, 4388.670849686222, 4524.728516289033, 4531.546845315442, 4679.2045352289515], "value": 4105.955004335884, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.4883036119804, 283.655358739132, 288.81154051079784, 290.12237980110166, 290.20628580831436, 4826.203676393391, 4857.417109946802, 4865.0539784086295, 4897.3388592539995, 4915.533024362689, 4797.9760101199445, 4822.565969062784, 4840.588549973257, 4842.467328593861, 4883.6093112551, 3629.3104310181047, 3727.217284370272, 3758.135628805376, 3759.0, 3759.3045286622064, 3712.4616930545053, 3818.503594532721, 3851.557070936836, 3859.5947425520335, 3876.9418458723144, 3782.03558092686, 3794.1083845296334, 3794.76670082223, 3806.828692708821, 3829.2341531693633, 3522.203676125169, 3697.3159965406217, 3805.3911374179997, 3824.3116239076953, 3828.9808550957346, 3205.4710972689477, 3209.630892447375, 3221.1463962050075, 3222.517641884736, 3226.2902161524426, 4715.976420117911, 4721.95278047222, 4725.929111063337, 4726.385569875929, 4742.786574604151, 4218.0, 4223.598758117974, 4232.407462955158, 4236.745795252264, 4250.872473825791, 4961.280614310905, 5031.264213846217, 5033.0, 5047.0, 5085.364329458817, 3480.7911525308564, 3482.512448257246, 3484.7212223022098, 3485.268093700326, 3489.3370259650837, 5301.73491325436, 5311.203319502067, 5329.227262046981, 5366.463353664602, 5380.677159370451, 3575.284943011356, 3651.8539258429896, 3656.3784156693428, 3677.209399978964, 3680.5031320771886, 3713.4032366082843, 3717.646823551758, 3728.0493474164455, 3735.234276973268, 3736.4768932348948, 4274.422952901381, 4275.551067137968, 4290.828366865353, 4291.249031419489, 4300.268954277718, 4925.852224433273, 5042.890564075898, 5055.974720126449, 5089.567386772092, 5091.745412729423, 1262.5265525427965, 1269.447790211261, 1279.9296038717773, 1280.257450678604, 1284.7494738526084, 782.6517199846128, 805.4120492040843, 817.3134566963779, 817.6238930092225, 823.9629216685264, 4257.212415703043, 4358.782060896942, 4417.403650507141, 4504.09918016398, 4505.189065968158, 5245.0, 5253.159494480865, 5259.895421961431, 5269.446708095671, 5292.285541451854, 4450.732956022584, 4472.843450479218, 4490.191765482179, 4493.640508759291, 4505.887352816206, 2623.396618777692, 2739.27425721798, 2752.5320695481814, 2763.1848604661673, 2766.197802637253, 4873.1959226727195, 4873.6832105913, 4905.484924082992, 4927.926081108786, 4981.6761910476425, 5047.466773258733, 5304.893902121936, 5333.626536191357, 5341.465853414705, 5347.064263753905, 4821.445533763592, 4858.5384388483035, 4884.975575122102, 4886.511348865085, 4926.261060840831, 5197.636165468532, 5203.869903252297, 5227.811035268559, 5243.23973023917, 5284.313039304866, 4997.350874211575, 5025.221090730796, 5135.306733590917, 5149.9227511586605, 5166.940777140602, 4859.271109333628, 4918.237548275501, 4925.45819959797, 4930.580900623487, 4933.383327084114, 3718.4794128822527, 3740.1210715481297, 3757.210985693035, 3758.774473531597, 3770.71719621024, 4930.8767280818265, 5243.741502039406, 5251.36983561975, 5268.57851371897, 5275.446078161891, 5029.5221953914315, 5039.773210205476, 5043.167877300204, 5059.747012649426, 5064.417591976822, 4990.226514890198, 5024.57291130258, 5032.84901452957, 5038.193888977746, 5054.29239906406, 4757.35775670294, 4931.914978704679, 4950.0, 4954.653174277766, 4980.22806464984, 4902.411710594752, 4965.528274813887, 5127.846164615067, 5154.917467331902, 5191.196753752931, 4987.351644286219, 4997.525235102659, 4997.625178111583, 5000.874978125576, 5024.547790698854], "value": 3797.2818014169766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.555707705714, 630.8801327747731, 633.5137707453451, 636.4844475974459, 677.9965650836762, 11866.821997670042, 11868.347240901747, 11992.340421276826, 12007.3395963222, 13097.869021309783, 12966.80549791754, 13116.0, 13131.73736525269, 13131.934340328286, 13135.211887286745, 615.9969200154015, 742.6286856571728, 761.1551178192202, 761.2767870522997, 762.881753328235, 738.4904415953002, 769.4152444142436, 771.5794891783992, 774.5352788327015, 775.0118598786564, 799.8960135182418, 801.2388231180373, 802.3901834605713, 802.4743792815715, 802.7310850864967, 633.6103296472683, 797.6211299632686, 798.5847359373123, 798.6286376834771, 798.648594618366, 561.5339268407212, 573.2776701356279, 576.766409604111, 576.8644368573382, 579.2354092597773, 12333.62999110028, 12367.443465044094, 12370.938145309306, 12381.876181238253, 12399.13206075575, 10236.846447303296, 10406.79186416275, 10426.68719938403, 11101.722456938642, 11133.72165695856, 11369.204155709022, 11420.771584568261, 11459.484323205475, 11954.820677689842, 11987.520499180022, 3885.2423777363424, 3944.447777311179, 3966.8809935701975, 4121.443605113302, 4128.153728485653, 63573.36426635767, 63596.682016590086, 63667.363326366605, 64646.35353646497, 65301.0, 9174.266058715286, 9238.538073096319, 9484.241260699127, 9527.237820974444, 9805.607775689035, 16988.83011169897, 17050.232739526502, 17101.059441730824, 17116.405554833626, 17123.914380428017, 6749.628770417576, 6750.257471678112, 6844.931550684529, 6873.621950792754, 7038.0146779450915, 3743.344914639927, 3761.4733937248807, 3765.8305376258136, 3773.2453509298252, 3837.731358804913, 2691.8923243070058, 2802.7617652499357, 2808.5787131930374, 2808.6208361871295, 2810.3114736889183, 474.93113498542516, 482.5223029201136, 482.8961773218775, 486.21719032357873, 489.82121525642884, 400.51937674790366, 483.9395075615548, 511.1846604665553, 526.9473052694776, 533.0857579251602, 7506.3244308012545, 7610.847783044308, 7634.6946122154495, 7638.656260468292, 7956.045274567089, 463.3582488253725, 509.95665368443355, 519.4908989190538, 532.4462558938737, 534.8769782949944, 3478.130467383154, 3527.6295988921306, 3688.5020522229693, 3709.5177626908867, 3715.108373990223, 59098.0, 60420.69789651024, 103507.0, 104976.47511762545, 107997.0, 52208.73895630497, 54954.72522637441, 66498.66750666215, 67513.66243168752, 90643.0, 43532.34701479417, 50091.749541252786, 64443.67778161173, 67475.32524674789, 69583.0, 7859.84280314402, 8179.509229446252, 8193.344532437271, 8384.0, 8393.286570641563, 40433.59566404298, 42806.78596606997, 42933.785331073144, 43477.78261108674, 43542.56457435385, 56164.15753763779, 56518.7174064127, 58962.0, 61036.69481652563, 61240.69379653073, 9769.241880133375, 10205.540750666387, 10429.11352535043, 10770.407627580558, 10877.510512027136, 12458.31479268649, 12494.562690305796, 12506.74986500283, 12552.497900083894, 12731.0, 8647.221750042527, 8991.100889910957, 11234.382108984084, 11623.18637695387, 11820.82268765952, 8432.072472028194, 10455.634052808115, 11577.884221157681, 11780.823287650863, 11989.460474278503, 11715.88284117182, 12174.207322839211, 12264.754704905674, 12539.686507837378, 12549.623511294676, 9177.23719368666, 9205.033471485398, 9373.484458354857, 9443.433393996382, 9462.30772680374, 8897.377183597086, 9234.82717794743, 9247.630094796134, 9630.229581633312, 9817.26370522228], "value": 6205.610277733004, "unit": "score"}], "name": "dromaeo_css", "value": 6301.615691956027, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 129.0, 114.0, 116.0, 116.0, 117.0, 113.0, 112.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 151.0, 193.0, 123.0, 153.0, 148.0, 128.0, 177.0, 125.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 161.0, 161.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 157.0, 78.0, 78.0, 76.0, 76.0, 163.0, 76.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 143.0, 83.0, 84.0, 84.0, 144.0, 84.0, 91.0, 129.0], "value": 100.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 145.0, 145.0, 146.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 64.0, 63.0, 64.0, 69.0, 63.0, 63.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 83.0, 92.0, 85.0, 81.0, 82.0, 82.0, 81.0, 82.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 134.0, 132.0, 135.0, 136.0, 141.0, 135.0, 172.0, 132.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 179.0, 173.0, 173.0, 172.0, 174.0, 172.0, 169.0, 170.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 71.0, 69.0, 69.0, 73.0, 70.0, 70.0, 66.0, 68.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1571.3, "alertThreshold": 2.0}]} 08:14:05 INFO - Return code: 0 08:14:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:14:05 INFO - # TBPL SUCCESS # 08:14:05 INFO - Running post-action listener: _resource_record_post_action 08:14:05 INFO - [mozharness: 2016-08-26 15:14:05.541066Z] Finished run-tests step (success) 08:14:05 INFO - Running post-run listener: _resource_record_post_run 08:14:05 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 0; Write bytes: 415727616; Read time: 0; Write time: 577012 08:14:05 INFO - TinderboxPrint: CPU usage
13.0% 08:14:05 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:14:05 INFO - TinderboxPrint: I/O write bytes / time
415,727,616 / 577,012 08:14:05 INFO - TinderboxPrint: CPU idle
8,345.6 (86.9%) 08:14:05 INFO - TinderboxPrint: CPU user
1,201.9 (12.5%) 08:14:05 INFO - TinderboxPrint: Swap in / out
0 / 0 08:14:05 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124948480; Read time: 0; Write time: 163428 08:14:05 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 290779136; Read time: 0; Write time: 413584 08:14:06 INFO - Running post-run listener: _upload_blobber_files 08:14:06 INFO - Blob upload gear active. 08:14:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:14:06 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 08:14:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:14:06 INFO - (blobuploader) - INFO - Open directory for files ... 08:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:14:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:07 INFO - (blobuploader) - INFO - Done attempting. 08:14:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:14:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:14:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:10 INFO - (blobuploader) - INFO - Done attempting. 08:14:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:14:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:14:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:12 INFO - (blobuploader) - INFO - Done attempting. 08:14:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:14:12 INFO - Return code: 0 08:14:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:14:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:14:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5f53fbd1c9ded3d36f5e01a43ec2a8adad62ca52a152593e46588f23bdcd0d0ef7faf61a0e83b151acd72c6a5fe5eea8902718122e56e96c0b09ac30c9115de7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/91428f24ca2c875a0d07c7d1e6390ef9294fa246275f7bd7ef68934825fd9f3e77c69c90ba9d2cd240954428278c05dd16eff285c30a4df55d97dc8e06cc4d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d8b4119013706bc5130fb0ddf7d7bc8f7f680a3ffb247d187225a4e2e8f20a0af7afb5fb5a362ba08b1f2a203c136b3bcdab3eb51b27d839950e36c12ac4d241"} 08:14:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:14:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:14:12 INFO - Contents: 08:14:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5f53fbd1c9ded3d36f5e01a43ec2a8adad62ca52a152593e46588f23bdcd0d0ef7faf61a0e83b151acd72c6a5fe5eea8902718122e56e96c0b09ac30c9115de7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/91428f24ca2c875a0d07c7d1e6390ef9294fa246275f7bd7ef68934825fd9f3e77c69c90ba9d2cd240954428278c05dd16eff285c30a4df55d97dc8e06cc4d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d8b4119013706bc5130fb0ddf7d7bc8f7f680a3ffb247d187225a4e2e8f20a0af7afb5fb5a362ba08b1f2a203c136b3bcdab3eb51b27d839950e36c12ac4d241"} 08:14:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:14:12 INFO - Copying logs to upload dir... 08:14:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:14:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.216611 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-26 08:14:11.667783) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 08:14:11.671851) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5f53fbd1c9ded3d36f5e01a43ec2a8adad62ca52a152593e46588f23bdcd0d0ef7faf61a0e83b151acd72c6a5fe5eea8902718122e56e96c0b09ac30c9115de7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/91428f24ca2c875a0d07c7d1e6390ef9294fa246275f7bd7ef68934825fd9f3e77c69c90ba9d2cd240954428278c05dd16eff285c30a4df55d97dc8e06cc4d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d8b4119013706bc5130fb0ddf7d7bc8f7f680a3ffb247d187225a4e2e8f20a0af7afb5fb5a362ba08b1f2a203c136b3bcdab3eb51b27d839950e36c12ac4d241"} build_url:https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011308 build_url: 'https://queue.taskcluster.net/v1/task/SzRBkp9SQGOX_jJPj6P98g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5f53fbd1c9ded3d36f5e01a43ec2a8adad62ca52a152593e46588f23bdcd0d0ef7faf61a0e83b151acd72c6a5fe5eea8902718122e56e96c0b09ac30c9115de7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/91428f24ca2c875a0d07c7d1e6390ef9294fa246275f7bd7ef68934825fd9f3e77c69c90ba9d2cd240954428278c05dd16eff285c30a4df55d97dc8e06cc4d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d8b4119013706bc5130fb0ddf7d7bc8f7f680a3ffb247d187225a4e2e8f20a0af7afb5fb5a362ba08b1f2a203c136b3bcdab3eb51b27d839950e36c12ac4d241"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 08:14:11.701469) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:14:11.701834) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472222773.630533-743465389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:14:11.784224) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 08:14:11.784560) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 08:14:11.784880) ========= ========= Total master_lag: 0.17 =========