builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1470759214.3 results: success (0) buildid: 20160809075520 builduid: 93145626d08f4aa894b79c58261076ee revision: 43ebf330ad942617aa8526294493a3dd25c0fc8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.304452) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.305328) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.305971) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.323716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.324025) ========= 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=17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 09:13:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2016-08-09 09:13:34 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.448067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.448688) ========= 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=17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019339 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.501359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:34.501719) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 43ebf330ad942617aa8526294493a3dd25c0fc8d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 43ebf330ad942617aa8526294493a3dd25c0fc8d --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=17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 09:13:34,594 truncating revision to first 12 chars 2016-08-09 09:13:34,594 Setting DEBUG logging. 2016-08-09 09:13:34,594 attempt 1/10 2016-08-09 09:13:34,594 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/43ebf330ad94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 09:13:34,921 unpacking tar archive at: autoland-43ebf330ad94/testing/mozharness/ program finished with exit code 0 elapsedTime=0.675960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:35.195456) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:35.195806) ========= script_repo_revision: 43ebf330ad942617aa8526294493a3dd25c0fc8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:35.196391) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:35.196698) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 09:13:35.207864) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-09 09:13:35.208173) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:13:35 INFO - MultiFileLogger online at 20160809 09:13:35 in /builds/slave/test 09:13:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 09:13:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:13:35 INFO - {'append_to_log': False, 09:13:35 INFO - 'base_work_dir': '/builds/slave/test', 09:13:35 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:13:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:13:35 INFO - 'branch': 'Autoland-Non-PGO', 09:13:35 INFO - 'buildbot_json_path': 'buildprops.json', 09:13:35 INFO - 'config_files': ('talos/linux_config.py',), 09:13:35 INFO - 'default_actions': ('clobber', 09:13:35 INFO - 'read-buildbot-config', 09:13:35 INFO - 'download-and-extract', 09:13:35 INFO - 'populate-webroot', 09:13:35 INFO - 'create-virtualenv', 09:13:35 INFO - 'install', 09:13:35 INFO - 'run-tests'), 09:13:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:13:35 INFO - 'download_minidump_stackwalk': True, 09:13:35 INFO - 'download_symbols': 'ondemand', 09:13:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:13:35 INFO - 'tooltool.py': '/tools/tooltool.py', 09:13:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:13:35 INFO - '/tools/misc-python/virtualenv.py')}, 09:13:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:13:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:13:35 INFO - 'installer_path': 'installer.exe', 09:13:35 INFO - 'log_level': 'info', 09:13:35 INFO - 'log_name': 'talos', 09:13:35 INFO - 'log_to_console': True, 09:13:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:13:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:13:35 INFO - 'opt_config_files': (), 09:13:35 INFO - 'pip_index': False, 09:13:35 INFO - 'sps_profile': False, 09:13:35 INFO - 'sps_profile_interval': 0, 09:13:35 INFO - 'suite': 'dromaeojs', 09:13:35 INFO - 'system_bits': '32', 09:13:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:13:35 INFO - 'title': 'talos-linux64-ix-009', 09:13:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:13:35 INFO - 'use_talos_json': True, 09:13:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:13:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:13:35 INFO - 'work_dir': 'build'} 09:13:35 INFO - [mozharness: 2016-08-09 16:13:35.382565Z] Running clobber step. 09:13:35 INFO - Running pre-action listener: _resource_record_pre_action 09:13:35 INFO - Running main action method: clobber 09:13:35 INFO - rmtree: /builds/slave/test/build 09:13:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:13:35 INFO - Running post-action listener: _resource_record_post_action 09:13:35 INFO - [mozharness: 2016-08-09 16:13:35.844483Z] Finished clobber step (success) 09:13:35 INFO - [mozharness: 2016-08-09 16:13:35.844584Z] Running read-buildbot-config step. 09:13:35 INFO - Running pre-action listener: _resource_record_pre_action 09:13:35 INFO - Running main action method: read_buildbot_config 09:13:35 INFO - Using buildbot properties: 09:13:35 INFO - { 09:13:35 INFO - "project": "", 09:13:35 INFO - "product": "firefox", 09:13:35 INFO - "script_repo_revision": "production", 09:13:35 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:13:35 INFO - "repository": "", 09:13:35 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:13:35 INFO - "buildid": "20160809075520", 09:13:35 INFO - "pgo_build": "False", 09:13:35 INFO - "basedir": "/builds/slave/test", 09:13:35 INFO - "buildnumber": 247, 09:13:35 INFO - "slavename": "talos-linux64-ix-009", 09:13:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:13:35 INFO - "platform": "ubuntu64_hw", 09:13:35 INFO - "scheduler": "tests-autoland-linux64-talos", 09:13:35 INFO - "branch": "autoland", 09:13:35 INFO - "repo_path": "integration/autoland", 09:13:35 INFO - "revision": "43ebf330ad942617aa8526294493a3dd25c0fc8d", 09:13:35 INFO - "stage_platform": "linux64", 09:13:35 INFO - "builduid": "93145626d08f4aa894b79c58261076ee", 09:13:35 INFO - "slavebuilddir": "test" 09:13:35 INFO - } 09:13:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:13:35 INFO - Running post-action listener: _resource_record_post_action 09:13:35 INFO - [mozharness: 2016-08-09 16:13:35.847100Z] Finished read-buildbot-config step (success) 09:13:35 INFO - [mozharness: 2016-08-09 16:13:35.847189Z] Running download-and-extract step. 09:13:35 INFO - Running pre-action listener: _resource_record_pre_action 09:13:35 INFO - Running main action method: download_and_extract 09:13:35 INFO - mkdir: /builds/slave/test/build/tests 09:13:35 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')]} 09:13:35 INFO - trying https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:13:35 INFO - Downloading https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:36 INFO - Downloaded 1361 bytes. 09:13:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:13:36 INFO - Using the following test package requirements: 09:13:36 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:13:36 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:13:36 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:13:36 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'jsshell-linux-x86_64.zip'], 09:13:36 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:13:36 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:13:36 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:13:36 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:13:36 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:13:36 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:13:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:13:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:36 INFO - trying https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:13:36 INFO - Downloading https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:37 INFO - Downloaded 20259733 bytes. 09:13:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:13:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:13:38 INFO - Return code: 0 09:13:38 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 09:13: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')]} 09:13:38 INFO - trying https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:13:38 INFO - Downloading https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:39 INFO - Downloaded 20259733 bytes. 09:13:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:13:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:13:40 INFO - Return code: 0 09:13:40 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')]} 09:13:40 INFO - trying https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:13:40 INFO - Downloading https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:41 INFO - Downloaded 14413723 bytes. 09:13:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:13:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:13:41 INFO - Return code: 0 09:13:41 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')]} 09:13:41 INFO - trying https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:13:41 INFO - Downloading https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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 09:13:43 INFO - Downloaded 55607654 bytes. 09:13:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:13:43 INFO - mkdir: /builds/slave/test/properties 09:13:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:13:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:13:43 INFO - Contents: 09:13:43 INFO - build_url:https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:13:43 INFO - Running post-action listener: _resource_record_post_action 09:13:43 INFO - Running post-action listener: set_extra_try_arguments 09:13:43 INFO - [mozharness: 2016-08-09 16:13:43.678575Z] Finished download-and-extract step (success) 09:13:43 INFO - [mozharness: 2016-08-09 16:13:43.678807Z] Running populate-webroot step. 09:13:43 INFO - Running pre-action listener: _resource_record_pre_action 09:13:43 INFO - Running main action method: populate_webroot 09:13:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:13:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:13:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:13:43 INFO - '--tppagecycles', 09:13:43 INFO - '7'], 09:13:43 INFO - 'tests': ['tsvgm']}}, 09:13:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:13:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:13:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:13:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['damp', 'tps']}, 09:13:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'tests': ['damp', 'tps']}, 09:13:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['dromaeo_dom']}, 09:13:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['basic_compositor_video']}, 09:13:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:13:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other-e10s': {'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tsvgx', 09:13:43 INFO - 'tsvgr_opacity', 09:13:43 INFO - 'tart', 09:13:43 INFO - 'tscrollx', 09:13:43 INFO - 'cart']}, 09:13:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:13:43 INFO - 'tsvgr_opacity', 09:13:43 INFO - 'tart', 09:13:43 INFO - 'tscrollx', 09:13:43 INFO - 'cart']}, 09:13:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tp5o']}, 09:13:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'tests': ['tp5o']}, 09:13:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s', 09:13:43 INFO - '--xperf_path', 09:13:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:43 INFO - 'tests': ['tp5n']}, 09:13:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:43 INFO - 'talos_options': ['--xperf_path', 09:13:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:43 INFO - 'tests': ['tp5n']}}, 09:13:43 INFO - 'talos.zip': {'path': '', 09:13:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:13:43 INFO - Running post-action listener: _resource_record_post_action 09:13:43 INFO - [mozharness: 2016-08-09 16:13:43.704273Z] Finished populate-webroot step (success) 09:13:43 INFO - [mozharness: 2016-08-09 16:13:43.704363Z] Running create-virtualenv step. 09:13:43 INFO - Running pre-action listener: _resource_record_pre_action 09:13:43 INFO - Running main action method: create_virtualenv 09:13:43 INFO - Creating virtualenv /builds/slave/test/build/venv 09:13:43 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 09:13:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:13:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:43 INFO - Using real prefix '/usr' 09:13:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:44 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:46 INFO - Installing pip.................done. 09:13:46 INFO - Return code: 0 09:13:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13: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', 'pip>=1.5'] in /builds/slave/test/build 09:13: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 pip>=1.5 09:13:46 INFO - Using env: {'DISPLAY': ':0', 09:13:46 INFO - 'HOME': '/home/cltbld', 09:13:46 INFO - 'LANG': 'en_US.UTF-8', 09:13:46 INFO - 'LANGUAGE': 'en_US:en', 09:13:46 INFO - 'LOGNAME': 'cltbld', 09:13:46 INFO - 'MAIL': '/var/mail/cltbld', 09:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:46 INFO - 'MOZ_NO_REMOTE': '1', 09:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:46 INFO - 'NO_EM_RESTART': '1', 09:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:46 INFO - 'PWD': '/builds/slave/test', 09:13:46 INFO - 'SHELL': '/bin/bash', 09:13:46 INFO - 'SHLVL': '1', 09:13:46 INFO - 'TERM': 'linux', 09:13:46 INFO - 'TMOUT': '86400', 09:13:46 INFO - 'USER': 'cltbld', 09:13:46 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:46 INFO - '_': '/tools/buildbot/bin/python'} 09:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:46 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 09:13:46 INFO - Cleaning up... 09:13:46 INFO - Return code: 0 09:13:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', '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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:13: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 psutil>=3.1.1 09:13:46 INFO - Using env: {'DISPLAY': ':0', 09:13:46 INFO - 'HOME': '/home/cltbld', 09:13:46 INFO - 'LANG': 'en_US.UTF-8', 09:13:46 INFO - 'LANGUAGE': 'en_US:en', 09:13:46 INFO - 'LOGNAME': 'cltbld', 09:13:46 INFO - 'MAIL': '/var/mail/cltbld', 09:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:46 INFO - 'MOZ_NO_REMOTE': '1', 09:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:46 INFO - 'NO_EM_RESTART': '1', 09:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:46 INFO - 'PWD': '/builds/slave/test', 09:13:46 INFO - 'SHELL': '/bin/bash', 09:13:46 INFO - 'SHLVL': '1', 09:13:46 INFO - 'TERM': 'linux', 09:13:46 INFO - 'TMOUT': '86400', 09:13:46 INFO - 'USER': 'cltbld', 09:13:46 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:46 INFO - '_': '/tools/buildbot/bin/python'} 09:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:46 INFO - Downloading/unpacking psutil>=3.1.1 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:48 INFO - Installing collected packages: psutil 09:13:48 INFO - Running setup.py install for psutil 09:13:48 INFO - building 'psutil._psutil_linux' extension 09:13:48 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 09:13:48 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 09:13:49 INFO - building 'psutil._psutil_posix' extension 09:13:49 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 09:13:49 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 09:13:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:49 INFO - Successfully installed psutil 09:13:49 INFO - Cleaning up... 09:13:49 INFO - Return code: 0 09:13:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:49 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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13:49 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 09:13:49 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 09:13:49 INFO - Using env: {'DISPLAY': ':0', 09:13:49 INFO - 'HOME': '/home/cltbld', 09:13:49 INFO - 'LANG': 'en_US.UTF-8', 09:13:49 INFO - 'LANGUAGE': 'en_US:en', 09:13:49 INFO - 'LOGNAME': 'cltbld', 09:13:49 INFO - 'MAIL': '/var/mail/cltbld', 09:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:49 INFO - 'MOZ_NO_REMOTE': '1', 09:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:49 INFO - 'NO_EM_RESTART': '1', 09:13:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:49 INFO - 'PWD': '/builds/slave/test', 09:13:49 INFO - 'SHELL': '/bin/bash', 09:13:49 INFO - 'SHLVL': '1', 09:13:49 INFO - 'TERM': 'linux', 09:13:49 INFO - 'TMOUT': '86400', 09:13:49 INFO - 'USER': 'cltbld', 09:13:49 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:49 INFO - '_': '/tools/buildbot/bin/python'} 09:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:13:49 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 09:13:49 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 09:13:49 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 09:13:49 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 09:13:49 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 09:13:49 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 09:13:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:51 INFO - Installing collected packages: mozsystemmonitor 09:13:51 INFO - Running setup.py install for mozsystemmonitor 09:13:51 INFO - Successfully installed mozsystemmonitor 09:13:51 INFO - Cleaning up... 09:13:51 INFO - Return code: 0 09:13:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', '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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:13: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 jsonschema==2.5.1 09:13:51 INFO - Using env: {'DISPLAY': ':0', 09:13:51 INFO - 'HOME': '/home/cltbld', 09:13:51 INFO - 'LANG': 'en_US.UTF-8', 09:13:51 INFO - 'LANGUAGE': 'en_US:en', 09:13:51 INFO - 'LOGNAME': 'cltbld', 09:13:51 INFO - 'MAIL': '/var/mail/cltbld', 09:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:51 INFO - 'MOZ_NO_REMOTE': '1', 09:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:51 INFO - 'NO_EM_RESTART': '1', 09:13:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:51 INFO - 'PWD': '/builds/slave/test', 09:13:51 INFO - 'SHELL': '/bin/bash', 09:13:51 INFO - 'SHLVL': '1', 09:13:51 INFO - 'TERM': 'linux', 09:13:51 INFO - 'TMOUT': '86400', 09:13:51 INFO - 'USER': 'cltbld', 09:13:51 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:51 INFO - '_': '/tools/buildbot/bin/python'} 09:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:53 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:53 INFO - warning: no files found matching '*.txt' 09:13:53 INFO - no previously-included directories found matching 'build' 09:13:53 INFO - no previously-included directories found matching 'dist' 09:13:53 INFO - no previously-included directories found matching '.git*' 09:13:53 INFO - Installing collected packages: jsonschema, functools32 09:13:53 INFO - Running setup.py install for functools32 09:13:54 INFO - warning: no files found matching '*.txt' 09:13:54 INFO - no previously-included directories found matching 'build' 09:13:54 INFO - no previously-included directories found matching 'dist' 09:13:54 INFO - no previously-included directories found matching '.git*' 09:13:54 INFO - Successfully installed jsonschema functools32 09:13:54 INFO - Cleaning up... 09:13:54 INFO - Return code: 0 09:13:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:13:54 INFO - Using env: {'DISPLAY': ':0', 09:13:54 INFO - 'HOME': '/home/cltbld', 09:13:54 INFO - 'LANG': 'en_US.UTF-8', 09:13:54 INFO - 'LANGUAGE': 'en_US:en', 09:13:54 INFO - 'LOGNAME': 'cltbld', 09:13:54 INFO - 'MAIL': '/var/mail/cltbld', 09:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:54 INFO - 'MOZ_NO_REMOTE': '1', 09:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:54 INFO - 'NO_EM_RESTART': '1', 09:13:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:54 INFO - 'PWD': '/builds/slave/test', 09:13:54 INFO - 'SHELL': '/bin/bash', 09:13:54 INFO - 'SHLVL': '1', 09:13:54 INFO - 'TERM': 'linux', 09:13:54 INFO - 'TMOUT': '86400', 09:13:54 INFO - 'USER': 'cltbld', 09:13:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:54 INFO - '_': '/tools/buildbot/bin/python'} 09:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:13:54 INFO - Cleaning up... 09:13:54 INFO - Return code: 0 09:13:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:13:54 INFO - Using env: {'DISPLAY': ':0', 09:13:54 INFO - 'HOME': '/home/cltbld', 09:13:54 INFO - 'LANG': 'en_US.UTF-8', 09:13:54 INFO - 'LANGUAGE': 'en_US:en', 09:13:54 INFO - 'LOGNAME': 'cltbld', 09:13:54 INFO - 'MAIL': '/var/mail/cltbld', 09:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:54 INFO - 'MOZ_NO_REMOTE': '1', 09:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:54 INFO - 'NO_EM_RESTART': '1', 09:13:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:54 INFO - 'PWD': '/builds/slave/test', 09:13:54 INFO - 'SHELL': '/bin/bash', 09:13:54 INFO - 'SHLVL': '1', 09:13:54 INFO - 'TERM': 'linux', 09:13:54 INFO - 'TMOUT': '86400', 09:13:54 INFO - 'USER': 'cltbld', 09:13:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:54 INFO - '_': '/tools/buildbot/bin/python'} 09:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:54 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:54 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 09:13:54 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 09:13:54 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 09:13:54 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 09:13:54 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 09:13:54 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 09:13:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:57 INFO - Downloading docopt-0.6.1.tar.gz 09:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:57 INFO - Running setup.py install for blobuploader 09:13:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:57 INFO - Running setup.py install for requests 09:13:57 INFO - Running setup.py install for docopt 09:13:57 INFO - Successfully installed blobuploader requests docopt 09:13:57 INFO - Cleaning up... 09:13:57 INFO - Return code: 0 09:13:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:57 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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:13:57 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 09:13:57 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 09:13:57 INFO - Using env: {'DISPLAY': ':0', 09:13:57 INFO - 'HOME': '/home/cltbld', 09:13:57 INFO - 'LANG': 'en_US.UTF-8', 09:13:57 INFO - 'LANGUAGE': 'en_US:en', 09:13:57 INFO - 'LOGNAME': 'cltbld', 09:13:57 INFO - 'MAIL': '/var/mail/cltbld', 09:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:57 INFO - 'MOZ_NO_REMOTE': '1', 09:13:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:57 INFO - 'NO_EM_RESTART': '1', 09:13:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:57 INFO - 'PWD': '/builds/slave/test', 09:13:57 INFO - 'SHELL': '/bin/bash', 09:13:57 INFO - 'SHLVL': '1', 09:13:57 INFO - 'TERM': 'linux', 09:13:57 INFO - 'TMOUT': '86400', 09:13:57 INFO - 'USER': 'cltbld', 09:13:57 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:57 INFO - '_': '/tools/buildbot/bin/python'} 09:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:58 INFO - Running setup.py (path:/tmp/pip-A01a7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:58 INFO - Running setup.py (path:/tmp/pip-eFRLQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:58 INFO - Running setup.py (path:/tmp/pip-6pR0cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:58 INFO - Running setup.py (path:/tmp/pip-CLFe0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:58 INFO - Running setup.py (path:/tmp/pip-aEHaPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:58 INFO - Running setup.py (path:/tmp/pip-4euQLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:58 INFO - Running setup.py (path:/tmp/pip-oL8qSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:58 INFO - Running setup.py (path:/tmp/pip-_lE70Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:58 INFO - Running setup.py (path:/tmp/pip-yiO9q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:58 INFO - Running setup.py (path:/tmp/pip-kZps6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:58 INFO - Running setup.py (path:/tmp/pip-4TNggt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:58 INFO - Running setup.py (path:/tmp/pip-qMRcyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:58 INFO - Running setup.py (path:/tmp/pip-3Wl0bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:58 INFO - Running setup.py (path:/tmp/pip-xmGdzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:59 INFO - Running setup.py (path:/tmp/pip-Wd5lII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:59 INFO - Running setup.py (path:/tmp/pip-arglRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:59 INFO - Running setup.py (path:/tmp/pip-pdefAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:59 INFO - Running setup.py install for manifestparser 09:13:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:59 INFO - Running setup.py install for mozcrash 09:13:59 INFO - Running setup.py install for mozdebug 09:13:59 INFO - Running setup.py install for mozdevice 09:13:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:59 INFO - Running setup.py install for mozfile 09:13:59 INFO - Running setup.py install for mozhttpd 09:13:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:59 INFO - Running setup.py install for mozinfo 09:13:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:59 INFO - Running setup.py install for mozInstall 09:14:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozleak 09:14:00 INFO - Running setup.py install for mozlog 09:14:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for moznetwork 09:14:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozprocess 09:14:00 INFO - Running setup.py install for mozprofile 09:14:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozrunner 09:14:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozscreenshot 09:14:00 INFO - Running setup.py install for moztest 09:14:00 INFO - Running setup.py install for mozversion 09:14:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:14:01 INFO - Cleaning up... 09:14:01 INFO - Return code: 0 09:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:01 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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:14:01 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 09:14:01 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 09:14:01 INFO - Using env: {'DISPLAY': ':0', 09:14:01 INFO - 'HOME': '/home/cltbld', 09:14:01 INFO - 'LANG': 'en_US.UTF-8', 09:14:01 INFO - 'LANGUAGE': 'en_US:en', 09:14:01 INFO - 'LOGNAME': 'cltbld', 09:14:01 INFO - 'MAIL': '/var/mail/cltbld', 09:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:01 INFO - 'MOZ_NO_REMOTE': '1', 09:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:01 INFO - 'NO_EM_RESTART': '1', 09:14:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:01 INFO - 'PWD': '/builds/slave/test', 09:14:01 INFO - 'SHELL': '/bin/bash', 09:14:01 INFO - 'SHLVL': '1', 09:14:01 INFO - 'TERM': 'linux', 09:14:01 INFO - 'TMOUT': '86400', 09:14:01 INFO - 'USER': 'cltbld', 09:14:01 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:01 INFO - '_': '/tools/buildbot/bin/python'} 09:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:01 INFO - Running setup.py (path:/tmp/pip-NQE9si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:01 INFO - Running setup.py (path:/tmp/pip-8TECsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:01 INFO - Running setup.py (path:/tmp/pip-3Pwlpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:01 INFO - Running setup.py (path:/tmp/pip-i2uZLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:01 INFO - Running setup.py (path:/tmp/pip-4PnlJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:01 INFO - Running setup.py (path:/tmp/pip-N2cBhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:01 INFO - Running setup.py (path:/tmp/pip-989JFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:01 INFO - Running setup.py (path:/tmp/pip-y6Ux9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:01 INFO - Running setup.py (path:/tmp/pip-WvB0P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:01 INFO - Running setup.py (path:/tmp/pip-8U3y9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:01 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)) 09:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:01 INFO - Running setup.py (path:/tmp/pip-plwDyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:02 INFO - Running setup.py (path:/tmp/pip-T8NRre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:02 INFO - Running setup.py (path:/tmp/pip-6gbFQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:02 INFO - Running setup.py (path:/tmp/pip-hkXx6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:02 INFO - Running setup.py (path:/tmp/pip-kzRw8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:02 INFO - Running setup.py (path:/tmp/pip-WmR2Qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:02 INFO - Running setup.py (path:/tmp/pip-bxXwVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:04 INFO - Downloading blessings-1.6.tar.gz 09:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:14:04 INFO - Installing collected packages: blessings 09:14:04 INFO - Running setup.py install for blessings 09:14:04 INFO - Successfully installed blessings 09:14:04 INFO - Cleaning up... 09:14:04 INFO - Return code: 0 09:14:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:14:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:04 INFO - Reading from file tmpfile_stdout 09:14:04 INFO - Current package versions: 09:14:04 INFO - argparse == 1.2.1 09:14:04 INFO - blessings == 1.6 09:14:04 INFO - blobuploader == 1.2.4 09:14:04 INFO - docopt == 0.6.1 09:14:04 INFO - functools32 == 3.2.3-2 09:14:04 INFO - jsonschema == 2.5.1 09:14:04 INFO - manifestparser == 1.1 09:14:04 INFO - mozInstall == 1.12 09:14:04 INFO - mozcrash == 0.17 09:14:04 INFO - mozdebug == 0.1 09:14:04 INFO - mozdevice == 0.48 09:14:04 INFO - mozfile == 1.2 09:14:04 INFO - mozhttpd == 0.7 09:14:04 INFO - mozinfo == 0.9 09:14:04 INFO - mozleak == 0.1 09:14:04 INFO - mozlog == 3.3 09:14:04 INFO - moznetwork == 0.27 09:14:04 INFO - mozprocess == 0.23 09:14:04 INFO - mozprofile == 0.28 09:14:04 INFO - mozrunner == 6.12 09:14:04 INFO - mozscreenshot == 0.1 09:14:04 INFO - mozsystemmonitor == 0.3 09:14:04 INFO - moztest == 0.7 09:14:04 INFO - mozversion == 1.4 09:14:04 INFO - psutil == 3.1.1 09:14:04 INFO - requests == 1.2.3 09:14:04 INFO - wsgiref == 0.1.2 09:14:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:04 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 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:14:04 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 09:14:04 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 09:14:04 INFO - Using env: {'DISPLAY': ':0', 09:14:04 INFO - 'HOME': '/home/cltbld', 09:14:04 INFO - 'LANG': 'en_US.UTF-8', 09:14:04 INFO - 'LANGUAGE': 'en_US:en', 09:14:04 INFO - 'LOGNAME': 'cltbld', 09:14:04 INFO - 'MAIL': '/var/mail/cltbld', 09:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:04 INFO - 'MOZ_NO_REMOTE': '1', 09:14:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:04 INFO - 'NO_EM_RESTART': '1', 09:14:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:04 INFO - 'PWD': '/builds/slave/test', 09:14:04 INFO - 'SHELL': '/bin/bash', 09:14:04 INFO - 'SHLVL': '1', 09:14:04 INFO - 'TERM': 'linux', 09:14:04 INFO - 'TMOUT': '86400', 09:14:04 INFO - 'USER': 'cltbld', 09:14:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:04 INFO - '_': '/tools/buildbot/bin/python'} 09:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 INFO - Cleaning up... 09:14:05 INFO - Return code: 0 09:14:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15de180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, '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': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', '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 09:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:14:05 INFO - Using env: {'DISPLAY': ':0', 09:14:05 INFO - 'HOME': '/home/cltbld', 09:14:05 INFO - 'LANG': 'en_US.UTF-8', 09:14:05 INFO - 'LANGUAGE': 'en_US:en', 09:14:05 INFO - 'LOGNAME': 'cltbld', 09:14:05 INFO - 'MAIL': '/var/mail/cltbld', 09:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:05 INFO - 'MOZ_NO_REMOTE': '1', 09:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:05 INFO - 'NO_EM_RESTART': '1', 09:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:05 INFO - 'PWD': '/builds/slave/test', 09:14:05 INFO - 'SHELL': '/bin/bash', 09:14:05 INFO - 'SHLVL': '1', 09:14:05 INFO - 'TERM': 'linux', 09:14:05 INFO - 'TMOUT': '86400', 09:14:05 INFO - 'USER': 'cltbld', 09:14:05 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:05 INFO - '_': '/tools/buildbot/bin/python'} 09:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:14:05 INFO - Cleaning up... 09:14:05 INFO - Return code: 0 09:14:05 INFO - Running post-action listener: _resource_record_post_action 09:14:05 INFO - Running post-action listener: _start_resource_monitoring 09:14:05 INFO - Starting resource monitoring. 09:14:05 INFO - [mozharness: 2016-08-09 16:14:05.310914Z] Finished create-virtualenv step (success) 09:14:05 INFO - [mozharness: 2016-08-09 16:14:05.311392Z] Running install step. 09:14:05 INFO - Running pre-action listener: _resource_record_pre_action 09:14:05 INFO - Running main action method: install 09:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:05 INFO - Reading from file tmpfile_stdout 09:14:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:14:05 INFO - Reading from file tmpfile_stdout 09:14:05 INFO - Output received: 09:14:05 INFO - Usage: mozinstall [options] installer 09:14:05 INFO - Options: 09:14:05 INFO - -h, --help show this help message and exit 09:14:05 INFO - -d DEST, --destination=DEST 09:14:05 INFO - Directory to install application into. [default: 09:14:05 INFO - "/builds/slave/test"] 09:14:05 INFO - --app=APP Application being installed. [default: firefox] 09:14:05 INFO - mkdir: /builds/slave/test/build/application 09:14:05 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'] 09:14:05 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 09:14:21 INFO - Reading from file tmpfile_stdout 09:14:21 INFO - Output received: 09:14:21 INFO - /builds/slave/test/build/application/firefox/firefox 09:14:21 INFO - Running post-action listener: _resource_record_post_action 09:14:21 INFO - [mozharness: 2016-08-09 16:14:21.659180Z] Finished install step (success) 09:14:21 INFO - [mozharness: 2016-08-09 16:14:21.659519Z] Running run-tests step. 09:14:21 INFO - Running pre-action listener: _resource_record_pre_action 09:14:21 INFO - Running main action method: run_tests 09:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:21 INFO - Python 2.7.3 09:14:21 INFO - Return code: 0 09:14:21 INFO - grabbing minidump binary from tooltool 09:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:21 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 0x15dc3b0>, '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 0x15dd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15dd740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:21 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 09:14:21 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 09:14:21 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 09:14:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:21 INFO - Return code: 0 09:14:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:14:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:14:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 09:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 09:14:21 INFO - Using env: {'DISPLAY': ':0', 09:14:21 INFO - 'HOME': '/home/cltbld', 09:14:21 INFO - 'LANG': 'en_US.UTF-8', 09:14:21 INFO - 'LANGUAGE': 'en_US:en', 09:14:21 INFO - 'LOGNAME': 'cltbld', 09:14:21 INFO - 'MAIL': '/var/mail/cltbld', 09:14:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:21 INFO - 'MOZ_NO_REMOTE': '1', 09:14:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:21 INFO - 'NO_EM_RESTART': '1', 09:14:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:21 INFO - 'PWD': '/builds/slave/test', 09:14:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:21 INFO - 'SHELL': '/bin/bash', 09:14:21 INFO - 'SHLVL': '1', 09:14:21 INFO - 'TERM': 'linux', 09:14:21 INFO - 'TMOUT': '86400', 09:14:21 INFO - 'USER': 'cltbld', 09:14:21 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217', 09:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:21 INFO - '_': '/tools/buildbot/bin/python'} 09:14:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 09:14:21 INFO - mozversion application_buildid: 20160809075520 09:14:21 INFO - mozversion application_changeset: 43ebf330ad942617aa8526294493a3dd25c0fc8d 09:14:21 INFO - mozversion application_display_name: Nightly 09:14:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:21 INFO - mozversion application_name: Firefox 09:14:21 INFO - mozversion application_remotingname: firefox 09:14:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:14:21 INFO - mozversion application_vendor: Mozilla 09:14:21 INFO - mozversion application_version: 51.0a1 09:14:21 INFO - mozversion platform_buildid: 20160809075520 09:14:21 INFO - mozversion platform_changeset: 43ebf330ad942617aa8526294493a3dd25c0fc8d 09:14:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:14:21 INFO - mozversion platform_version: 51.0a1 09:14:21 INFO - using testdate: 1470759261 09:14:21 INFO - actual date: 1470759261 09:14:21 INFO - starting webserver on 'localhost:35275' 09:14:21 INFO - SUITE-START | Running 2 tests 09:14:21 INFO - TEST-START | dromaeo_css 09:14:21 INFO - operating with platform_type : linux_ 09:14:21 INFO - Initialising browser for dromaeo_css test... 09:14:21 INFO - TEST-INFO | started process 29685 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ3bHct/profile http://localhost:35275/getInfo.html) 09:14:26 INFO - PROCESS | 29685 | __metrics Screen width/height:1600/1200 09:14:26 INFO - PROCESS | 29685 | colorDepth:24 09:14:26 INFO - PROCESS | 29685 | Browser inner width/height: 1024/697 09:14:26 INFO - PROCESS | 29685 | __metrics 09:14:27 INFO - PROCESS | 29685 | 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" 09:14:27 INFO - PROCESS | 29685 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:14:28 INFO - TEST-INFO | 29685: exit 0 09:14:28 INFO - Browser initialized. 09:14:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:14:28 INFO - TEST-INFO | started process 29767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ3bHct/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:40 INFO - PROCESS | 29767 | RSS: Main: 157122560 09:14:40 INFO - PROCESS | 29767 | 09:15:32 INFO - PROCESS | 29767 | 1470759332296 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] 09:17:43 INFO - PROCESS | 29767 | Cycle 1(1): loaded http://localhost:35275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35275/tests/dromaeo/cssquery-ext.html) 09:17:45 INFO - PROCESS | 29767 | RSS: Main: 242388992 09:17:45 INFO - PROCESS | 29767 | 09:20:47 INFO - PROCESS | 29767 | Cycle 1(1): loaded http://localhost:35275/tests/dromaeo/cssquery-ext.html (next: http://localhost:35275/tests/dromaeo/cssquery-jquery.html) 09:20:48 INFO - PROCESS | 29767 | RSS: Main: 237420544 09:20:48 INFO - PROCESS | 29767 | 09:23:50 INFO - PROCESS | 29767 | Cycle 1(1): loaded http://localhost:35275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35275/tests/dromaeo/cssquery-mootools.html) 09:23:50 INFO - PROCESS | 29767 | RSS: Main: 188870656 09:23:50 INFO - PROCESS | 29767 | 09:26:53 INFO - PROCESS | 29767 | Cycle 1(1): loaded http://localhost:35275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35275/tests/dromaeo/cssquery-prototype.html) 09:26:53 INFO - PROCESS | 29767 | RSS: Main: 188780544 09:26:53 INFO - PROCESS | 29767 | 09:29:56 INFO - PROCESS | 29767 | Cycle 1(1): loaded http://localhost:35275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35275/tests/dromaeo/cssquery-yui.html) 09:29:57 INFO - PROCESS | 29767 | RSS: Main: 188305408 09:29:57 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | Cycle 1(1): loaded http://localhost:35275/tests/dromaeo/cssquery-yui.html (next: http://localhost:35275/tests/dromaeo/cssquery-dojo.html) 09:33:00 INFO - PROCESS | 29767 | RSS: Main: 279756800 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | __start_tp_report 09:33:00 INFO - PROCESS | 29767 | _x_x_mozilla_page_load 09:33:00 INFO - PROCESS | 29767 | _x_x_mozilla_page_load_details 09:33:00 INFO - PROCESS | 29767 | |i|pagename|runs| 09:33:00 INFO - PROCESS | 29767 | |0;dojo.html;2581.6643836301278;2589.9741002589967;2618.018243158816;2635.7232490588476;2637.3406648337914;4533.6146427553695;4587.839425620104;4638.234691275939;4679.181143299922;4702.224133018052;4645.163870503308;4688.953110468894;4722.38608980832;4734.479207287196;4790.736509491977;4269.487661480619;4301.806418711166;4339.913201735963;4390.143921935224;4469.82120715171;4242.363645453176;4399.890002749925;4422.800973956179;4433.553624910284;4476.350929115292;4153.7507749535125;4198.685098617616;4373.846915357947;4379.5182529921685;4470.687051906368;4192.769397683126;4219.071804203101;4243.045314804175;4250.702450828443;4258.659307255412;4240.1307731914885;4291.871243862689;4303.182395344875;4308.849190278356;4348.847790327355;4484.394606728082;4734.455537613184;4745.59662428694;4755.595774359183;4772.856814295576;4913;4955.4796746341835;4962.60299176065;5033.345665063555;5086.54221120101;5036.244563315496;5088.880446301808;5099.235114732783;5106.4638212987475;5113.616478764107;4488.977555112204;4593.517680643551;4764.118638051971;4770.522947705236;4782.617390608743;5047.545720885138;5092.083424983464;5114.41184263807;5130.230465430215;5139.049275883974;4884.584810291165;4895;4916.360873086476;4942.061008408391;4942.110420124341;4789.401324834395;4949.158643030693;4967.304577359173;5025;5034.34553508049;4911.68074075184;4968.6025117990475;4975.427825800007;4994.675346102492;5016.2224855147515;4917.58200552957;4935.432425271139;4941.703497790144;4942.827001054946;4986.825461108844;4072.714909956275;4124.938125928114;4257.127288905767;4264.317709166518;4314.61168494837;3667.94498082529;3680.190358121209;3712.777233366007;3742.4947632069866;3795.7153213509096;4302.462192225972;4308.267594508941;4309.978450107792;4346.043870348582;4351.216780979391;4772.6420518461255;4987.725675087832;5006.148954677714;5009.849704508871;5033.219850923113;4176.206520761046;4286.207051695446;4332.848350307724;4343.630791382768;4395.868123956287;4504.09918016398;4522.186006518792;4522.276435770326;4528.320751887178;4564.292534657134;4745.430548334222;4756.833510827105;4773.474917759113;4774.092922344744;4803.951960480351;4839.443464001544;4844.273358996179;4921.138800709933;4934.432540257916;4986.6010719143105;4707.576318131385;4716.858494245178;4720.7639618018275;4721.055788842314;4767.928481072718;4932.827351042696;4946.653734238569;4947.505249475023;4958.2810492477665;5008.549230569266;4803.063402636452;4870.050340183772;4899;4931.457539670705;5004.899902001866;4795.808167673394;4816.903661926812;4821.083994041121;4855.174620314484;4857.757112144308;4811.326414302071;4813.590844778093;4814.662973591815;4815.686980346266;4864.051509955525;4588.9541104588525;4718.032803275389;4751.073540659539;4771.928421073756;4779.187538118597;4879.243717223836;4897.878345257926;4904.994476132456;4908.288298196813;4936.062148191832;4786.234202527578;4790.8323208687525;4791.137595232892;4831.73425461603;4836.975815120902 09:33:00 INFO - PROCESS | 29767 | |1;ext.html;564.0495764636587;572.0875204049539;576.0638961687258;577.2438106081034;584.5791030458068;29847.253818654517;40037;41449.378259326135;41497.99999999996;45647.543524564746;41292.380614290734;41639.79180104103;41746;41780;41818.163636727244;7766.8834967475395;7901.446898717092;7905.762827115196;7906.407019473534;7916;7792.181820908808;7851.136374998746;7888.368930485576;7891.4081443891655;7898.368130549571;7869.331106855924;7900;7900.209979002083;7919;7921.20787921209;7771.572563509005;7842.137364889858;7844.333231675315;7849.5682737449415;7873.5275883447175;7554.8866766998535;7604.353629941462;7610.543367397919;7634.351080158193;7654.770356889302;38888;39153;39308.213835723414;39308.41037384442;39334;3152.8423578820966;3160.2573395252098;3181.7454603631654;3182.411253918033;3275.557799697034;30111.302898637055;30137;30224.697753022407;30632.234194145323;31199.84400078008;3523.9295214095832;3600.0999750062483;3620.873269435557;3679.4480827875764;3703.166787472824;260308;261240;262165;263070;263300.9999999981;55818;56088.15867761905;56183.71908140433;56251;56285.437145628835;33269.66730332714;34828.477572836426;34847.30305393878;59559.40440595625;59696;14090.85909140916;14099.436022559188;14106.647333816736;14147.575572732629;14162.221077840612;11807.291562506278;11846.526138954345;11869.169158158846;11874.287542747463;11875.524979000917;18864.52838679044;18923.43229703111;18941.43175704731;18982.240710371432;18994.335198267996;6833.6241506716615;6857.142857142857;6924.075924075924;7073.418202356206;7189.209186989427;774.2799196747079;780.3912947900646;933.0762545080322;936.798588303518;998.2114129837347;25142.622860657106;26306.342341441617;26688.466230675276;26696.332591685365;26710.86644566765;940.7318914109446;962.3936919740519;968.7432830299977;971.4802580619335;1022.8414595737672;12883.420246088948;13360.599182024554;13473.258970756506;13608.251546164858;13649.727005459836;422549;423971;425958;426004.8699756481;427623;51122.233166502534;51322;51600.22599661008;51784.74107629438;51795.223071653956;1097.4896673047072;1167.8481797366458;1184.703824043989;1187.7327601289815;1243.9129260951827;27889.302767430978;30117.849410752806;30332.545011824397;30608.540871886493;30853.228669283446;43012.78493607512;43478.78260608677;43728.781356093015;43749.12501750011;43788.562114379725;315792;317298;317883;318047;318145;22957.540849182587;25031.624525632495;26185.73814261833;26193;26668.866655666596;43302;43786.56213437825;43821.78089109534;43892;44177.55822441863;37062.814685926394;37395;37507.06232344104;38066.61933380631;39460.802695986335;38084.80957595194;39592.60407396004;40023.049715853056;41830.79084604557;42107.789461052496;38977.22045559016;39544.40683389809;39547.209055818144;39593;39707.205855882145;38230.61769382271;39445.80277098596;39650.801745991084;39746;39947.40078898877;26640.866795665897;27258.72741272562;27283.86358068197;27354.452910941272;27469.450610988068 09:33:00 INFO - PROCESS | 29767 | |2;jquery.html;357.77281426294314;358.58046086079287;360.7420694203644;365.6142769378306;390.56647121694914;16779.74830377544;17308.394206202785;17421.39025134119;17466.650666986654;17507.91246043768;16923.830761692378;17327.30690772371;17943.730844037316;18050.368237111703;18071.63856722865;5092.0834249834925;5260.053190425722;5270.7628156733035;5302.310699609065;5325.653832500894;3729.254149170163;3828;3872.283627528902;5174.534291913727;5427.83716488504;6177.783777567786;6190.628562286277;6190.81427557174;6192.907106393408;6193.411625895533;5947.6134051286745;6109.541784366191;6166.475849552793;6170.136180934673;6184.25788905332;5362.705051222181;5379.462053794628;5380.21986811914;5381.569474442074;5382.9730851345885;21401.67897481539;21695.783042169533;21758.89120554387;21816.563668726536;21962.45093872666;1982.692682634194;2046.2326627514683;2064.2465500092508;2072.782357852434;2511.0960054380407;13344.733105337937;13496.797548036788;13665.385057672329;13712.588622341345;14375.137491750529;1674.4390629139132;1682.7644129821836;1704.420497031003;1743.651269746056;1829.359724096569;69212.30787692108;69396;70737.29262707359;71122.28877711209;71358.64320678363;8604.741857744277;8787.297016238686;8916.910830891738;9706.75733106678;10101.646442374482;13541.255230962195;13566.321683915765;13594.38825252866;13614.18314901109;13619.795703064461;7233.963830180815;7495.776815969186;7568.735094271784;7571.280728330799;7675.61621918913;7244.601546914971;7265.89101163483;7423.294786995227;7459.70161193557;7486.513376630502;6121.84695382619;6132.478739307209;6156.5998210116195;6233.407826256498;6396.5202609804455;1762.5505496098413;1821.5628249220092;1837.6784062788959;1840.7791065072277;1843.889366638006;806.3146325623173;808.4623725222779;812.5252666410564;875.8073223890734;918.3617385916724;16035.19824008795;16793.748093778602;17049.48851534456;17458.912705436393;17484.912575437294;787.4409169489612;844.8732690096411;849.3290300662408;869.2350731356366;887.8357503861806;9005.594748236344;9274.165325120772;9280.489573073412;9430.3398762086;9601.711948641552;382805;385130;385640;387674;418617;82738.17261827426;84138.57930710308;84231;85388.57305713432;87589.12410875937;926.8424367857481;952.2619969523536;975.2831668723483;978.6085565773748;980.9068138526828;16117.194140292704;16610.75083873767;17023.31906723717;17113.330004992513;17193.398231061852;38188.42717359297;38489.451142131446;38528.42207366836;38648.80675596604;41541.1691766169;50875.491245087076;52236.216456752416;52334.47665523448;53608;54499.18251226314;7174.210836808052;7294.343509084208;7307.26927307265;7351.66917488725;7473.0312165225405;8298.585070746318;10487;10764.623238186627;11093;12091.879081209076;8918.37571369998;8997.415168014059;9012.369134160808;9074.90925090767;9318.34771566011;9997.350172238781;10197.286189966631;10209.795804084026;10226.744331391477;10240.795184096425;8616.181462761027;8877.778055548662;9108.499032553273;9496.952515237379;9545.570449329936;7510.699572017058;7513.248675132661;7601.163871974189;7611.923880761122;7737.381009519338;8792;8889.4666320021;8939.195472407515;9022.548872556477;9257.35198536096 09:33:00 INFO - PROCESS | 29767 | |3;mootools.html;1249.6126200877734;1360.3878254785343;1412.3220853990085;1467.2883651429056;1653.7767401400815;6175.783847565336;7142.857142857146;7360.631968401585;7371.668274927621;7574.4319176061745;7427.554346739199;7455.552666839993;7481.812954676136;7498.47510674253;7498.6250687465545;2142.7642959274467;2169.3275084723787;2170.1427935965285;2171.5331203791184;2172.674098885164;2076.7611724651633;2114.8836813975227;2122.9256976005845;2125.2455378340696;2127.712758777561;2101.443117573844;2116.3650904728597;2120.766715661276;2127.393692797553;2156.1159924430985;2087.436392174112;2091.9267825626107;2092.7488701355783;2093.2464312847364;2094.6857971304275;2015.0730663894626;2066.71066050753;2068.524239424926;2069.048237810609;2072.5025993761387;7233.710651573931;7353.889691654629;7437.962810185969;7438.367738742213;7454.180040195574;4253.936190957138;4380.868573942755;4434.556544345572;4440.222960981848;4455.643548516111;2779.5830625406147;2927.1072322941495;2972.2420782700274;2980.523116301403;2998.5352270398125;975.2929126383386;983.0660872171466;991.4002028772561;991.5884907763269;1009.8939611340777;9850.06424389689;10064.496775161213;10074.445905475195;10076.445795481319;10082.747931301703;4035.9145686672186;4044.9191016180102;4157.91684166315;4169.249535083714;4180.247555439989;8625.396222264506;8641.395102342776;8644.265237454762;8649.697260595975;8656.52389118592;4510.2783554631105;4575.656825738083;4581.610563102107;4581.633469322446;4584.014436896084;3324.900252992414;3648.890533284006;4135.524414692289;4169.791510424467;4175.997760537449;3579.1052236940764;3633.709303255733;3719.8698045568276;3740.4202348635463;3741.85032598693;1178.711215752129;1222.0834374219335;1223.3332833605707;1224.2960297828783;1234.6357824441811;647.5952529668957;737.7159793479442;754.1101500229781;754.3248792330833;774.013133255093;4647.140279048387;4659.5806377425515;4695.483496815347;4754.952450475451;4791.808327666924;413.34072154912866;422.6576473056834;425.15818679015723;425.74029841796477;435.3295924276642;3328.5007248912857;3363.714084302813;3365.74756893234;3388.9661103389144;3447.293304872495;8862.379633425599;9283.58223879927;9287.90712092884;9304.209142222851;9317.813643727088;9208.447493150432;9217.953910230406;9223.53882305882;9234.307426943005;9254.907450925404;7109.82225444368;7121.5727056375745;7168.139823221247;7174.784756457315;7185.389241914496;4727.550882666142;4895.33912921751;4905.3623029006;4948.851534453972;5051.1160546904875;8440.071592124985;8510.191531804468;8535.402521823413;8629.654813807378;8629.913700862911;7239.710411583688;8450.66197352099;8691.913080869363;8705.912940870763;8707.434016788888;5871.236739223874;6022.728977196124;6026.276846778239;6177.536684748752;6181.412765787243;6539.901901471577;6550.47596192313;6605.867882642417;6608.471322294301;6616.966915165393;5866.266716660418;5915.645061296336;5920.141579471038;5925.288965324189;5945.791897283574;6005.309389420097;6062.060380641008;6081.6351018939;6106.419890110433;6169.1054797053275;5684;5753.165790960371;5768.509676677361;5771.249737534094;5776.480116789509;4248.468941382327;4305.397244385851;4340.891477713082;4394.604485596312;4432.357308190359;4136.462259906193;4275.593818587229;4339.262325404626;4359.782010899506;4380.342948557742 09:33:00 INFO - PROCESS | 29767 | |4;prototype.html;295.25448243185957;303.3992694464959;306.78525032477256;314.65388073119584;316.52837272464035;4836.081144582531;4850.714560641429;4855.854324370266;4856.878578035551;4881.926771098437;4709.976450117753;4720.719100225345;4760.357351757511;4810.086083644114;4815.494373090819;3633.927321453576;3731.2724018816343;3749.5500539935247;3754.4743735877;3765.5669597996307;3719.75821571597;3766.6610005099537;3817.866374676887;3826.693864490834;3876.6898648108086;3778.1310298631333;3786.9431958520645;3808.542974843023;3820.388737801952;3843.673287770543;3566.661167189113;3761.2665530221616;3780.206156707095;3783.205526839367;3820.4269359596283;3306.8842590509216;3307.5038744188323;3310.255192581674;3315.3037862049;3324.6176689680756;4741.146593613148;4759.571638552512;4762.78567464465;4764.857054288377;4773.832915847928;4247.938015496126;4255.1064276501975;4308.418363520916;4337.652987760972;4345.456817897762;4908.9263661045115;4964.230544265609;4992.550670439678;5000.524950129732;5015.247712843103;3614.1868079681863;3617.2584620152807;3622.2212224371624;3623.2391197848483;3623.710103191738;5247.711375874325;5284.5772338212855;5296.576273898079;5302.867428314323;5309.787608495655;3542.6457354264367;3717.20080182762;3724.2551489701627;3740.9625903741157;3741.401375779862;3762.5484941806612;3768.698504119664;3775.2638235543805;3775.7734535927934;3778.3387907116694;4263.786810659517;4269.701120921506;4282.807273672693;4298.957010429918;4299.441072660534;4824.565789079;5026.421961474404;5040.823571175065;5043.067032598981;5043.167877300278;1267.7084270617809;1276.234259444326;1282.8838910148231;1286.6747250332173;1295.4170623219588;802.3781569283851;813.7558732380262;823.4112609484127;825.3644693585906;829.0051937674814;4244.427002354642;4314.762688052125;4362.672799540047;4364.650827933758;4364.890877728082;5090.007448547572;5196.869283593708;5198.2202669599865;5199.7140157291715;5212.55693266069;4389.517153113155;4427.712198707074;4431.825566224953;4473.664475164375;4476.328550717419;2617.9345516361864;2650.5494066008823;2705.229009732217;2711.9864400678266;2722.4963381774437;4776.617870570346;4795.568398844121;4818.156822556039;4822.421309442819;4829.830955916526;5055.6713813602;5241.266222728821;5256.764660304825;5257.950388774501;5282.652923504482;4740.250654620312;4797.800549862534;4813;4848.666616680441;4868.829590964299;5112.616553758408;5123.257127716534;5124.846254612367;5156.819511317086;5168.9741551292;4756.785944632431;4995.925061124159;5030.647854650139;5032.547070763649;5038.345015148007;4772.615941376962;4806.975965120152;4826.986332869996;4830.154722923545;4832.903341933212;3679.822456813794;3692.464592634107;3692.8892133236045;3713.901645570244;3722.571904231048;5163.6058264267685;5184.481551844936;5192.792288308577;5193.169092945111;5195.740212989411;4758.571728544448;5012.77442515095;5017.57350625201;5024.472430394902;5052.747362631781;4960.181570040902;5001.224810154432;5009.348784657971;5011.574016208663;5018.5232402921665;4826.710397376169;4944.653874228769;4955.578775804097;4989.451160372429;5016.54851063406;4750.239961606127;4869.805207791649;4984.800607975641;5020;5070.442251352422;4919.803207871788;4926.531979461945;4928.926066109083;4940.901181976268;4948.826791062295 09:33:00 INFO - PROCESS | 29767 | |5;yui.html;639.6375719717004;647.5175993884557;691.3432239372597;698.3156506623505;749.1646813802608;13839.861601383996;14785.55643330699;14827.629309267273;14837.777433338495;14855.108693478373;12791.104622676416;12872.09895307329;12887;12906.870931290685;12931.547395841148;639.6322114783994;769.9807504812383;776.5884081436819;792.2711105782694;806.9435139540235;779.154617240295;790.7192946503994;791.0349373764027;791.4618059719388;791.8614242507567;845.9661613535482;850.1031411860484;850.2985037344184;850.4557083466586;850.6597361055566;668.1714673804472;809.299955538455;835.3359079531787;845.7420486751538;846.9449485783435;615.5629503052838;620.7268801727225;621.1583304622217;621.3848290192676;621.4375989729302;13400.597982060553;13441.327933603281;13455.125416847874;13472.326383680776;13490.797638035438;12810.74378512434;13185.406656700472;13569.728605427836;13625.591232262948;13887.930560347133;11900.226485278428;11924.642260732191;12054.578089766816;12099.637010889599;12304.81542776859;4148.834046638161;4151.605597468236;4152.162854948582;4190.790460476995;4256.425382573344;57304.42695573033;58804.117938230964;59038.70480647569;64770.676146618964;64965.3503464964;10200.642977495752;10731.89268107309;10770.892291076989;10810.459477026274;10815.242932994615;18898.905505472383;18906;18936.24255029811;18938.242470301033;18971.43085707431;7265;7296.124465064227;7324.487285889936;7366.410687145016;7512.699492020367;3888.7083468739957;3914.6085391460624;3989.7606143631474;4045.8988525287104;4055.91888162241;2871.9282017949718;2984.6418429788564;3005.8046226995175;3006.5790789289517;3010.1120169550036;500.2596157686644;506.6554742774875;515.3635260453337;518.722483471341;520.5159201942155;568.7014317483354;593.5577994394117;598.4733434577544;604.909263610462;633.9049142628643;7956.84086318282;8491.490510569261;8667.263282621048;8675.869861952076;8758.824823503493;509.81901424994334;529.4652401074865;569.4248808703239;571.977120915167;581.8021872563348;3959.0696186396176;3967.5834037425657;4107.137501124797;4118.19695159441;4147.19129769692;57018.7149064252;66397;106980;110483;110741.57033718722;51063.489365106616;51147;74954;75894.62052689701;93210;63882.25059744645;79712.60143699244;79799;80549.59725201337;80584.59707701424;7186.38915692172;7517.361024312994;7680.654370553227;7874.645640946285;7957.880631790644;47626.76186619045;47812.52187478081;48084.7595762019;49222.75388623034;49240.261396079804;38556.03609909775;39219.215615688096;39303;59023.70488147704;67849.32150678628;10048.94975525147;10089.344192627455;10151.492425378554;10239.18086553059;10288;10184.694459166238;10407.895921040692;10748.193885458466;10992.780144397228;11420.143489238175;9608.13526782593;9627.229821614394;9775.413475191512;9801.362911410735;9904.50477476109;11549.769004619693;11722.355270460206;11820.408979550815;12461.626151215478;12506.124571279923;11607.477663504993;11775.705607359885;12136.211146275817;12289.508419663114;12679.23924564529;10112.848307275544;10321.74195645115;10356.689299321033;10582.682519524427;11038;10046.54790534413;10411.53148108352;10449;10502.317349372266;10525.736856578647 09:33:00 INFO - PROCESS | 29767 | __end_tp_report 09:33:00 INFO - PROCESS | 29767 | __start_cc_report 09:33:00 INFO - PROCESS | 29767 | _x_x_mozilla_cycle_collect,942 09:33:00 INFO - PROCESS | 29767 | __end_cc_report 09:33:00 INFO - PROCESS | 29767 | __startTimestamp1470760380512__endTimestamp 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | ------- Summary: start ------- 09:33:00 INFO - PROCESS | 29767 | Number of tests: 6 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | [#0] dojo.html Cycles:165 Average:4615.18 Median:4771.93 stddev:477.15 (10.0%) stddev-sans-first:451.16 09:33:00 INFO - PROCESS | 29767 | Values: 2581.7 2590.0 2618.0 2635.7 2637.3 4533.6 4587.8 4638.2 4679.2 4702.2 4645.2 4689.0 4722.4 4734.5 4790.7 4269.5 4301.8 4339.9 4390.1 4469.8 4242.4 4399.9 4422.8 4433.6 4476.4 4153.8 4198.7 4373.8 4379.5 4470.7 4192.8 4219.1 4243.0 4250.7 4258.7 4240.1 4291.9 4303.2 4308.8 4348.8 4484.4 4734.5 4745.6 4755.6 4772.9 4913.0 4955.5 4962.6 5033.3 5086.5 5036.2 5088.9 5099.2 5106.5 5113.6 4489.0 4593.5 4764.1 4770.5 4782.6 5047.5 5092.1 5114.4 5130.2 5139.0 4884.6 4895.0 4916.4 4942.1 4942.1 4789.4 4949.2 4967.3 5025.0 5034.3 4911.7 4968.6 4975.4 4994.7 5016.2 4917.6 4935.4 4941.7 4942.8 4986.8 4072.7 4124.9 4257.1 4264.3 4314.6 3667.9 3680.2 3712.8 3742.5 3795.7 4302.5 4308.3 4310.0 4346.0 4351.2 4772.6 4987.7 5006.1 5009.8 5033.2 4176.2 4286.2 4332.8 4343.6 4395.9 4504.1 4522.2 4522.3 4528.3 4564.3 4745.4 4756.8 4773.5 4774.1 4804.0 4839.4 4844.3 4921.1 4934.4 4986.6 4707.6 4716.9 4720.8 4721.1 4767.9 4932.8 4946.7 4947.5 4958.3 5008.5 4803.1 4870.1 4899.0 4931.5 5004.9 4795.8 4816.9 4821.1 4855.2 4857.8 4811.3 4813.6 4814.7 4815.7 4864.1 4589.0 4718.0 4751.1 4771.9 4779.2 4879.2 4897.9 4905.0 4908.3 4936.1 4786.2 4790.8 4791.1 4831.7 4837.0 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | [#1] ext.html Cycles:180 Average:49330.77 Median:39569.91 stddev:90095.38 (227.7%) stddev-sans-first:90273.72 09:33:00 INFO - PROCESS | 29767 | Values: 564.0 572.1 576.1 577.2 584.6 29847.3 40037.0 41449.4 41498.0 45647.5 41292.4 41639.8 41746.0 41780.0 41818.2 7766.9 7901.4 7905.8 7906.4 7916.0 7792.2 7851.1 7888.4 7891.4 7898.4 7869.3 7900.0 7900.2 7919.0 7921.2 7771.6 7842.1 7844.3 7849.6 7873.5 7554.9 7604.4 7610.5 7634.4 7654.8 38888.0 39153.0 39308.2 39308.4 39334.0 3152.8 3160.3 3181.7 3182.4 3275.6 30111.3 30137.0 30224.7 30632.2 31199.8 3523.9 3600.1 3620.9 3679.4 3703.2 260308.0 261240.0 262165.0 263070.0 263301.0 55818.0 56088.2 56183.7 56251.0 56285.4 33269.7 34828.5 34847.3 59559.4 59696.0 14090.9 14099.4 14106.6 14147.6 14162.2 11807.3 11846.5 11869.2 11874.3 11875.5 18864.5 18923.4 18941.4 18982.2 18994.3 6833.6 6857.1 6924.1 7073.4 7189.2 774.3 780.4 933.1 936.8 998.2 25142.6 26306.3 26688.5 26696.3 26710.9 940.7 962.4 968.7 971.5 1022.8 12883.4 13360.6 13473.3 13608.3 13649.7 422549.0 423971.0 425958.0 426004.9 427623.0 51122.2 51322.0 51600.2 51784.7 51795.2 1097.5 1167.8 1184.7 1187.7 1243.9 27889.3 30117.8 30332.5 30608.5 30853.2 43012.8 43478.8 43728.8 43749.1 43788.6 315792.0 317298.0 317883.0 318047.0 318145.0 22957.5 25031.6 26185.7 26193.0 26668.9 43302.0 43786.6 43821.8 43892.0 44177.6 37062.8 37395.0 37507.1 38066.6 39460.8 38084.8 39592.6 40023.0 41830.8 42107.8 38977.2 39544.4 39547.2 39593.0 39707.2 38230.6 39445.8 39650.8 39746.0 39947.4 26640.9 27258.7 27283.9 27354.5 27469.5 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | [#2] jquery.html Cycles:180 Average:24866.77 Median:5382.27 stddev:64968.38 (1207.1%) stddev-sans-first:65124.57 09:33:00 INFO - PROCESS | 29767 | Values: 357.8 358.6 360.7 365.6 390.6 16779.7 17308.4 17421.4 17466.7 17507.9 16923.8 17327.3 17943.7 18050.4 18071.6 5092.1 5260.1 5270.8 5302.3 5325.7 3729.3 3828.0 3872.3 5174.5 5427.8 6177.8 6190.6 6190.8 6192.9 6193.4 5947.6 6109.5 6166.5 6170.1 6184.3 5362.7 5379.5 5380.2 5381.6 5383.0 21401.7 21695.8 21758.9 21816.6 21962.5 1982.7 2046.2 2064.2 2072.8 2511.1 13344.7 13496.8 13665.4 13712.6 14375.1 1674.4 1682.8 1704.4 1743.7 1829.4 69212.3 69396.0 70737.3 71122.3 71358.6 8604.7 8787.3 8916.9 9706.8 10101.6 13541.3 13566.3 13594.4 13614.2 13619.8 7234.0 7495.8 7568.7 7571.3 7675.6 7244.6 7265.9 7423.3 7459.7 7486.5 6121.8 6132.5 6156.6 6233.4 6396.5 1762.6 1821.6 1837.7 1840.8 1843.9 806.3 808.5 812.5 875.8 918.4 16035.2 16793.7 17049.5 17458.9 17484.9 787.4 844.9 849.3 869.2 887.8 9005.6 9274.2 9280.5 9430.3 9601.7 382805.0 385130.0 385640.0 387674.0 418617.0 82738.2 84138.6 84231.0 85388.6 87589.1 926.8 952.3 975.3 978.6 980.9 16117.2 16610.8 17023.3 17113.3 17193.4 38188.4 38489.5 38528.4 38648.8 41541.2 50875.5 52236.2 52334.5 53608.0 54499.2 7174.2 7294.3 7307.3 7351.7 7473.0 8298.6 10487.0 10764.6 11093.0 12091.9 8918.4 8997.4 9012.4 9074.9 9318.3 9997.4 10197.3 10209.8 10226.7 10240.8 8616.2 8877.8 9108.5 9497.0 9545.6 7510.7 7513.2 7601.2 7611.9 7737.4 8792.0 8889.5 8939.2 9022.5 9257.4 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | [#3] mootools.html Cycles:180 Average:4836.41 Median:4653.36 stddev:2701.75 (58.1%) stddev-sans-first:2695.88 09:33:00 INFO - PROCESS | 29767 | Values: 1249.6 1360.4 1412.3 1467.3 1653.8 6175.8 7142.9 7360.6 7371.7 7574.4 7427.6 7455.6 7481.8 7498.5 7498.6 2142.8 2169.3 2170.1 2171.5 2172.7 2076.8 2114.9 2122.9 2125.2 2127.7 2101.4 2116.4 2120.8 2127.4 2156.1 2087.4 2091.9 2092.7 2093.2 2094.7 2015.1 2066.7 2068.5 2069.0 2072.5 7233.7 7353.9 7438.0 7438.4 7454.2 4253.9 4380.9 4434.6 4440.2 4455.6 2779.6 2927.1 2972.2 2980.5 2998.5 975.3 983.1 991.4 991.6 1009.9 9850.1 10064.5 10074.4 10076.4 10082.7 4035.9 4044.9 4157.9 4169.2 4180.2 8625.4 8641.4 8644.3 8649.7 8656.5 4510.3 4575.7 4581.6 4581.6 4584.0 3324.9 3648.9 4135.5 4169.8 4176.0 3579.1 3633.7 3719.9 3740.4 3741.9 1178.7 1222.1 1223.3 1224.3 1234.6 647.6 737.7 754.1 754.3 774.0 4647.1 4659.6 4695.5 4755.0 4791.8 413.3 422.7 425.2 425.7 435.3 3328.5 3363.7 3365.7 3389.0 3447.3 8862.4 9283.6 9287.9 9304.2 9317.8 9208.4 9218.0 9223.5 9234.3 9254.9 7109.8 7121.6 7168.1 7174.8 7185.4 4727.6 4895.3 4905.4 4948.9 5051.1 8440.1 8510.2 8535.4 8629.7 8629.9 7239.7 8450.7 8691.9 8705.9 8707.4 5871.2 6022.7 6026.3 6177.5 6181.4 6539.9 6550.5 6605.9 6608.5 6617.0 5866.3 5915.6 5920.1 5925.3 5945.8 6005.3 6062.1 6081.6 6106.4 6169.1 5684.0 5753.2 5768.5 5771.2 5776.5 4248.5 4305.4 4340.9 4394.6 4432.4 4136.5 4275.6 4339.3 4359.8 4380.3 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | [#4] prototype.html Cycles:180 Average:4171.65 Median:4768.74 stddev:1205.75 (25.3%) stddev-sans-first:1173.51 09:33:00 INFO - PROCESS | 29767 | Values: 295.3 303.4 306.8 314.7 316.5 4836.1 4850.7 4855.9 4856.9 4881.9 4710.0 4720.7 4760.4 4810.1 4815.5 3633.9 3731.3 3749.6 3754.5 3765.6 3719.8 3766.7 3817.9 3826.7 3876.7 3778.1 3786.9 3808.5 3820.4 3843.7 3566.7 3761.3 3780.2 3783.2 3820.4 3306.9 3307.5 3310.3 3315.3 3324.6 4741.1 4759.6 4762.8 4764.9 4773.8 4247.9 4255.1 4308.4 4337.7 4345.5 4908.9 4964.2 4992.6 5000.5 5015.2 3614.2 3617.3 3622.2 3623.2 3623.7 5247.7 5284.6 5296.6 5302.9 5309.8 3542.6 3717.2 3724.3 3741.0 3741.4 3762.5 3768.7 3775.3 3775.8 3778.3 4263.8 4269.7 4282.8 4299.0 4299.4 4824.6 5026.4 5040.8 5043.1 5043.2 1267.7 1276.2 1282.9 1286.7 1295.4 802.4 813.8 823.4 825.4 829.0 4244.4 4314.8 4362.7 4364.7 4364.9 5090.0 5196.9 5198.2 5199.7 5212.6 4389.5 4427.7 4431.8 4473.7 4476.3 2617.9 2650.5 2705.2 2712.0 2722.5 4776.6 4795.6 4818.2 4822.4 4829.8 5055.7 5241.3 5256.8 5258.0 5282.7 4740.3 4797.8 4813.0 4848.7 4868.8 5112.6 5123.3 5124.8 5156.8 5169.0 4756.8 4995.9 5030.6 5032.5 5038.3 4772.6 4807.0 4827.0 4830.2 4832.9 3679.8 3692.5 3692.9 3713.9 3722.6 5163.6 5184.5 5192.8 5193.2 5195.7 4758.6 5012.8 5017.6 5024.5 5052.7 4960.2 5001.2 5009.3 5011.6 5018.5 4826.7 4944.7 4955.6 4989.5 5016.5 4750.2 4869.8 4984.8 5020.0 5070.4 4919.8 4926.5 4928.9 4940.9 4948.8 09:33:00 INFO - PROCESS | 29767 | 09:33:00 INFO - PROCESS | 29767 | [#5] yui.html Cycles:180 Average:16977.21 Median:25941.59 stddev:23863.02 (92.0%) stddev-sans-first:23898.43 09:33:00 INFO - PROCESS | 29767 | Values: 639.6 647.5 691.3 698.3 749.2 13839.9 14785.6 14827.6 14837.8 14855.1 12791.1 12872.1 12887.0 12906.9 12931.5 639.6 770.0 776.6 792.3 806.9 779.2 790.7 791.0 791.5 791.9 846.0 850.1 850.3 850.5 850.7 668.2 809.3 835.3 845.7 846.9 615.6 620.7 621.2 621.4 621.4 13400.6 13441.3 13455.1 13472.3 13490.8 12810.7 13185.4 13569.7 13625.6 13887.9 11900.2 11924.6 12054.6 12099.6 12304.8 4148.8 4151.6 4152.2 4190.8 4256.4 57304.4 58804.1 59038.7 64770.7 64965.4 10200.6 10731.9 10770.9 10810.5 10815.2 18898.9 18906.0 18936.2 18938.2 18971.4 7265.0 7296.1 7324.5 7366.4 7512.7 3888.7 3914.6 3989.8 4045.9 4055.9 2871.9 2984.6 3005.8 3006.6 3010.1 500.3 506.7 515.4 518.7 520.5 568.7 593.6 598.5 604.9 633.9 7956.8 8491.5 8667.3 8675.9 8758.8 509.8 529.5 569.4 572.0 581.8 3959.1 3967.6 4107.1 4118.2 4147.2 57018.7 66397.0 106980.0 110483.0 110741.6 51063.5 51147.0 74954.0 75894.6 93210.0 63882.3 79712.6 79799.0 80549.6 80584.6 7186.4 7517.4 7680.7 7874.6 7957.9 47626.8 47812.5 48084.8 49222.8 49240.3 38556.0 39219.2 39303.0 59023.7 67849.3 10048.9 10089.3 10151.5 10239.2 10288.0 10184.7 10407.9 10748.2 10992.8 11420.1 9608.1 9627.2 9775.4 9801.4 9904.5 11549.8 11722.4 11820.4 12461.6 12506.1 11607.5 11775.7 12136.2 12289.5 12679.2 10112.8 10321.7 10356.7 10582.7 11038.0 10046.5 10411.5 10449.0 10502.3 10525.7 09:33:00 INFO - PROCESS | 29767 | -------- Summary: end -------- 09:33:00 INFO - PROCESS | 29767 | 09:33:01 INFO - TEST-INFO | 29767: exit 0 09:33:01 INFO - TEST-OK | dromaeo_css | took 1119489ms 09:33:01 INFO - TEST-START | kraken 09:33:01 INFO - operating with platform_type : linux_ 09:33:01 INFO - Initialising browser for kraken test... 09:33:01 INFO - TEST-INFO | started process 29962 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSDID4a/profile http://localhost:35275/getInfo.html) 09:33:05 INFO - PROCESS | 29962 | __metrics Screen width/height:1600/1200 09:33:05 INFO - PROCESS | 29962 | colorDepth:24 09:33:05 INFO - PROCESS | 29962 | Browser inner width/height: 1024/697 09:33:05 INFO - PROCESS | 29962 | __metrics 09:33:06 INFO - PROCESS | 29962 | 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" 09:33:06 INFO - PROCESS | 29962 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:07 INFO - TEST-INFO | 29962: exit 0 09:33:07 INFO - Browser initialized. 09:33:07 INFO - Running cycle 1/1 for kraken test... 09:33:07 INFO - TEST-INFO | started process 30044 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSDID4a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:19 INFO - PROCESS | 30044 | RSS: Main: 156577792 09:33:19 INFO - PROCESS | 30044 | 09:33:22 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35275/tests/kraken/driver.html?testName=audio-beat-detection) 09:33:23 INFO - PROCESS | 30044 | RSS: Main: 212287488 09:33:23 INFO - PROCESS | 30044 | 09:33:26 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35275/tests/kraken/driver.html?testName=audio-dft) 09:33:26 INFO - PROCESS | 30044 | RSS: Main: 190763008 09:33:26 INFO - PROCESS | 30044 | 09:33:29 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35275/tests/kraken/driver.html?testName=audio-fft) 09:33:30 INFO - PROCESS | 30044 | RSS: Main: 269541376 09:33:30 INFO - PROCESS | 30044 | 09:33:32 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35275/tests/kraken/driver.html?testName=audio-oscillator) 09:33:33 INFO - PROCESS | 30044 | RSS: Main: 179273728 09:33:33 INFO - PROCESS | 30044 | 09:33:35 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:36 INFO - PROCESS | 30044 | RSS: Main: 183689216 09:33:36 INFO - PROCESS | 30044 | 09:33:39 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35275/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:40 INFO - PROCESS | 30044 | RSS: Main: 219750400 09:33:40 INFO - PROCESS | 30044 | 09:33:43 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35275/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:44 INFO - PROCESS | 30044 | RSS: Main: 214016000 09:33:44 INFO - PROCESS | 30044 | 09:33:47 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35275/tests/kraken/driver.html?testName=json-parse-financial) 09:33:47 INFO - PROCESS | 30044 | RSS: Main: 216977408 09:33:47 INFO - PROCESS | 30044 | 09:33:49 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:49 INFO - PROCESS | 30044 | RSS: Main: 182788096 09:33:49 INFO - PROCESS | 30044 | 09:33:51 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:52 INFO - PROCESS | 30044 | RSS: Main: 189734912 09:33:52 INFO - PROCESS | 30044 | 09:33:54 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:55 INFO - PROCESS | 30044 | RSS: Main: 193060864 09:33:55 INFO - PROCESS | 30044 | 09:33:58 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:58 INFO - PROCESS | 30044 | RSS: Main: 187936768 09:33:58 INFO - PROCESS | 30044 | 09:34:01 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:34:02 INFO - PROCESS | 30044 | RSS: Main: 191242240 09:34:02 INFO - PROCESS | 30044 | 09:34:03 INFO - PROCESS | 30044 | Cycle 1(1): loaded http://localhost:35275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35275/tests/kraken/driver.html?testName=ai-astar) 09:34:04 INFO - PROCESS | 30044 | RSS: Main: 241803264 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | __start_tp_report 09:34:04 INFO - PROCESS | 30044 | _x_x_mozilla_page_load 09:34:04 INFO - PROCESS | 30044 | _x_x_mozilla_page_load_details 09:34:04 INFO - PROCESS | 30044 | |i|pagename|runs| 09:34:04 INFO - PROCESS | 30044 | |0;ai-astar;107;116;116;116;114;117;114;114;117;117 09:34:04 INFO - PROCESS | 30044 | |1;audio-beat-detection;150;123;127;185;128;125;155;121;195;122 09:34:04 INFO - PROCESS | 30044 | |2;audio-dft;160;161;160;163;160;161;160;162;162;163 09:34:04 INFO - PROCESS | 30044 | |3;audio-fft;76;75;75;105;75;77;75;75;103;76 09:34:04 INFO - PROCESS | 30044 | |4;audio-oscillator;87;84;136;85;85;86;117;85;86;120 09:34:04 INFO - PROCESS | 30044 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;117;117 09:34:04 INFO - PROCESS | 30044 | |6;imaging-darkroom;146;147;146;146;147;147;147;188;146;147 09:34:04 INFO - PROCESS | 30044 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;95 09:34:04 INFO - PROCESS | 30044 | |8;json-parse-financial;64;62;63;62;62;64;62;63;63;63 09:34:04 INFO - PROCESS | 30044 | |9;json-stringify-tinderbox;66;65;65;65;66;65;66;66;65;65 09:34:04 INFO - PROCESS | 30044 | |10;stanford-crypto-aes;79;79;79;78;88;80;85;78;78;79 09:34:04 INFO - PROCESS | 30044 | |11;stanford-crypto-ccm;130;129;129;131;130;127;132;137;144;144 09:34:04 INFO - PROCESS | 30044 | |12;stanford-crypto-pbkdf2;168;170;168;172;173;167;167;168;167;167 09:34:04 INFO - PROCESS | 30044 | |13;stanford-crypto-sha256-iterative;67;68;71;67;66;65;70;66;70;66 09:34:04 INFO - PROCESS | 30044 | __end_tp_report 09:34:04 INFO - PROCESS | 30044 | __start_cc_report 09:34:04 INFO - PROCESS | 30044 | _x_x_mozilla_cycle_collect,1963 09:34:04 INFO - PROCESS | 30044 | __end_cc_report 09:34:04 INFO - PROCESS | 30044 | __startTimestamp1470760444213__endTimestamp 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | ------- Summary: start ------- 09:34:04 INFO - PROCESS | 30044 | Number of tests: 14 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#0] ai-astar Cycles:10 Average:114.80 Median:116.00 stddev:3.01 (2.6%) stddev-sans-first:1.32 09:34:04 INFO - PROCESS | 30044 | Values: 107.0 116.0 116.0 116.0 114.0 117.0 114.0 114.0 117.0 117.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:139.00 stddev:27.47 (19.8%) stddev-sans-first:29.02 09:34:04 INFO - PROCESS | 30044 | Values: 150.0 123.0 127.0 185.0 128.0 125.0 155.0 121.0 195.0 122.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.23 (0.8%) stddev-sans-first:1.22 09:34:04 INFO - PROCESS | 30044 | Values: 160.0 161.0 160.0 163.0 160.0 161.0 160.0 162.0 162.0 163.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:12.04 (16.1%) stddev-sans-first:12.63 09:34:04 INFO - PROCESS | 30044 | Values: 76.0 75.0 75.0 105.0 75.0 77.0 75.0 75.0 103.0 76.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:85.00 stddev:19.42 (22.8%) stddev-sans-first:20.25 09:34:04 INFO - PROCESS | 30044 | Values: 87.0 84.0 136.0 85.0 85.0 86.0 117.0 85.0 86.0 120.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:34:04 INFO - PROCESS | 30044 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:147.00 stddev:13.12 (8.9%) stddev-sans-first:13.80 09:34:04 INFO - PROCESS | 30044 | Values: 146.0 147.0 146.0 146.0 147.0 147.0 147.0 188.0 146.0 147.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:34:04 INFO - PROCESS | 30044 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:0.79 (1.3%) stddev-sans-first:0.71 09:34:04 INFO - PROCESS | 30044 | Values: 64.0 62.0 63.0 62.0 62.0 64.0 62.0 63.0 63.0 63.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 09:34:04 INFO - PROCESS | 30044 | Values: 66.0 65.0 65.0 65.0 66.0 65.0 66.0 66.0 65.0 65.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:79.00 stddev:3.40 (4.3%) stddev-sans-first:3.57 09:34:04 INFO - PROCESS | 30044 | Values: 79.0 79.0 79.0 78.0 88.0 80.0 85.0 78.0 78.0 79.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:131.50 stddev:6.22 (4.7%) stddev-sans-first:6.48 09:34:04 INFO - PROCESS | 30044 | Values: 130.0 129.0 129.0 131.0 130.0 127.0 132.0 137.0 144.0 144.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:168.00 stddev:2.21 (1.3%) stddev-sans-first:2.33 09:34:04 INFO - PROCESS | 30044 | Values: 168.0 170.0 168.0 172.0 173.0 167.0 167.0 168.0 167.0 167.0 09:34:04 INFO - PROCESS | 30044 | 09:34:04 INFO - PROCESS | 30044 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:2.07 (3.1%) stddev-sans-first:2.18 09:34:04 INFO - PROCESS | 30044 | Values: 67.0 68.0 71.0 67.0 66.0 65.0 70.0 66.0 70.0 66.0 09:34:04 INFO - PROCESS | 30044 | -------- Summary: end -------- 09:34:04 INFO - PROCESS | 30044 | 09:34:05 INFO - TEST-INFO | 30044: exit 0 09:34:05 INFO - TEST-OK | kraken | took 63813ms 09:34:05 INFO - SUITE-END | took 1183s 09:34:05 INFO - Completed test suite (00:19:43) 09:34:05 INFO - javascript benchmark 09:34:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2581.6643836301278, 2589.9741002589967, 2618.018243158816, 2635.7232490588476, 2637.3406648337914, 4533.6146427553695, 4587.839425620104, 4638.234691275939, 4679.181143299922, 4702.224133018052, 4645.163870503308, 4688.953110468894, 4722.38608980832, 4734.479207287196, 4790.736509491977, 4269.487661480619, 4301.806418711166, 4339.913201735963, 4390.143921935224, 4469.82120715171, 4242.363645453176, 4399.890002749925, 4422.800973956179, 4433.553624910284, 4476.350929115292, 4153.7507749535125, 4198.685098617616, 4373.846915357947, 4379.5182529921685, 4470.687051906368, 4192.769397683126, 4219.071804203101, 4243.045314804175, 4250.702450828443, 4258.659307255412, 4240.1307731914885, 4291.871243862689, 4303.182395344875, 4308.849190278356, 4348.847790327355, 4484.394606728082, 4734.455537613184, 4745.59662428694, 4755.595774359183, 4772.856814295576, 4913.0, 4955.4796746341835, 4962.60299176065, 5033.345665063555, 5086.54221120101, 5036.244563315496, 5088.880446301808, 5099.235114732783, 5106.4638212987475, 5113.616478764107, 4488.977555112204, 4593.517680643551, 4764.118638051971, 4770.522947705236, 4782.617390608743, 5047.545720885138, 5092.083424983464, 5114.41184263807, 5130.230465430215, 5139.049275883974, 4884.584810291165, 4895.0, 4916.360873086476, 4942.061008408391, 4942.110420124341, 4789.401324834395, 4949.158643030693, 4967.304577359173, 5025.0, 5034.34553508049, 4911.68074075184, 4968.6025117990475, 4975.427825800007, 4994.675346102492, 5016.2224855147515, 4917.58200552957, 4935.432425271139, 4941.703497790144, 4942.827001054946, 4986.825461108844, 4072.714909956275, 4124.938125928114, 4257.127288905767, 4264.317709166518, 4314.61168494837, 3667.94498082529, 3680.190358121209, 3712.777233366007, 3742.4947632069866, 3795.7153213509096, 4302.462192225972, 4308.267594508941, 4309.978450107792, 4346.043870348582, 4351.216780979391, 4772.6420518461255, 4987.725675087832, 5006.148954677714, 5009.849704508871, 5033.219850923113, 4176.206520761046, 4286.207051695446, 4332.848350307724, 4343.630791382768, 4395.868123956287, 4504.09918016398, 4522.186006518792, 4522.276435770326, 4528.320751887178, 4564.292534657134, 4745.430548334222, 4756.833510827105, 4773.474917759113, 4774.092922344744, 4803.951960480351, 4839.443464001544, 4844.273358996179, 4921.138800709933, 4934.432540257916, 4986.6010719143105, 4707.576318131385, 4716.858494245178, 4720.7639618018275, 4721.055788842314, 4767.928481072718, 4932.827351042696, 4946.653734238569, 4947.505249475023, 4958.2810492477665, 5008.549230569266, 4803.063402636452, 4870.050340183772, 4899.0, 4931.457539670705, 5004.899902001866, 4795.808167673394, 4816.903661926812, 4821.083994041121, 4855.174620314484, 4857.757112144308, 4811.326414302071, 4813.590844778093, 4814.662973591815, 4815.686980346266, 4864.051509955525, 4588.9541104588525, 4718.032803275389, 4751.073540659539, 4771.928421073756, 4779.187538118597, 4879.243717223836, 4897.878345257926, 4904.994476132456, 4908.288298196813, 4936.062148191832, 4786.234202527578, 4790.8323208687525, 4791.137595232892, 4831.73425461603, 4836.975815120902], "value": 4585.164483943848, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.0495764636587, 572.0875204049539, 576.0638961687258, 577.2438106081034, 584.5791030458068, 29847.253818654517, 40037.0, 41449.378259326135, 41497.99999999996, 45647.543524564746, 41292.380614290734, 41639.79180104103, 41746.0, 41780.0, 41818.163636727244, 7766.8834967475395, 7901.446898717092, 7905.762827115196, 7906.407019473534, 7916.0, 7792.181820908808, 7851.136374998746, 7888.368930485576, 7891.4081443891655, 7898.368130549571, 7869.331106855924, 7900.0, 7900.209979002083, 7919.0, 7921.20787921209, 7771.572563509005, 7842.137364889858, 7844.333231675315, 7849.5682737449415, 7873.5275883447175, 7554.8866766998535, 7604.353629941462, 7610.543367397919, 7634.351080158193, 7654.770356889302, 38888.0, 39153.0, 39308.213835723414, 39308.41037384442, 39334.0, 3152.8423578820966, 3160.2573395252098, 3181.7454603631654, 3182.411253918033, 3275.557799697034, 30111.302898637055, 30137.0, 30224.697753022407, 30632.234194145323, 31199.84400078008, 3523.9295214095832, 3600.0999750062483, 3620.873269435557, 3679.4480827875764, 3703.166787472824, 260308.0, 261240.0, 262165.0, 263070.0, 263300.9999999981, 55818.0, 56088.15867761905, 56183.71908140433, 56251.0, 56285.437145628835, 33269.66730332714, 34828.477572836426, 34847.30305393878, 59559.40440595625, 59696.0, 14090.85909140916, 14099.436022559188, 14106.647333816736, 14147.575572732629, 14162.221077840612, 11807.291562506278, 11846.526138954345, 11869.169158158846, 11874.287542747463, 11875.524979000917, 18864.52838679044, 18923.43229703111, 18941.43175704731, 18982.240710371432, 18994.335198267996, 6833.6241506716615, 6857.142857142857, 6924.075924075924, 7073.418202356206, 7189.209186989427, 774.2799196747079, 780.3912947900646, 933.0762545080322, 936.798588303518, 998.2114129837347, 25142.622860657106, 26306.342341441617, 26688.466230675276, 26696.332591685365, 26710.86644566765, 940.7318914109446, 962.3936919740519, 968.7432830299977, 971.4802580619335, 1022.8414595737672, 12883.420246088948, 13360.599182024554, 13473.258970756506, 13608.251546164858, 13649.727005459836, 422549.0, 423971.0, 425958.0, 426004.8699756481, 427623.0, 51122.233166502534, 51322.0, 51600.22599661008, 51784.74107629438, 51795.223071653956, 1097.4896673047072, 1167.8481797366458, 1184.703824043989, 1187.7327601289815, 1243.9129260951827, 27889.302767430978, 30117.849410752806, 30332.545011824397, 30608.540871886493, 30853.228669283446, 43012.78493607512, 43478.78260608677, 43728.781356093015, 43749.12501750011, 43788.562114379725, 315792.0, 317298.0, 317883.0, 318047.0, 318145.0, 22957.540849182587, 25031.624525632495, 26185.73814261833, 26193.0, 26668.866655666596, 43302.0, 43786.56213437825, 43821.78089109534, 43892.0, 44177.55822441863, 37062.814685926394, 37395.0, 37507.06232344104, 38066.61933380631, 39460.802695986335, 38084.80957595194, 39592.60407396004, 40023.049715853056, 41830.79084604557, 42107.789461052496, 38977.22045559016, 39544.40683389809, 39547.209055818144, 39593.0, 39707.205855882145, 38230.61769382271, 39445.80277098596, 39650.801745991084, 39746.0, 39947.40078898877, 26640.866795665897, 27258.72741272562, 27283.86358068197, 27354.452910941272, 27469.450610988068], "value": 17912.009321910078, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.77281426294314, 358.58046086079287, 360.7420694203644, 365.6142769378306, 390.56647121694914, 16779.74830377544, 17308.394206202785, 17421.39025134119, 17466.650666986654, 17507.91246043768, 16923.830761692378, 17327.30690772371, 17943.730844037316, 18050.368237111703, 18071.63856722865, 5092.0834249834925, 5260.053190425722, 5270.7628156733035, 5302.310699609065, 5325.653832500894, 3729.254149170163, 3828.0, 3872.283627528902, 5174.534291913727, 5427.83716488504, 6177.783777567786, 6190.628562286277, 6190.81427557174, 6192.907106393408, 6193.411625895533, 5947.6134051286745, 6109.541784366191, 6166.475849552793, 6170.136180934673, 6184.25788905332, 5362.705051222181, 5379.462053794628, 5380.21986811914, 5381.569474442074, 5382.9730851345885, 21401.67897481539, 21695.783042169533, 21758.89120554387, 21816.563668726536, 21962.45093872666, 1982.692682634194, 2046.2326627514683, 2064.2465500092508, 2072.782357852434, 2511.0960054380407, 13344.733105337937, 13496.797548036788, 13665.385057672329, 13712.588622341345, 14375.137491750529, 1674.4390629139132, 1682.7644129821836, 1704.420497031003, 1743.651269746056, 1829.359724096569, 69212.30787692108, 69396.0, 70737.29262707359, 71122.28877711209, 71358.64320678363, 8604.741857744277, 8787.297016238686, 8916.910830891738, 9706.75733106678, 10101.646442374482, 13541.255230962195, 13566.321683915765, 13594.38825252866, 13614.18314901109, 13619.795703064461, 7233.963830180815, 7495.776815969186, 7568.735094271784, 7571.280728330799, 7675.61621918913, 7244.601546914971, 7265.89101163483, 7423.294786995227, 7459.70161193557, 7486.513376630502, 6121.84695382619, 6132.478739307209, 6156.5998210116195, 6233.407826256498, 6396.5202609804455, 1762.5505496098413, 1821.5628249220092, 1837.6784062788959, 1840.7791065072277, 1843.889366638006, 806.3146325623173, 808.4623725222779, 812.5252666410564, 875.8073223890734, 918.3617385916724, 16035.19824008795, 16793.748093778602, 17049.48851534456, 17458.912705436393, 17484.912575437294, 787.4409169489612, 844.8732690096411, 849.3290300662408, 869.2350731356366, 887.8357503861806, 9005.594748236344, 9274.165325120772, 9280.489573073412, 9430.3398762086, 9601.711948641552, 382805.0, 385130.0, 385640.0, 387674.0, 418617.0, 82738.17261827426, 84138.57930710308, 84231.0, 85388.57305713432, 87589.12410875937, 926.8424367857481, 952.2619969523536, 975.2831668723483, 978.6085565773748, 980.9068138526828, 16117.194140292704, 16610.75083873767, 17023.31906723717, 17113.330004992513, 17193.398231061852, 38188.42717359297, 38489.451142131446, 38528.42207366836, 38648.80675596604, 41541.1691766169, 50875.491245087076, 52236.216456752416, 52334.47665523448, 53608.0, 54499.18251226314, 7174.210836808052, 7294.343509084208, 7307.26927307265, 7351.66917488725, 7473.0312165225405, 8298.585070746318, 10487.0, 10764.623238186627, 11093.0, 12091.879081209076, 8918.37571369998, 8997.415168014059, 9012.369134160808, 9074.90925090767, 9318.34771566011, 9997.350172238781, 10197.286189966631, 10209.795804084026, 10226.744331391477, 10240.795184096425, 8616.181462761027, 8877.778055548662, 9108.499032553273, 9496.952515237379, 9545.570449329936, 7510.699572017058, 7513.248675132661, 7601.163871974189, 7611.923880761122, 7737.381009519338, 8792.0, 8889.4666320021, 8939.195472407515, 9022.548872556477, 9257.35198536096], "value": 8388.810301539253, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.6126200877734, 1360.3878254785343, 1412.3220853990085, 1467.2883651429056, 1653.7767401400815, 6175.783847565336, 7142.857142857146, 7360.631968401585, 7371.668274927621, 7574.4319176061745, 7427.554346739199, 7455.552666839993, 7481.812954676136, 7498.47510674253, 7498.6250687465545, 2142.7642959274467, 2169.3275084723787, 2170.1427935965285, 2171.5331203791184, 2172.674098885164, 2076.7611724651633, 2114.8836813975227, 2122.9256976005845, 2125.2455378340696, 2127.712758777561, 2101.443117573844, 2116.3650904728597, 2120.766715661276, 2127.393692797553, 2156.1159924430985, 2087.436392174112, 2091.9267825626107, 2092.7488701355783, 2093.2464312847364, 2094.6857971304275, 2015.0730663894626, 2066.71066050753, 2068.524239424926, 2069.048237810609, 2072.5025993761387, 7233.710651573931, 7353.889691654629, 7437.962810185969, 7438.367738742213, 7454.180040195574, 4253.936190957138, 4380.868573942755, 4434.556544345572, 4440.222960981848, 4455.643548516111, 2779.5830625406147, 2927.1072322941495, 2972.2420782700274, 2980.523116301403, 2998.5352270398125, 975.2929126383386, 983.0660872171466, 991.4002028772561, 991.5884907763269, 1009.8939611340777, 9850.06424389689, 10064.496775161213, 10074.445905475195, 10076.445795481319, 10082.747931301703, 4035.9145686672186, 4044.9191016180102, 4157.91684166315, 4169.249535083714, 4180.247555439989, 8625.396222264506, 8641.395102342776, 8644.265237454762, 8649.697260595975, 8656.52389118592, 4510.2783554631105, 4575.656825738083, 4581.610563102107, 4581.633469322446, 4584.014436896084, 3324.900252992414, 3648.890533284006, 4135.524414692289, 4169.791510424467, 4175.997760537449, 3579.1052236940764, 3633.709303255733, 3719.8698045568276, 3740.4202348635463, 3741.85032598693, 1178.711215752129, 1222.0834374219335, 1223.3332833605707, 1224.2960297828783, 1234.6357824441811, 647.5952529668957, 737.7159793479442, 754.1101500229781, 754.3248792330833, 774.013133255093, 4647.140279048387, 4659.5806377425515, 4695.483496815347, 4754.952450475451, 4791.808327666924, 413.34072154912866, 422.6576473056834, 425.15818679015723, 425.74029841796477, 435.3295924276642, 3328.5007248912857, 3363.714084302813, 3365.74756893234, 3388.9661103389144, 3447.293304872495, 8862.379633425599, 9283.58223879927, 9287.90712092884, 9304.209142222851, 9317.813643727088, 9208.447493150432, 9217.953910230406, 9223.53882305882, 9234.307426943005, 9254.907450925404, 7109.82225444368, 7121.5727056375745, 7168.139823221247, 7174.784756457315, 7185.389241914496, 4727.550882666142, 4895.33912921751, 4905.3623029006, 4948.851534453972, 5051.1160546904875, 8440.071592124985, 8510.191531804468, 8535.402521823413, 8629.654813807378, 8629.913700862911, 7239.710411583688, 8450.66197352099, 8691.913080869363, 8705.912940870763, 8707.434016788888, 5871.236739223874, 6022.728977196124, 6026.276846778239, 6177.536684748752, 6181.412765787243, 6539.901901471577, 6550.47596192313, 6605.867882642417, 6608.471322294301, 6616.966915165393, 5866.266716660418, 5915.645061296336, 5920.141579471038, 5925.288965324189, 5945.791897283574, 6005.309389420097, 6062.060380641008, 6081.6351018939, 6106.419890110433, 6169.1054797053275, 5684.0, 5753.165790960371, 5768.509676677361, 5771.249737534094, 5776.480116789509, 4248.468941382327, 4305.397244385851, 4340.891477713082, 4394.604485596312, 4432.357308190359, 4136.462259906193, 4275.593818587229, 4339.262325404626, 4359.782010899506, 4380.342948557742], "value": 3866.578131041811, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.25448243185957, 303.3992694464959, 306.78525032477256, 314.65388073119584, 316.52837272464035, 4836.081144582531, 4850.714560641429, 4855.854324370266, 4856.878578035551, 4881.926771098437, 4709.976450117753, 4720.719100225345, 4760.357351757511, 4810.086083644114, 4815.494373090819, 3633.927321453576, 3731.2724018816343, 3749.5500539935247, 3754.4743735877, 3765.5669597996307, 3719.75821571597, 3766.6610005099537, 3817.866374676887, 3826.693864490834, 3876.6898648108086, 3778.1310298631333, 3786.9431958520645, 3808.542974843023, 3820.388737801952, 3843.673287770543, 3566.661167189113, 3761.2665530221616, 3780.206156707095, 3783.205526839367, 3820.4269359596283, 3306.8842590509216, 3307.5038744188323, 3310.255192581674, 3315.3037862049, 3324.6176689680756, 4741.146593613148, 4759.571638552512, 4762.78567464465, 4764.857054288377, 4773.832915847928, 4247.938015496126, 4255.1064276501975, 4308.418363520916, 4337.652987760972, 4345.456817897762, 4908.9263661045115, 4964.230544265609, 4992.550670439678, 5000.524950129732, 5015.247712843103, 3614.1868079681863, 3617.2584620152807, 3622.2212224371624, 3623.2391197848483, 3623.710103191738, 5247.711375874325, 5284.5772338212855, 5296.576273898079, 5302.867428314323, 5309.787608495655, 3542.6457354264367, 3717.20080182762, 3724.2551489701627, 3740.9625903741157, 3741.401375779862, 3762.5484941806612, 3768.698504119664, 3775.2638235543805, 3775.7734535927934, 3778.3387907116694, 4263.786810659517, 4269.701120921506, 4282.807273672693, 4298.957010429918, 4299.441072660534, 4824.565789079, 5026.421961474404, 5040.823571175065, 5043.067032598981, 5043.167877300278, 1267.7084270617809, 1276.234259444326, 1282.8838910148231, 1286.6747250332173, 1295.4170623219588, 802.3781569283851, 813.7558732380262, 823.4112609484127, 825.3644693585906, 829.0051937674814, 4244.427002354642, 4314.762688052125, 4362.672799540047, 4364.650827933758, 4364.890877728082, 5090.007448547572, 5196.869283593708, 5198.2202669599865, 5199.7140157291715, 5212.55693266069, 4389.517153113155, 4427.712198707074, 4431.825566224953, 4473.664475164375, 4476.328550717419, 2617.9345516361864, 2650.5494066008823, 2705.229009732217, 2711.9864400678266, 2722.4963381774437, 4776.617870570346, 4795.568398844121, 4818.156822556039, 4822.421309442819, 4829.830955916526, 5055.6713813602, 5241.266222728821, 5256.764660304825, 5257.950388774501, 5282.652923504482, 4740.250654620312, 4797.800549862534, 4813.0, 4848.666616680441, 4868.829590964299, 5112.616553758408, 5123.257127716534, 5124.846254612367, 5156.819511317086, 5168.9741551292, 4756.785944632431, 4995.925061124159, 5030.647854650139, 5032.547070763649, 5038.345015148007, 4772.615941376962, 4806.975965120152, 4826.986332869996, 4830.154722923545, 4832.903341933212, 3679.822456813794, 3692.464592634107, 3692.8892133236045, 3713.901645570244, 3722.571904231048, 5163.6058264267685, 5184.481551844936, 5192.792288308577, 5193.169092945111, 5195.740212989411, 4758.571728544448, 5012.77442515095, 5017.57350625201, 5024.472430394902, 5052.747362631781, 4960.181570040902, 5001.224810154432, 5009.348784657971, 5011.574016208663, 5018.5232402921665, 4826.710397376169, 4944.653874228769, 4955.578775804097, 4989.451160372429, 5016.54851063406, 4750.239961606127, 4869.805207791649, 4984.800607975641, 5020.0, 5070.442251352422, 4919.803207871788, 4926.531979461945, 4928.926066109083, 4940.901181976268, 4948.826791062295], "value": 3787.78654130373, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.6375719717004, 647.5175993884557, 691.3432239372597, 698.3156506623505, 749.1646813802608, 13839.861601383996, 14785.55643330699, 14827.629309267273, 14837.777433338495, 14855.108693478373, 12791.104622676416, 12872.09895307329, 12887.0, 12906.870931290685, 12931.547395841148, 639.6322114783994, 769.9807504812383, 776.5884081436819, 792.2711105782694, 806.9435139540235, 779.154617240295, 790.7192946503994, 791.0349373764027, 791.4618059719388, 791.8614242507567, 845.9661613535482, 850.1031411860484, 850.2985037344184, 850.4557083466586, 850.6597361055566, 668.1714673804472, 809.299955538455, 835.3359079531787, 845.7420486751538, 846.9449485783435, 615.5629503052838, 620.7268801727225, 621.1583304622217, 621.3848290192676, 621.4375989729302, 13400.597982060553, 13441.327933603281, 13455.125416847874, 13472.326383680776, 13490.797638035438, 12810.74378512434, 13185.406656700472, 13569.728605427836, 13625.591232262948, 13887.930560347133, 11900.226485278428, 11924.642260732191, 12054.578089766816, 12099.637010889599, 12304.81542776859, 4148.834046638161, 4151.605597468236, 4152.162854948582, 4190.790460476995, 4256.425382573344, 57304.42695573033, 58804.117938230964, 59038.70480647569, 64770.676146618964, 64965.3503464964, 10200.642977495752, 10731.89268107309, 10770.892291076989, 10810.459477026274, 10815.242932994615, 18898.905505472383, 18906.0, 18936.24255029811, 18938.242470301033, 18971.43085707431, 7265.0, 7296.124465064227, 7324.487285889936, 7366.410687145016, 7512.699492020367, 3888.7083468739957, 3914.6085391460624, 3989.7606143631474, 4045.8988525287104, 4055.91888162241, 2871.9282017949718, 2984.6418429788564, 3005.8046226995175, 3006.5790789289517, 3010.1120169550036, 500.2596157686644, 506.6554742774875, 515.3635260453337, 518.722483471341, 520.5159201942155, 568.7014317483354, 593.5577994394117, 598.4733434577544, 604.909263610462, 633.9049142628643, 7956.84086318282, 8491.490510569261, 8667.263282621048, 8675.869861952076, 8758.824823503493, 509.81901424994334, 529.4652401074865, 569.4248808703239, 571.977120915167, 581.8021872563348, 3959.0696186396176, 3967.5834037425657, 4107.137501124797, 4118.19695159441, 4147.19129769692, 57018.7149064252, 66397.0, 106980.0, 110483.0, 110741.57033718722, 51063.489365106616, 51147.0, 74954.0, 75894.62052689701, 93210.0, 63882.25059744645, 79712.60143699244, 79799.0, 80549.59725201337, 80584.59707701424, 7186.38915692172, 7517.361024312994, 7680.654370553227, 7874.645640946285, 7957.880631790644, 47626.76186619045, 47812.52187478081, 48084.7595762019, 49222.75388623034, 49240.261396079804, 38556.03609909775, 39219.215615688096, 39303.0, 59023.70488147704, 67849.32150678628, 10048.94975525147, 10089.344192627455, 10151.492425378554, 10239.18086553059, 10288.0, 10184.694459166238, 10407.895921040692, 10748.193885458466, 10992.780144397228, 11420.143489238175, 9608.13526782593, 9627.229821614394, 9775.413475191512, 9801.362911410735, 9904.50477476109, 11549.769004619693, 11722.355270460206, 11820.408979550815, 12461.626151215478, 12506.124571279923, 11607.477663504993, 11775.705607359885, 12136.211146275817, 12289.508419663114, 12679.23924564529, 10112.848307275544, 10321.74195645115, 10356.689299321033, 10582.682519524427, 11038.0, 10046.54790534413, 10411.53148108352, 10449.0, 10502.317349372266, 10525.736856578647], "value": 6572.964210968743, "unit": "score"}], "name": "dromaeo_css", "value": 6362.403779156513, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 116.0, 116.0, 114.0, 117.0, 114.0, 114.0, 117.0, 117.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 123.0, 127.0, 185.0, 128.0, 125.0, 155.0, 121.0, 195.0, 122.0], "value": 143.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 163.0, 160.0, 161.0, 160.0, 162.0, 162.0, 163.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 105.0, 75.0, 77.0, 75.0, 75.0, 103.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 136.0, 85.0, 85.0, 86.0, 117.0, 85.0, 86.0, 120.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 188.0, 146.0, 147.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 62.0, 62.0, 64.0, 62.0, 63.0, 63.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 78.0, 88.0, 80.0, 85.0, 78.0, 78.0, 79.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 129.0, 129.0, 131.0, 130.0, 127.0, 132.0, 137.0, 144.0, 144.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 168.0, 172.0, 173.0, 167.0, 167.0, 168.0, 167.0, 167.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 71.0, 67.0, 66.0, 65.0, 70.0, 66.0, 70.0, 66.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1537.9999999999998, "alertThreshold": 2.0}]} 09:34:05 INFO - Return code: 0 09:34:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:34:05 INFO - # TBPL SUCCESS # 09:34:05 INFO - Running post-action listener: _resource_record_post_action 09:34:05 INFO - [mozharness: 2016-08-09 16:34:05.500172Z] Finished run-tests step (success) 09:34:05 INFO - Running post-run listener: _resource_record_post_run 09:34:05 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 12288; Write bytes: 524537856; Read time: 280; Write time: 450408 09:34:05 INFO - TinderboxPrint: CPU usage
13.0% 09:34:05 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 280 09:34:05 INFO - TinderboxPrint: I/O write bytes / time
524,537,856 / 450,408 09:34:05 INFO - TinderboxPrint: CPU idle
8,334.4 (87.0%) 09:34:05 INFO - TinderboxPrint: CPU user
1,198.7 (12.5%) 09:34:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:34:05 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 237838336; Read time: 188; Write time: 259052 09:34:05 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 280010752; Read time: 64; Write time: 190520 09:34:06 INFO - Running post-run listener: _upload_blobber_files 09:34:06 INFO - Blob upload gear active. 09:34:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:34: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 09:34: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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:34: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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:34:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:34:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:34:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:34:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:07 INFO - (blobuploader) - INFO - Done attempting. 09:34:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:34:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:34:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:08 INFO - (blobuploader) - INFO - Done attempting. 09:34:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:34:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:34:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:12 INFO - (blobuploader) - INFO - Done attempting. 09:34:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:34:12 INFO - Return code: 0 09:34:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:34:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:34:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0ba07bc1fe8746013c0bc0dfbc8c6c79661508d4c23978414b6729b6e96d3740e7fbacd75300de5fd0cb64f86f84eac8470a9b72fb175a902039135b10e0a995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/26052d8942be078528b0b94adbfa1ecf0333dca3232511ddb22f5164f74488ff16a19b259fd696d7ed97803f7856d2f481a6dc359468f5c98aa710b4b5fb05e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/733b8ddc9490ac0a61fb5ebfdff54fa309431b21f602f426affaf07f0d950a89f7096e2d4a8f59a2748595b801fb601bed56c167c10bc54a644db47721a46553"} 09:34:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:34:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:34:12 INFO - Contents: 09:34:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0ba07bc1fe8746013c0bc0dfbc8c6c79661508d4c23978414b6729b6e96d3740e7fbacd75300de5fd0cb64f86f84eac8470a9b72fb175a902039135b10e0a995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/26052d8942be078528b0b94adbfa1ecf0333dca3232511ddb22f5164f74488ff16a19b259fd696d7ed97803f7856d2f481a6dc359468f5c98aa710b4b5fb05e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/733b8ddc9490ac0a61fb5ebfdff54fa309431b21f602f426affaf07f0d950a89f7096e2d4a8f59a2748595b801fb601bed56c167c10bc54a644db47721a46553"} 09:34:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:34:12 INFO - Copying logs to upload dir... 09:34:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:34:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.239354 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-09 09:34:12.467363) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 09:34:12.470754) ========= 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=17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0ba07bc1fe8746013c0bc0dfbc8c6c79661508d4c23978414b6729b6e96d3740e7fbacd75300de5fd0cb64f86f84eac8470a9b72fb175a902039135b10e0a995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/26052d8942be078528b0b94adbfa1ecf0333dca3232511ddb22f5164f74488ff16a19b259fd696d7ed97803f7856d2f481a6dc359468f5c98aa710b4b5fb05e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/733b8ddc9490ac0a61fb5ebfdff54fa309431b21f602f426affaf07f0d950a89f7096e2d4a8f59a2748595b801fb601bed56c167c10bc54a644db47721a46553"} build_url:https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/LklkLQz_T0WZ3GZ3BaT9jw/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/Autoland-Non-PGO/sha512/0ba07bc1fe8746013c0bc0dfbc8c6c79661508d4c23978414b6729b6e96d3740e7fbacd75300de5fd0cb64f86f84eac8470a9b72fb175a902039135b10e0a995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/26052d8942be078528b0b94adbfa1ecf0333dca3232511ddb22f5164f74488ff16a19b259fd696d7ed97803f7856d2f481a6dc359468f5c98aa710b4b5fb05e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/733b8ddc9490ac0a61fb5ebfdff54fa309431b21f602f426affaf07f0d950a89f7096e2d4a8f59a2748595b801fb601bed56c167c10bc54a644db47721a46553"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 09:34:12.499490) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:34:12.499811) ========= 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=17051fcd5208b27b50bedfeb000001dd-1470759213.434279-1855979217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:34:12.582781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 09:34:12.583450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 09:34:12.584116) ========= ========= Total master_lag: 0.18 =========