builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-063 starttime: 1470877911.05 results: success (0) buildid: 20160810165421 builduid: 7753e1e4f1f54092b4584c1b6926a41a revision: 0502bd9e025edde29777ba1de4280f9b52af4663 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.046370) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.046802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.047111) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.107388) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.107714) ========= 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=33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 18:11:51-- 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% 45.8M=0s 2016-08-10 18:11:51 (45.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092984 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.230757) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.231038) ========= 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=33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014372 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.286619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.286928) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0502bd9e025edde29777ba1de4280f9b52af4663 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0502bd9e025edde29777ba1de4280f9b52af4663 --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=33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 18:11:51,472 truncating revision to first 12 chars 2016-08-10 18:11:51,472 Setting DEBUG logging. 2016-08-10 18:11:51,472 attempt 1/10 2016-08-10 18:11:51,472 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0502bd9e025e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 18:11:51,782 unpacking tar archive at: mozilla-central-0502bd9e025e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657091 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.960248) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.960524) ========= script_repo_revision: 0502bd9e025edde29777ba1de4280f9b52af4663 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.960891) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.961180) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 18:11:51.970625) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-10 18:11:51.970947) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:11:52 INFO - MultiFileLogger online at 20160810 18:11:52 in /builds/slave/test 18:11:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:11:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:52 INFO - {'append_to_log': False, 18:11:52 INFO - 'base_work_dir': '/builds/slave/test', 18:11:52 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:11:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:52 INFO - 'branch': 'Firefox-Non-PGO', 18:11:52 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:52 INFO - 'config_files': ('talos/linux_config.py',), 18:11:52 INFO - 'default_actions': ('clobber', 18:11:52 INFO - 'read-buildbot-config', 18:11:52 INFO - 'download-and-extract', 18:11:52 INFO - 'populate-webroot', 18:11:52 INFO - 'create-virtualenv', 18:11:52 INFO - 'install', 18:11:52 INFO - 'run-tests'), 18:11:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:52 INFO - 'download_minidump_stackwalk': True, 18:11:52 INFO - 'download_symbols': 'ondemand', 18:11:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:11:52 INFO - 'tooltool.py': '/tools/tooltool.py', 18:11:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:11:52 INFO - '/tools/misc-python/virtualenv.py')}, 18:11:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:52 INFO - 'installer_path': 'installer.exe', 18:11:52 INFO - 'log_level': 'info', 18:11:52 INFO - 'log_name': 'talos', 18:11:52 INFO - 'log_to_console': True, 18:11:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:11:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:11:52 INFO - 'opt_config_files': (), 18:11:52 INFO - 'pip_index': False, 18:11:52 INFO - 'sps_profile': False, 18:11:52 INFO - 'sps_profile_interval': 0, 18:11:52 INFO - 'suite': 'dromaeojs', 18:11:52 INFO - 'system_bits': '32', 18:11:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:52 INFO - 'title': 'talos-linux64-ix-063', 18:11:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:52 INFO - 'use_talos_json': True, 18:11:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:52 INFO - 'work_dir': 'build'} 18:11:52 INFO - [mozharness: 2016-08-11 01:11:52.241873Z] Running clobber step. 18:11:52 INFO - Running pre-action listener: _resource_record_pre_action 18:11:52 INFO - Running main action method: clobber 18:11:52 INFO - rmtree: /builds/slave/test/build 18:11:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:53 INFO - Running post-action listener: _resource_record_post_action 18:11:53 INFO - [mozharness: 2016-08-11 01:11:53.214825Z] Finished clobber step (success) 18:11:53 INFO - [mozharness: 2016-08-11 01:11:53.214925Z] Running read-buildbot-config step. 18:11:53 INFO - Running pre-action listener: _resource_record_pre_action 18:11:53 INFO - Running main action method: read_buildbot_config 18:11:53 INFO - Using buildbot properties: 18:11:53 INFO - { 18:11:53 INFO - "project": "", 18:11:53 INFO - "product": "firefox", 18:11:53 INFO - "script_repo_revision": "production", 18:11:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:11:53 INFO - "repository": "", 18:11:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:11:53 INFO - "buildid": "20160810165421", 18:11:53 INFO - "pgo_build": "False", 18:11:53 INFO - "basedir": "/builds/slave/test", 18:11:53 INFO - "buildnumber": 591, 18:11:53 INFO - "slavename": "talos-linux64-ix-063", 18:11:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:11:53 INFO - "platform": "ubuntu64_hw", 18:11:53 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:11:53 INFO - "branch": "mozilla-central", 18:11:53 INFO - "repo_path": "mozilla-central", 18:11:53 INFO - "revision": "0502bd9e025edde29777ba1de4280f9b52af4663", 18:11:53 INFO - "stage_platform": "linux64", 18:11:53 INFO - "builduid": "7753e1e4f1f54092b4584c1b6926a41a", 18:11:53 INFO - "slavebuilddir": "test" 18:11:53 INFO - } 18:11:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:11:53 INFO - Running post-action listener: _resource_record_post_action 18:11:53 INFO - [mozharness: 2016-08-11 01:11:53.217344Z] Finished read-buildbot-config step (success) 18:11:53 INFO - [mozharness: 2016-08-11 01:11:53.217440Z] Running download-and-extract step. 18:11:53 INFO - Running pre-action listener: _resource_record_pre_action 18:11:53 INFO - Running main action method: download_and_extract 18:11:53 INFO - mkdir: /builds/slave/test/build/tests 18:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:53 INFO - trying https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:53 INFO - Downloaded 1361 bytes. 18:11:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:11:53 INFO - Using the following test package requirements: 18:11:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:11:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:11:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:11:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'jsshell-linux-x86_64.zip'], 18:11:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:11:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:11:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:11:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:11:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:11:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:11:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:53 INFO - trying https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:54 INFO - Downloaded 20262077 bytes. 18:11:54 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'] 18:11:54 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 18:11:55 INFO - Return code: 0 18:11:55 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 18:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:55 INFO - trying https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:11:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:57 INFO - Downloaded 20262077 bytes. 18:11:57 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'] 18:11:57 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 18:11:59 INFO - Return code: 0 18:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:59 INFO - trying https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:11:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:12:00 INFO - Downloaded 14413719 bytes. 18:12:00 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'] 18:12:00 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 18:12:00 INFO - Return code: 0 18:12:00 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')]} 18:12:00 INFO - trying https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:12:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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 18:12:02 INFO - Downloaded 55619608 bytes. 18:12:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:12:02 INFO - mkdir: /builds/slave/test/properties 18:12:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:12:02 INFO - Writing to file /builds/slave/test/properties/build_url 18:12:02 INFO - Contents: 18:12:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:12:02 INFO - Running post-action listener: _resource_record_post_action 18:12:02 INFO - Running post-action listener: set_extra_try_arguments 18:12:02 INFO - [mozharness: 2016-08-11 01:12:02.916121Z] Finished download-and-extract step (success) 18:12:02 INFO - [mozharness: 2016-08-11 01:12:02.916354Z] Running populate-webroot step. 18:12:02 INFO - Running pre-action listener: _resource_record_pre_action 18:12:02 INFO - Running main action method: populate_webroot 18:12:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:12:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:12:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:12:02 INFO - '--tppagecycles', 18:12:02 INFO - '7'], 18:12:02 INFO - 'tests': ['tsvgm']}}, 18:12:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:12:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:12:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:12:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:12:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['damp', 'tps']}, 18:12:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'tests': ['damp', 'tps']}, 18:12:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['dromaeo_dom']}, 18:12:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:12:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['basic_compositor_video']}, 18:12:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:12:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['a11yr', 18:12:02 INFO - 'ts_paint', 18:12:02 INFO - 'tpaint', 18:12:02 INFO - 'sessionrestore', 18:12:02 INFO - 'sessionrestore_no_auto_restore', 18:12:02 INFO - 'tabpaint']}, 18:12:02 INFO - 'other-e10s': {'tests': ['a11yr', 18:12:02 INFO - 'ts_paint', 18:12:02 INFO - 'tpaint', 18:12:02 INFO - 'sessionrestore', 18:12:02 INFO - 'sessionrestore_no_auto_restore', 18:12:02 INFO - 'tabpaint']}, 18:12:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:12:02 INFO - 'ts_paint', 18:12:02 INFO - 'tpaint', 18:12:02 INFO - 'sessionrestore', 18:12:02 INFO - 'sessionrestore_no_auto_restore', 18:12:02 INFO - 'tabpaint']}, 18:12:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:12:02 INFO - 'ts_paint', 18:12:02 INFO - 'tpaint', 18:12:02 INFO - 'sessionrestore', 18:12:02 INFO - 'sessionrestore_no_auto_restore', 18:12:02 INFO - 'tabpaint']}, 18:12:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['a11yr', 18:12:02 INFO - 'ts_paint', 18:12:02 INFO - 'tpaint', 18:12:02 INFO - 'sessionrestore', 18:12:02 INFO - 'sessionrestore_no_auto_restore', 18:12:02 INFO - 'tabpaint']}, 18:12:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['a11yr', 18:12:02 INFO - 'ts_paint', 18:12:02 INFO - 'tpaint', 18:12:02 INFO - 'sessionrestore', 18:12:02 INFO - 'sessionrestore_no_auto_restore', 18:12:02 INFO - 'tabpaint']}, 18:12:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['tsvgx', 18:12:02 INFO - 'tsvgr_opacity', 18:12:02 INFO - 'tart', 18:12:02 INFO - 'tscrollx', 18:12:02 INFO - 'cart']}, 18:12:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:12:02 INFO - 'tsvgr_opacity', 18:12:02 INFO - 'tart', 18:12:02 INFO - 'tscrollx', 18:12:02 INFO - 'cart']}, 18:12:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'talos_options': ['--disable-e10s'], 18:12:02 INFO - 'tests': ['tp5o']}, 18:12:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'tests': ['tp5o']}, 18:12:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'talos_options': ['--disable-e10s', 18:12:02 INFO - '--xperf_path', 18:12:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:02 INFO - 'tests': ['tp5n']}, 18:12:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:02 INFO - 'talos_options': ['--xperf_path', 18:12:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:02 INFO - 'tests': ['tp5n']}}, 18:12:02 INFO - 'talos.zip': {'path': '', 18:12:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:12:02 INFO - Running post-action listener: _resource_record_post_action 18:12:02 INFO - [mozharness: 2016-08-11 01:12:02.938029Z] Finished populate-webroot step (success) 18:12:02 INFO - [mozharness: 2016-08-11 01:12:02.938118Z] Running create-virtualenv step. 18:12:02 INFO - Running pre-action listener: _resource_record_pre_action 18:12:02 INFO - Running main action method: create_virtualenv 18:12:02 INFO - Creating virtualenv /builds/slave/test/build/venv 18:12:02 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 18:12:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:12:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:12:03 INFO - Using real prefix '/usr' 18:12:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:12:03 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:05 INFO - Installing pip.................done. 18:12:05 INFO - Return code: 0 18:12:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12: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', 'pip>=1.5'] in /builds/slave/test/build 18:12: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 pip>=1.5 18:12:05 INFO - Using env: {'DISPLAY': ':0', 18:12:05 INFO - 'HOME': '/home/cltbld', 18:12:05 INFO - 'LANG': 'en_US.UTF-8', 18:12:05 INFO - 'LANGUAGE': 'en_US:en', 18:12:05 INFO - 'LOGNAME': 'cltbld', 18:12:05 INFO - 'MAIL': '/var/mail/cltbld', 18:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:05 INFO - 'MOZ_NO_REMOTE': '1', 18:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:05 INFO - 'NO_EM_RESTART': '1', 18:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:05 INFO - 'PWD': '/builds/slave/test', 18:12:05 INFO - 'SHELL': '/bin/bash', 18:12:05 INFO - 'SHLVL': '1', 18:12:05 INFO - 'TERM': 'linux', 18:12:05 INFO - 'TMOUT': '86400', 18:12:05 INFO - 'USER': 'cltbld', 18:12:05 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:05 INFO - '_': '/tools/buildbot/bin/python'} 18:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:05 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 18:12:05 INFO - Cleaning up... 18:12:05 INFO - Return code: 0 18:12:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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', '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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:12: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 psutil>=3.1.1 18:12:05 INFO - Using env: {'DISPLAY': ':0', 18:12:05 INFO - 'HOME': '/home/cltbld', 18:12:05 INFO - 'LANG': 'en_US.UTF-8', 18:12:05 INFO - 'LANGUAGE': 'en_US:en', 18:12:05 INFO - 'LOGNAME': 'cltbld', 18:12:05 INFO - 'MAIL': '/var/mail/cltbld', 18:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:05 INFO - 'MOZ_NO_REMOTE': '1', 18:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:05 INFO - 'NO_EM_RESTART': '1', 18:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:05 INFO - 'PWD': '/builds/slave/test', 18:12:05 INFO - 'SHELL': '/bin/bash', 18:12:05 INFO - 'SHLVL': '1', 18:12:05 INFO - 'TERM': 'linux', 18:12:05 INFO - 'TMOUT': '86400', 18:12:05 INFO - 'USER': 'cltbld', 18:12:05 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:05 INFO - '_': '/tools/buildbot/bin/python'} 18:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:05 INFO - Downloading/unpacking psutil>=3.1.1 18:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:07 INFO - Installing collected packages: psutil 18:12:07 INFO - Running setup.py install for psutil 18:12:08 INFO - building 'psutil._psutil_linux' extension 18:12:08 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 18:12:08 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 18:12:08 INFO - building 'psutil._psutil_posix' extension 18:12:08 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 18:12:08 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 18:12:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:08 INFO - Successfully installed psutil 18:12:08 INFO - Cleaning up... 18:12:08 INFO - Return code: 0 18:12:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:08 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')]} 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:08 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')]} 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:08 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:08 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 18:12:08 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 18:12:08 INFO - Using env: {'DISPLAY': ':0', 18:12:08 INFO - 'HOME': '/home/cltbld', 18:12:08 INFO - 'LANG': 'en_US.UTF-8', 18:12:08 INFO - 'LANGUAGE': 'en_US:en', 18:12:08 INFO - 'LOGNAME': 'cltbld', 18:12:08 INFO - 'MAIL': '/var/mail/cltbld', 18:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:08 INFO - 'MOZ_NO_REMOTE': '1', 18:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:08 INFO - 'NO_EM_RESTART': '1', 18:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:08 INFO - 'PWD': '/builds/slave/test', 18:12:08 INFO - 'SHELL': '/bin/bash', 18:12:08 INFO - 'SHLVL': '1', 18:12:08 INFO - 'TERM': 'linux', 18:12:08 INFO - 'TMOUT': '86400', 18:12:08 INFO - 'USER': 'cltbld', 18:12:08 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:08 INFO - '_': '/tools/buildbot/bin/python'} 18:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:08 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 18:12:08 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 18:12:08 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 18:12:08 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 18:12:08 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 18:12:08 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 18:12:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:10 INFO - Installing collected packages: mozsystemmonitor 18:12:10 INFO - Running setup.py install for mozsystemmonitor 18:12:10 INFO - Successfully installed mozsystemmonitor 18:12:10 INFO - Cleaning up... 18:12:10 INFO - Return code: 0 18:12:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:12:10 INFO - Using env: {'DISPLAY': ':0', 18:12:10 INFO - 'HOME': '/home/cltbld', 18:12:10 INFO - 'LANG': 'en_US.UTF-8', 18:12:10 INFO - 'LANGUAGE': 'en_US:en', 18:12:10 INFO - 'LOGNAME': 'cltbld', 18:12:10 INFO - 'MAIL': '/var/mail/cltbld', 18:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:10 INFO - 'MOZ_NO_REMOTE': '1', 18:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:10 INFO - 'NO_EM_RESTART': '1', 18:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:10 INFO - 'PWD': '/builds/slave/test', 18:12:10 INFO - 'SHELL': '/bin/bash', 18:12:10 INFO - 'SHLVL': '1', 18:12:10 INFO - 'TERM': 'linux', 18:12:10 INFO - 'TMOUT': '86400', 18:12:10 INFO - 'USER': 'cltbld', 18:12:10 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:10 INFO - '_': '/tools/buildbot/bin/python'} 18:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:10 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:13 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:13 INFO - warning: no files found matching '*.txt' 18:12:13 INFO - no previously-included directories found matching 'build' 18:12:13 INFO - no previously-included directories found matching 'dist' 18:12:13 INFO - no previously-included directories found matching '.git*' 18:12:13 INFO - Installing collected packages: jsonschema, functools32 18:12:13 INFO - Running setup.py install for functools32 18:12:13 INFO - warning: no files found matching '*.txt' 18:12:13 INFO - no previously-included directories found matching 'build' 18:12:13 INFO - no previously-included directories found matching 'dist' 18:12:13 INFO - no previously-included directories found matching '.git*' 18:12:13 INFO - Successfully installed jsonschema functools32 18:12:13 INFO - Cleaning up... 18:12:13 INFO - Return code: 0 18:12:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12:13 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')]} 18:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:13 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')]} 18:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:13 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:13 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 18:12:13 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 18:12:13 INFO - Using env: {'DISPLAY': ':0', 18:12:13 INFO - 'HOME': '/home/cltbld', 18:12:13 INFO - 'LANG': 'en_US.UTF-8', 18:12:13 INFO - 'LANGUAGE': 'en_US:en', 18:12:13 INFO - 'LOGNAME': 'cltbld', 18:12:13 INFO - 'MAIL': '/var/mail/cltbld', 18:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:13 INFO - 'MOZ_NO_REMOTE': '1', 18:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:13 INFO - 'NO_EM_RESTART': '1', 18:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:13 INFO - 'PWD': '/builds/slave/test', 18:12:13 INFO - 'SHELL': '/bin/bash', 18:12:13 INFO - 'SHLVL': '1', 18:12:13 INFO - 'TERM': 'linux', 18:12:13 INFO - 'TMOUT': '86400', 18:12:13 INFO - 'USER': 'cltbld', 18:12:13 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:13 INFO - '_': '/tools/buildbot/bin/python'} 18:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:13 INFO - Cleaning up... 18:12:13 INFO - Return code: 0 18:12:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12:13 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')]} 18:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:13 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')]} 18:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:13 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:13 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 18:12:13 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 18:12:13 INFO - Using env: {'DISPLAY': ':0', 18:12:13 INFO - 'HOME': '/home/cltbld', 18:12:13 INFO - 'LANG': 'en_US.UTF-8', 18:12:13 INFO - 'LANGUAGE': 'en_US:en', 18:12:13 INFO - 'LOGNAME': 'cltbld', 18:12:13 INFO - 'MAIL': '/var/mail/cltbld', 18:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:13 INFO - 'MOZ_NO_REMOTE': '1', 18:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:13 INFO - 'NO_EM_RESTART': '1', 18:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:13 INFO - 'PWD': '/builds/slave/test', 18:12:13 INFO - 'SHELL': '/bin/bash', 18:12:13 INFO - 'SHLVL': '1', 18:12:13 INFO - 'TERM': 'linux', 18:12:13 INFO - 'TMOUT': '86400', 18:12:13 INFO - 'USER': 'cltbld', 18:12:13 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:13 INFO - '_': '/tools/buildbot/bin/python'} 18:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:13 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:15 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:16 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 18:12:16 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 18:12:16 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 18:12:16 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 18:12:16 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 18:12:16 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 18:12:16 INFO - Downloading docopt-0.6.1.tar.gz 18:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:16 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:16 INFO - Running setup.py install for blobuploader 18:12:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:16 INFO - Running setup.py install for requests 18:12:16 INFO - Running setup.py install for docopt 18:12:16 INFO - Successfully installed blobuploader requests docopt 18:12:16 INFO - Cleaning up... 18:12:16 INFO - Return code: 0 18:12:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:16 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')]} 18:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:16 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')]} 18:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:16 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:16 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 18:12:16 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 18:12:16 INFO - Using env: {'DISPLAY': ':0', 18:12:16 INFO - 'HOME': '/home/cltbld', 18:12:16 INFO - 'LANG': 'en_US.UTF-8', 18:12:16 INFO - 'LANGUAGE': 'en_US:en', 18:12:16 INFO - 'LOGNAME': 'cltbld', 18:12:16 INFO - 'MAIL': '/var/mail/cltbld', 18:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:16 INFO - 'MOZ_NO_REMOTE': '1', 18:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:16 INFO - 'NO_EM_RESTART': '1', 18:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:16 INFO - 'PWD': '/builds/slave/test', 18:12:16 INFO - 'SHELL': '/bin/bash', 18:12:16 INFO - 'SHLVL': '1', 18:12:16 INFO - 'TERM': 'linux', 18:12:16 INFO - 'TMOUT': '86400', 18:12:16 INFO - 'USER': 'cltbld', 18:12:16 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:16 INFO - '_': '/tools/buildbot/bin/python'} 18:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:17 INFO - Running setup.py (path:/tmp/pip-SEnm_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:17 INFO - Running setup.py (path:/tmp/pip-7_RSRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:17 INFO - Running setup.py (path:/tmp/pip-4Sl8Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:17 INFO - Running setup.py (path:/tmp/pip-J6QMox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:17 INFO - Running setup.py (path:/tmp/pip-6SUWdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:17 INFO - Running setup.py (path:/tmp/pip-XXWoL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:17 INFO - Running setup.py (path:/tmp/pip-07Wqa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:17 INFO - Running setup.py (path:/tmp/pip-gcCZ4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:17 INFO - Running setup.py (path:/tmp/pip-7BgJRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:17 INFO - Running setup.py (path:/tmp/pip-rRlRta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:17 INFO - Running setup.py (path:/tmp/pip-cRuTsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:17 INFO - Running setup.py (path:/tmp/pip-BtVHTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:18 INFO - Running setup.py (path:/tmp/pip-2GBxxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:18 INFO - Running setup.py (path:/tmp/pip-tdKWP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:18 INFO - Running setup.py (path:/tmp/pip-csV3VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:18 INFO - Running setup.py (path:/tmp/pip-mNB48l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:18 INFO - Running setup.py (path:/tmp/pip-qGrnDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:18 INFO - Running setup.py install for manifestparser 18:12:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:18 INFO - Running setup.py install for mozcrash 18:12:18 INFO - Running setup.py install for mozdebug 18:12:18 INFO - Running setup.py install for mozdevice 18:12:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:12:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:18 INFO - Running setup.py install for mozfile 18:12:18 INFO - Running setup.py install for mozhttpd 18:12:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozinfo 18:12:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozInstall 18:12:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozleak 18:12:19 INFO - Running setup.py install for mozlog 18:12:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for moznetwork 18:12:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozprocess 18:12:19 INFO - Running setup.py install for mozprofile 18:12:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozrunner 18:12:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozscreenshot 18:12:20 INFO - Running setup.py install for moztest 18:12:20 INFO - Running setup.py install for mozversion 18:12:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:20 INFO - Cleaning up... 18:12:20 INFO - Return code: 0 18:12:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:20 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')]} 18:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:20 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')]} 18:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:20 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:20 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 18:12:20 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 18:12:20 INFO - Using env: {'DISPLAY': ':0', 18:12:20 INFO - 'HOME': '/home/cltbld', 18:12:20 INFO - 'LANG': 'en_US.UTF-8', 18:12:20 INFO - 'LANGUAGE': 'en_US:en', 18:12:20 INFO - 'LOGNAME': 'cltbld', 18:12:20 INFO - 'MAIL': '/var/mail/cltbld', 18:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:20 INFO - 'MOZ_NO_REMOTE': '1', 18:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:20 INFO - 'NO_EM_RESTART': '1', 18:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:20 INFO - 'PWD': '/builds/slave/test', 18:12:20 INFO - 'SHELL': '/bin/bash', 18:12:20 INFO - 'SHLVL': '1', 18:12:20 INFO - 'TERM': 'linux', 18:12:20 INFO - 'TMOUT': '86400', 18:12:20 INFO - 'USER': 'cltbld', 18:12:20 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:20 INFO - '_': '/tools/buildbot/bin/python'} 18:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:20 INFO - Running setup.py (path:/tmp/pip-ZHYkOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:20 INFO - Running setup.py (path:/tmp/pip-MIAMLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:20 INFO - Running setup.py (path:/tmp/pip-0CnBh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:20 INFO - Running setup.py (path:/tmp/pip-kPr58A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:20 INFO - Running setup.py (path:/tmp/pip-O7Do9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:20 INFO - Running setup.py (path:/tmp/pip-2EgTvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:20 INFO - Running setup.py (path:/tmp/pip-IA7wOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:20 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)) 18:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:20 INFO - Running setup.py (path:/tmp/pip-ZeW0aL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:21 INFO - Running setup.py (path:/tmp/pip-ET78BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:21 INFO - Running setup.py (path:/tmp/pip-ORkkIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:21 INFO - Running setup.py (path:/tmp/pip-8o8Mph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:21 INFO - Running setup.py (path:/tmp/pip-hr91kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:21 INFO - Running setup.py (path:/tmp/pip-Xhcxeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:21 INFO - Running setup.py (path:/tmp/pip-nAtqfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:21 INFO - Running setup.py (path:/tmp/pip-5BJdFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:21 INFO - Running setup.py (path:/tmp/pip-_5nbuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:21 INFO - Running setup.py (path:/tmp/pip-b1uTay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:21 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)) 18:12:21 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)) 18:12:21 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)) 18:12:21 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)) 18:12:21 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)) 18:12:21 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)) 18:12:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:21 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 18:12:21 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 18:12:21 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 18:12:21 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 18:12:21 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 18:12:21 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 18:12:23 INFO - Downloading blessings-1.6.tar.gz 18:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:23 INFO - Installing collected packages: blessings 18:12:23 INFO - Running setup.py install for blessings 18:12:23 INFO - Successfully installed blessings 18:12:23 INFO - Cleaning up... 18:12:23 INFO - Return code: 0 18:12:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:24 INFO - Reading from file tmpfile_stdout 18:12:24 INFO - Current package versions: 18:12:24 INFO - argparse == 1.2.1 18:12:24 INFO - blessings == 1.6 18:12:24 INFO - blobuploader == 1.2.4 18:12:24 INFO - docopt == 0.6.1 18:12:24 INFO - functools32 == 3.2.3-2 18:12:24 INFO - jsonschema == 2.5.1 18:12:24 INFO - manifestparser == 1.1 18:12:24 INFO - mozInstall == 1.12 18:12:24 INFO - mozcrash == 0.17 18:12:24 INFO - mozdebug == 0.1 18:12:24 INFO - mozdevice == 0.48 18:12:24 INFO - mozfile == 1.2 18:12:24 INFO - mozhttpd == 0.7 18:12:24 INFO - mozinfo == 0.9 18:12:24 INFO - mozleak == 0.1 18:12:24 INFO - mozlog == 3.3 18:12:24 INFO - moznetwork == 0.27 18:12:24 INFO - mozprocess == 0.23 18:12:24 INFO - mozprofile == 0.28 18:12:24 INFO - mozrunner == 6.12 18:12:24 INFO - mozscreenshot == 0.1 18:12:24 INFO - mozsystemmonitor == 0.3 18:12:24 INFO - moztest == 0.7 18:12:24 INFO - mozversion == 1.4 18:12:24 INFO - psutil == 3.1.1 18:12:24 INFO - requests == 1.2.3 18:12:24 INFO - wsgiref == 0.1.2 18:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:24 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')]} 18:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:24 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')]} 18:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:24 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:24 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 18:12:24 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 18:12:24 INFO - Using env: {'DISPLAY': ':0', 18:12:24 INFO - 'HOME': '/home/cltbld', 18:12:24 INFO - 'LANG': 'en_US.UTF-8', 18:12:24 INFO - 'LANGUAGE': 'en_US:en', 18:12:24 INFO - 'LOGNAME': 'cltbld', 18:12:24 INFO - 'MAIL': '/var/mail/cltbld', 18:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:24 INFO - 'MOZ_NO_REMOTE': '1', 18:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:24 INFO - 'NO_EM_RESTART': '1', 18:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:24 INFO - 'PWD': '/builds/slave/test', 18:12:24 INFO - 'SHELL': '/bin/bash', 18:12:24 INFO - 'SHLVL': '1', 18:12:24 INFO - 'TERM': 'linux', 18:12:24 INFO - 'TMOUT': '86400', 18:12:24 INFO - 'USER': 'cltbld', 18:12:24 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:24 INFO - '_': '/tools/buildbot/bin/python'} 18:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 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)) 18:12:24 INFO - Cleaning up... 18:12:24 INFO - Return code: 0 18:12:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:24 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')]} 18:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:24 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')]} 18:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:24 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 0x25bd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, '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': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', '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 18:12:24 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 18:12:24 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 18:12:24 INFO - Using env: {'DISPLAY': ':0', 18:12:24 INFO - 'HOME': '/home/cltbld', 18:12:24 INFO - 'LANG': 'en_US.UTF-8', 18:12:24 INFO - 'LANGUAGE': 'en_US:en', 18:12:24 INFO - 'LOGNAME': 'cltbld', 18:12:24 INFO - 'MAIL': '/var/mail/cltbld', 18:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:24 INFO - 'MOZ_NO_REMOTE': '1', 18:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:24 INFO - 'NO_EM_RESTART': '1', 18:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:24 INFO - 'PWD': '/builds/slave/test', 18:12:24 INFO - 'SHELL': '/bin/bash', 18:12:24 INFO - 'SHLVL': '1', 18:12:24 INFO - 'TERM': 'linux', 18:12:24 INFO - 'TMOUT': '86400', 18:12:24 INFO - 'USER': 'cltbld', 18:12:24 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:24 INFO - '_': '/tools/buildbot/bin/python'} 18:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:24 INFO - Cleaning up... 18:12:24 INFO - Return code: 0 18:12:24 INFO - Running post-action listener: _resource_record_post_action 18:12:24 INFO - Running post-action listener: _start_resource_monitoring 18:12:24 INFO - Starting resource monitoring. 18:12:24 INFO - [mozharness: 2016-08-11 01:12:24.474127Z] Finished create-virtualenv step (success) 18:12:24 INFO - [mozharness: 2016-08-11 01:12:24.474601Z] Running install step. 18:12:24 INFO - Running pre-action listener: _resource_record_pre_action 18:12:24 INFO - Running main action method: install 18:12:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:24 INFO - Reading from file tmpfile_stdout 18:12:24 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:24 INFO - Reading from file tmpfile_stdout 18:12:24 INFO - Output received: 18:12:24 INFO - Usage: mozinstall [options] installer 18:12:24 INFO - Options: 18:12:24 INFO - -h, --help show this help message and exit 18:12:24 INFO - -d DEST, --destination=DEST 18:12:24 INFO - Directory to install application into. [default: 18:12:24 INFO - "/builds/slave/test"] 18:12:24 INFO - --app=APP Application being installed. [default: firefox] 18:12:24 INFO - mkdir: /builds/slave/test/build/application 18:12:24 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'] 18:12:24 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 18:12:46 INFO - Reading from file tmpfile_stdout 18:12:46 INFO - Output received: 18:12:46 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:46 INFO - Running post-action listener: _resource_record_post_action 18:12:46 INFO - [mozharness: 2016-08-11 01:12:46.824594Z] Finished install step (success) 18:12:46 INFO - [mozharness: 2016-08-11 01:12:46.824933Z] Running run-tests step. 18:12:46 INFO - Running pre-action listener: _resource_record_pre_action 18:12:46 INFO - Running main action method: run_tests 18:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:46 INFO - Python 2.7.3 18:12:46 INFO - Return code: 0 18:12:46 INFO - grabbing minidump binary from tooltool 18:12: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')]} 18:12:46 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 0x287c3b0>, '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 0x287d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:46 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 18:12:46 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 18:12:46 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 18:12:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:46 INFO - Return code: 0 18:12:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 18:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 18:12:46 INFO - Using env: {'DISPLAY': ':0', 18:12:46 INFO - 'HOME': '/home/cltbld', 18:12:46 INFO - 'LANG': 'en_US.UTF-8', 18:12:46 INFO - 'LANGUAGE': 'en_US:en', 18:12:46 INFO - 'LOGNAME': 'cltbld', 18:12:46 INFO - 'MAIL': '/var/mail/cltbld', 18:12:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:46 INFO - 'MOZ_NO_REMOTE': '1', 18:12:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:46 INFO - 'NO_EM_RESTART': '1', 18:12:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:46 INFO - 'PWD': '/builds/slave/test', 18:12:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:46 INFO - 'SHELL': '/bin/bash', 18:12:46 INFO - 'SHLVL': '1', 18:12:46 INFO - 'TERM': 'linux', 18:12:46 INFO - 'TMOUT': '86400', 18:12:46 INFO - 'USER': 'cltbld', 18:12:46 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325', 18:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:46 INFO - '_': '/tools/buildbot/bin/python'} 18:12:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 18:12:47 INFO - mozversion application_buildid: 20160810165421 18:12:47 INFO - mozversion application_changeset: 0502bd9e025edde29777ba1de4280f9b52af4663 18:12:47 INFO - mozversion application_display_name: Nightly 18:12:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:47 INFO - mozversion application_name: Firefox 18:12:47 INFO - mozversion application_remotingname: firefox 18:12:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:12:47 INFO - mozversion application_vendor: Mozilla 18:12:47 INFO - mozversion application_version: 51.0a1 18:12:47 INFO - mozversion platform_buildid: 20160810165421 18:12:47 INFO - mozversion platform_changeset: 0502bd9e025edde29777ba1de4280f9b52af4663 18:12:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:12:47 INFO - mozversion platform_version: 51.0a1 18:12:47 INFO - using testdate: 1470877967 18:12:47 INFO - actual date: 1470877967 18:12:47 INFO - starting webserver on 'localhost:58442' 18:12:47 INFO - SUITE-START | Running 2 tests 18:12:47 INFO - TEST-START | dromaeo_css 18:12:47 INFO - operating with platform_type : linux_ 18:12:47 INFO - Initialising browser for dromaeo_css test... 18:12:47 INFO - TEST-INFO | started process 3231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3JtPw5/profile http://localhost:58442/getInfo.html) 18:13:02 INFO - PROCESS | 3231 | __metrics Screen width/height:1600/1200 18:13:02 INFO - PROCESS | 3231 | colorDepth:24 18:13:02 INFO - PROCESS | 3231 | Browser inner width/height: 1024/697 18:13:02 INFO - PROCESS | 3231 | __metrics 18:13:07 INFO - PROCESS | 3231 | 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" 18:13:07 INFO - PROCESS | 3231 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:13:07 INFO - PROCESS | 3231 | console.error: PushService: 18:13:07 INFO - PROCESS | 3231 | stateChangeProcessEnqueue: Error transitioning state 18:13:07 INFO - PROCESS | 3231 | UnknownError 18:13:08 INFO - TEST-INFO | 3231: exit 0 18:13:08 INFO - Browser initialized. 18:13:08 INFO - Running cycle 1/1 for dromaeo_css test... 18:13:08 INFO - TEST-INFO | started process 3314 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3JtPw5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:21 INFO - PROCESS | 3314 | RSS: Main: 156745728 18:13:21 INFO - PROCESS | 3314 | 18:14:11 INFO - PROCESS | 3314 | 1470878051956 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] 18:16:26 INFO - PROCESS | 3314 | Cycle 1(1): loaded http://localhost:58442/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58442/tests/dromaeo/cssquery-ext.html) 18:16:27 INFO - PROCESS | 3314 | RSS: Main: 249729024 18:16:27 INFO - PROCESS | 3314 | 18:19:29 INFO - PROCESS | 3314 | Cycle 1(1): loaded http://localhost:58442/tests/dromaeo/cssquery-ext.html (next: http://localhost:58442/tests/dromaeo/cssquery-jquery.html) 18:19:30 INFO - PROCESS | 3314 | RSS: Main: 252899328 18:19:30 INFO - PROCESS | 3314 | 18:22:32 INFO - PROCESS | 3314 | Cycle 1(1): loaded http://localhost:58442/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58442/tests/dromaeo/cssquery-mootools.html) 18:22:33 INFO - PROCESS | 3314 | RSS: Main: 187449344 18:22:33 INFO - PROCESS | 3314 | 18:25:35 INFO - PROCESS | 3314 | Cycle 1(1): loaded http://localhost:58442/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58442/tests/dromaeo/cssquery-prototype.html) 18:25:36 INFO - PROCESS | 3314 | RSS: Main: 187297792 18:25:36 INFO - PROCESS | 3314 | 18:28:39 INFO - PROCESS | 3314 | Cycle 1(1): loaded http://localhost:58442/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58442/tests/dromaeo/cssquery-yui.html) 18:28:39 INFO - PROCESS | 3314 | RSS: Main: 187527168 18:28:39 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | Cycle 1(1): loaded http://localhost:58442/tests/dromaeo/cssquery-yui.html (next: http://localhost:58442/tests/dromaeo/cssquery-dojo.html) 18:31:42 INFO - PROCESS | 3314 | RSS: Main: 277012480 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | __start_tp_report 18:31:42 INFO - PROCESS | 3314 | _x_x_mozilla_page_load 18:31:42 INFO - PROCESS | 3314 | _x_x_mozilla_page_load_details 18:31:42 INFO - PROCESS | 3314 | |i|pagename|runs| 18:31:42 INFO - PROCESS | 3314 | |0;dojo.html;2596.195179494357;2607.7392260773927;2640.6567146270986;2649.7747691446225;2662.334416395901;4620.122176786408;4650.767461626918;4691.9765401172945;4734.171519984002;4734.171519984006;4755.857324280269;4776.47458779534;4811.903761924768;4829.90340193196;4839.5644392004715;4308.159908817782;4336.111097225069;4411.029573493843;4466.3747075409465;4552.9089418211615;4454.621357184643;4479.39528163697;4496.078303947683;4500.617447516965;4520.434945631796;4230.407742915994;4269.359596060584;4270.807813648362;4326.069894972596;4421.182081314935;4399.5380485049245;4401.559844015573;4401.933970990438;4411.669124815651;4497.325401189815;4440.933385999213;4443.800028998703;4445.110977804452;4467.5755803198645;4485.439320084989;4562.634989200856;4569.611583015448;4851.611871050308;4852.805887764485;4907.730074845882;4904.901901961976;4965.751712414401;5012.699238045692;5015.3730783652045;5027.49725027498;4993.176125939216;4994.500549944976;5009.474005229435;5036.219385995177;5063.670861393997;4580.97709511457;4714.717116972958;4717.127331443625;4761.666683332133;4816.109019831342;5284.286621306074;5307.01820163271;5348.197770334481;5359.490848369399;5362.463753624606;5077.543021128116;5078.21287700407;5114.3095682083185;5129.3075434816565;5133.25567792668;4897.0450762101045;5037.042961837239;5080.212567052113;5164.070467315844;5209.661372010807;5081.390233172043;5088.796448142107;5089.440161582223;5118.641695081383;5131.538161565402;5059.494050594984;5078.390593128848;5081.568066714297;5083.389993200765;5127.179651255781;4183.456150700389;4232.132412855419;4336.804843782037;4379.211741886427;4426.690131690751;3819.5034645495907;3839.443280724335;3874.612538746103;3898.9805050974564;3932.3315036443873;4423.756693381831;4426.535213802569;4427.158839820424;4427.402300689366;4475.26158183896;4796.400449943757;4813.302071199727;5204.0892843752945;5207.479252074762;5254.369475662868;4347.913041739211;4411.801468933906;4412.161689279027;4414.735115893057;4481.596656300949;4690.343351930733;4691.390119284471;4693.342931989524;4694.929576056362;4747.7626118693925;4625.098105869323;4842;4875;4899.191633380452;4940.999999999928;5089.414717307557;5164;5180.6891586504935;5186.325777648882;5207.635465517377;4827.25177597473;4832.855014349575;4839.201531747222;4845.370101886732;4887.291342755351;4840.177169881058;5056.367954005749;5057.291979122852;5059.494050594911;5109.6678715883345;5023.799048038038;5072.9746351268;5079.9238011429115;5121.74391280442;5139.922901156411;4955.1823949048;4958.553730164303;4963.6525443218625;4966.205407134841;5018.84943451697;4762;4950.579200767975;4960.454350021422;4965.106280869334;5018.172001619692;4756.7145971241835;4824.445188803333;4829.1790395633525;4889.144399730104;4897.3878265216845;4959.826406075915;4970.403551573834;4972.602191824573;4996.975015124901;5014;4672.696274742131;4732.455767586634;4852.8301509447;4864.562189402971;4873.098476781806 18:31:42 INFO - PROCESS | 3314 | |1;ext.html;531.853740221439;557.6654007595446;566.1649067625253;570.4153242926003;573.1202604002854;28764.568531472018;38394.42408363873;39418.8029059855;39468.407973880414;43638.127237455235;40621;41034.179316413654;41061.794691026575;41083.79458102713;41135.58864411355;7334.963325183393;7643.350315223199;7768.961155194216;7776;7776.4945278557;7335.193128755833;7637.236276372346;7642.732504362349;9444;9608;9735.36720113194;9740.951295243514;9781.804363912717;9790.314677972545;9807.362521436084;9852.310338276324;9859.161971232383;9898.109170174646;9908.10827025564;9918.355306905029;9775.804483910353;9818.803623927553;9844.803103937953;9845.01549845017;9846.064623860722;38700.41949370762;39023.414648780294;39028.609713902784;39051.41422878659;39052.85209313487;3037.726604605574;3124.656287808339;3580.2481478889463;3631;3685.594584595692;27072.59391109135;29215.56176657352;29497.85251073731;29745.10764677063;30374.392512149632;3307.255867429833;3308.9007329780147;3399.201187720884;3413.4026545354714;3488.2500262443696;254733;254833;255119;255338;255350.72324638444;31371.372572548877;32373.19067023343;54483.45516544863;54868;54875;33906.83046584751;34036;34214.82892585571;34260.48609270863;34281.82859085689;11658.125640576991;11672.70818229534;11674.124440666985;11732.002779763816;11732.70668233301;10227.897721022844;10274.948625256826;10290.588376464857;10293.43386113771;10301.433421161759;7263.527870688388;7416.517926334771;7425.1461081975185;7545.283198096172;7632.961835190788;3089.073278016631;3096.6284045914635;3105.7049580289836;3115.8597863096215;3170.2708377073404;801.4710291207891;829.639106988462;893;918.196577994255;986.452518852044;27869.860650696617;28617.856910715313;28682.282942926176;28811.855940720583;29406.558901616492;966.0629189685993;1006.3659894266711;1021.4484178543652;1043.9791204176026;1069.0752499088387;14228.786568201485;14850.40598376053;15034.924825375803;15316.923415382851;15681.215939203223;413335;414015;414798;414822;420159;44006.339904901455;48081.5191848077;49875.501244987085;50277;50434;1137.7212582917239;1145.5818626201376;1174.3130268792852;1187.9821802672966;1191.6008137273936;28547.857260713565;29655.25861853471;29953.55069673913;30479.085627431214;30524.38951220919;42802.57197427986;47452.525474746195;48737;48761.5123848757;53785.46214537804;377350;383165;644143;674974;679065;38191.80904095462;38260.23479530338;38299.42550861795;38564.61435385722;39071.023224419616;42193.78903105588;42420;42618.360724588536;42937.78531107325;42991.57008430001;36842.8157859209;37108.62891371052;37194.8140259297;37380.81309593435;37973.43039854349;36804.44793328156;36806.26387472289;37153.6249132207;37555;38026.429603555414;36210.818945906154;36286;36966.815165923996;37253;37266.62733372632;31515.21211969719;32682.673173268915;32850.50724239186;33511.83244083764;33594.32811343808;25547.744522554538;25620.487590248464;25692.871535642204;25972.740272597788;26237.475250495267 18:31:42 INFO - PROCESS | 3314 | |2;jquery.html;334.81585128179495;354.68787467028994;363.492927366324;364.8011833550715;382.8372941499862;12766.489340426371;16846.831531684697;17364.131793410328;18350.449486515427;18376.081195940216;15863.603409914758;15990.84009159908;16411.67176656469;16561.50315490537;17303.48089557312;4990.900181996358;5127.66670166439;5170.09523333417;5193.16909294513;5195.298634684307;3751.3622684143625;5151.278820965068;5234.555062819665;5846;6164.907526387108;6166.506679465654;6187.07193920911;6193.69031548423;6194.225721784777;6196.132541444202;5951.434613711691;6017.578769486137;6077.787277445313;6077.939220607782;6083.361247069082;5132.332796736438;5323.201519772027;5327.627066105374;5334;5339.6262261641705;20996.265130720352;21479.248226312004;21545.353639390843;21584.676229856566;21687.783122168734;1917.2307768603127;1927.0364817591205;1938.1278424708937;2132.6274148213033;2470.876456177202;13666.93166534161;13762.24307663078;13830;13844.653883652889;14564.271786410636;1748.195829918239;1954.9706754398696;2112.482441801753;2155.461134716321;2185.0713446785053;83931.16068839356;84200;86257.56871215666;86403;86638.56680716557;16657.91671041637;16679.833201668072;16795;17108.74336884948;17604.471865844047;29800.850995744884;29849.403011940074;29858.253543661584;29893;29955.700442995727;8441.198086181803;8984.46093234408;9029.325426823947;9145.588448519833;9195.862062069074;7516.624168791539;7536.284053014955;7575.280348366897;7731.304182623591;7760.456768026297;8205.343572514184;8299.95850020746;8545.188207120314;8577.871331930026;8611.569421528899;3129.4054129715737;3159.731422829085;3204.8557814898386;3315.419801534722;3345.414552453311;792.4096548071724;811.3022800391658;815.4821688228018;916.1113719691888;916.2029034739686;16670.58323541921;16958.745618815727;17162.056086915338;17508;17661.028643424735;835.5321020228691;836.7364280251701;853.7737499562621;857.1300130367682;893.7318804358666;9326.95336523313;9356.859647105299;10089.899101009043;10377.325473844176;10383.688489345332;416311;423250;425845;427649;429639;47255.5274447258;47973.280400794014;48276.5172348279;78813;80097.59498082886;908.9681861134828;923.4966943016072;947.1570302430843;947.4031360243002;956.6412595276771;15745.842541574437;15963.281652325906;15998.280077396312;16312.918435408223;16781.496555103367;38515.807420962716;38937.41593876151;39201.823945281685;39541;40057.599424006556;52542;53119.73440132775;53362.46637533575;53703.46296536984;53934.46065539451;7176.138863336224;7350.448716346403;7402.592857392895;7553.848923021619;7599.848003039798;8836.337274704294;11471.827922581335;11680.883191167979;12355.567555135527;12462;10359.482025898826;10488.370697758159;10568.10171135462;10605.681829545127;10857;10236.18110551169;10331.741706457398;10607;10693.572257109628;10745.080036724994;10143.08712215904;10356.275060745675;10421.583136674448;10858.457077145953;10969.067629251604;7473.290037446434;7514.211007844317;7515.398768098648;7608.961955190189;7829.530228186327;9140.725778226399;10373.896261037293;10449.791004180026;10795.678929030297;10930.945345273223 18:31:42 INFO - PROCESS | 3314 | |3;mootools.html;1323.2722002898404;1366.7198224364006;1374.890008799296;1572.3160425215026;1619.3441656129264;7157.6063316517575;7778.222177782219;7794.181610930856;7795.220477952202;7795.883061754078;6369.936300636992;7420.220876807938;7602.353799926999;7633.1680396704805;7752.496087754306;2107.546877421354;2144.517483566193;2145.1526646974435;2145.270607993282;2148.0548558634227;2115.0693694774327;2141.4860433496005;2181.236567201475;2181.4655409424713;2181.8472706910525;2133.264023911756;2149.4733790221417;2157.2988778647004;2157.4067131538795;2172.565486902618;2110.2086717480943;2113.482196861779;2115.0270875397337;2116.619008578463;2116.8518203725744;1988.5823976964855;2064.6386882295687;2066.349100033485;2067.1524674883376;2070.8446866485074;7603.771886843404;7704.537727736298;7710.421718371144;7712.768616941501;7714.6914123435;4188.183304255671;4259.829606815723;4275.743455392686;4278.315469524892;4283.957160428387;2770.5844123381453;2858.742713155805;2895.5367141257507;2897.3480966782304;2898.4782739106954;941.6798288581915;995.6017592962801;999.6501224571415;1017.7557386227326;1023.3706270643574;9243.722688319362;9384.906150938472;9397.812043759155;9411.200047995928;9434.48110353937;3569.4824250483534;3718.5723641781;3745.0637340664834;3878.4957955466175;3879.1465877506903;8196.13940536247;8218.178182181853;8236.341092712688;8255.25702686761;8280.04779450371;4233.1956928183545;4234.470691163605;4238.4490016298305;4240.045989652365;4259.8083086261195;3456.0495863637702;3906.3163946309282;3907.1794923066473;3910.9217815644097;4058.3912413138264;3537.7877327360443;3539.3542002968743;3669.889903302905;3687.4284485904727;3690.7601005935066;1114.136544178252;1119.4234968990977;1130.2992144870234;1148.55780524497;1173.6831055614937;738.3686947659673;771.8070482379405;818.7789296889806;818.950862948225;845.116364121592;4896.534829191221;4918.114739346952;5076.771545280398;5142.588592912558;5311.044012077786;359.4733715107328;360.39093931256616;362.1200482826704;362.66091204723665;924.191332583989;3538.292341531704;3721.3673675475234;3818.5417749870194;3831.2912111259507;3836.635519625631;8662.826743465095;8991.235744961752;9014.278857691368;9424.481653509123;9508.334416590773;9098.772530686785;9183.632654693738;9188.8621670675;9190;9199.494027828534;6929.930700693029;7082.822929426806;7201.89197162043;7270.636468176675;7274.927250727531;5334.333208348957;5482.479164479368;5528.087865502147;5576.7654760239775;5749.798757043483;8600;8629;8647.481151130953;8686.131386861263;8805.031446540746;8278.71024514139;8521;8623.137686231326;8769.561521924006;8897.688580899638;5648.774049038157;6439.323870993666;6533.216014078151;6567.408933196035;6576.868462630625;7194.6042967635185;7343.48595598303;7385.778426647209;7387.741429049957;7396.9260307396235;6349.14286571307;6402.231732192166;6566.9014964774615;6588.143541339595;6767.492438067263;6548.672566371567;6581.80254592363;6609.074729537965;6626.53614246998;6630.071789949508;6088.6042407243385;6184.6598437086395;6190.288116866618;6192.690365481798;6242.5942313749465;4431.7562534060935;4569.954300457086;4643.141018911512;4652.604528615106;4700.600448961876;4463;4501.639868810422;4503.211937910918;4616.976915115403;4784.354112194809 18:31:42 INFO - PROCESS | 3314 | |4;prototype.html;285.90006402970187;292.11052345607635;294.34508219212256;297.7528651219488;298.7520358102775;4887.560119589236;4910.404118661436;4914.722172235218;4915.975420122903;4928.236123400874;4850.126977144113;4853.757312134397;4875.122477953967;4878.243872199815;4881.803958030278;3637.7089832813376;3741.494898188737;3762.9435558466507;3806.7335286529956;3825.196708691178;3814.561325447581;3824.7705137691687;3826.1391186982846;3829.138443850139;3843.1737176507045;3773.9433908491396;3801.33476641588;3802.8098595070137;3807.8667246646373;3817.351050321452;3621.6016238213906;3688.8340024698955;3809.3714537101346;3820.3314419976673;3830.7893065881367;3338.0319707284857;3359.5464612277274;3365.2596428785628;3370.679785420381;3372.106391806173;4691.554302341272;4728.66899317048;4734.289856521515;4738.7679203407015;4750.809967601292;4197;4259.488861336628;4311.396404503372;4338.566143385668;4344.304911214191;4982.725950072744;4997.450280469146;5012.598992080625;5019.548240658358;5026.924596131061;3642.4900513928073;3658.664587425597;3659.1218107654236;3667.313036003415;3689.1330537323706;5050.3434553507805;5275.762590683428;5296.364436267634;5314;5322.467753224685;3638.43604241343;3639.1448009717697;3643.763155394891;3649.434337677664;3650.123970247175;3682.2509308078447;3692.372296709566;3693.501377314028;3693.8337774800198;3698.5746639136305;4203.159368126387;4217.894552636148;4232.894177645522;4240.978795106004;4242.809073591696;4958.801647934114;5023.070731914608;5038.697678139323;5054.570361519239;5070.543651071422;1291.838520184977;1299.571141523295;1302.3358087375318;1309.109805332383;1310.33173081729;815.9143289954588;842.5534466732641;850.4174640371366;850.9702160424356;857.352698712468;4278.679099067583;4400.657799371159;4479;4516.909661806745;4551.340055691906;5290.127129023668;5290.550303224192;5292;5299.152135658276;5299.311089558333;4467.620252278528;4484.910301793946;4488.4165058542185;4503.482099558527;4514.72911625297;2628.579427291624;2776.9861150694524;2788.27127181149;2789.7907656925813;2791.4417116576765;4941.678790878654;4942.283368911488;4943.898842991714;4957.628177886673;4969.925451118236;5137.460566640449;5395.514403703685;5405.78376864921;5424.593155513352;5434.456554344613;4938.160512712848;4952.177258962429;4960.826371076995;4981.900361992667;5008.624353173599;5303.15149576066;5314.840554783363;5341.225522299323;5379.058664733734;5387.730613469233;5026.246063090565;5232.581393488588;5302.867428314323;5313.601479889101;5315.335583052119;4977.751112444435;4980.402351717817;4987.975060124676;4997.650634328784;5002.174641184164;3707.703383729241;3743.401055831054;3768.773873567485;3776.320262352693;3776.981115094407;5136.688490179359;5335.813246536352;5384.822954456889;5398.1093119634825;5402.35171779389;5105.2342148678;5108.948910510847;5113.923291150561;5140.254663073759;5173.81891633791;5110.412302585101;5110.565601923878;5126.051680439131;5143.948560514347;5159.045576568347;4964.230544265644;4989.55094041538;5040.49595040493;5050.419201791841;5063.772130254221;4883.438404583376;5003.199488081889;5084.745762711924;5108.59131269505;5155.097857874782;5070.670406423571;5106.208537676666;5116.386033675983;5120.718360490208;5131.846044618667 18:31:42 INFO - PROCESS | 3314 | |5;yui.html;621.4686443091157;627.9560430769842;635.5265327331139;667.1960287853137;727.5634619228464;12796.552120675778;14087.859121408783;14099.718005639881;14109.365078571464;14116.647083822909;13457.528986485475;13570.864291357108;13610.727785444285;13624.455021799116;13640.181589104635;610.5298919831749;731.9670614822346;743.6096049573963;747.8803391457368;751.7519218657843;751.0386705017559;759.092884003616;759.6201899050475;760.4562737642613;760.7033257029763;805.375833728859;807.6163822184445;808.9433739638228;809.6882700160422;810.05628442864;643.8647883944377;793.6825269892032;795.3954994204418;795.932345750612;816.9999999999941;551.1017042221154;551.2640624765942;551.5229326632474;590.306389991758;592.0113410604301;13676.589702308947;13693.520726774615;13698.452061917511;13706.725865482635;13717.862821371758;13309.267990260532;13739.450421983209;13801.723965520734;13939.930300348535;13940.37268322928;10483.528241229164;10527.368357898551;10579.418132002736;10590.470476476223;10829.891701083046;3290.489959203082;3300.950485742716;3339.248669049469;3374.7300215982664;3416.5558477398026;55717.72141139268;65134.3486565133;67027.66486167586;67817;68073;10377.273590848568;10622.681319560426;10638.574457021632;10787.460626968621;10867.673969780919;19701.901490492455;20067;20078.19687212495;20082.69875951862;20088.49778755543;6873.553219040847;6937.445004399737;6938.340857618518;7049.753258636026;7224.602646854473;3762.567304759933;3798.506194194737;3826.6364695353896;3864.30442520349;3965.4646622705563;2626.317157539054;2741.7806575474315;2743.0399360223764;2743.4238809850153;2743.465024320238;461.92609182530634;462.75936513013045;463.04612498253704;468.6180762678406;473.3160582957593;453.5691093461225;468.6695879405011;476.8235752771497;529.4229290073838;565.6012511179608;7547;7659.923400765921;7790.364023555117;7858.1356050834365;7926;450.6845208354165;528.4900071431088;537.8843548636984;574.8275517344863;623.5261201486812;3910.3156947534067;4143.686249546216;4213.9578604214175;4215.325547912319;4410.404595379647;102079.29550446765;104108;107128;107689.46155269173;111214.44392777984;54622;71122.1765605783;72544.2745572548;78538.21461785423;89251.55374223216;47940;76307.23692763112;79863.60068199622;80679;84202;7911.613372884806;8435.572121949072;8499.362547808816;8548.316134709332;8743;47879;48346.758266208446;48835.755821220664;49051.509484904695;49586;62583;62652.373476264656;66457.66771166306;67589.32410676026;69705.3029469719;10458.529366178693;10463.110635595755;10536.315139515908;10902.618408355669;11241.550337986717;12301.200421972542;12337;12417.937910310391;12563.623091307276;12673.302968336462;12138.878611214128;12246.203996740183;12338.259704417764;12375.814362784746;12738.235705857678;12356.443960021641;12360.731136751163;12436.626901192978;12505.812412813997;12549.623511294676;11843.348615826213;12556.748865022466;12592.307423091452;12719.618411447671;12733.681657958625;10366.533505992098;10711;10810.35137891729;10846.18653600999;11004.834927475935;10157.492125393848;10721.571137154426;10785.191110666574;10857.40284284372;10871.29336593119 18:31:42 INFO - PROCESS | 3314 | __end_tp_report 18:31:42 INFO - PROCESS | 3314 | __start_cc_report 18:31:42 INFO - PROCESS | 3314 | _x_x_mozilla_cycle_collect,1012 18:31:42 INFO - PROCESS | 3314 | __end_cc_report 18:31:42 INFO - PROCESS | 3314 | __startTimestamp1470879102886__endTimestamp 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | ------- Summary: start ------- 18:31:42 INFO - PROCESS | 3314 | Number of tests: 6 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | [#0] dojo.html Cycles:165 Average:4717.05 Median:4839.20 stddev:491.60 (10.2%) stddev-sans-first:464.11 18:31:42 INFO - PROCESS | 3314 | Values: 2596.2 2607.7 2640.7 2649.8 2662.3 4620.1 4650.8 4692.0 4734.2 4734.2 4755.9 4776.5 4811.9 4829.9 4839.6 4308.2 4336.1 4411.0 4466.4 4552.9 4454.6 4479.4 4496.1 4500.6 4520.4 4230.4 4269.4 4270.8 4326.1 4421.2 4399.5 4401.6 4401.9 4411.7 4497.3 4440.9 4443.8 4445.1 4467.6 4485.4 4562.6 4569.6 4851.6 4852.8 4907.7 4904.9 4965.8 5012.7 5015.4 5027.5 4993.2 4994.5 5009.5 5036.2 5063.7 4581.0 4714.7 4717.1 4761.7 4816.1 5284.3 5307.0 5348.2 5359.5 5362.5 5077.5 5078.2 5114.3 5129.3 5133.3 4897.0 5037.0 5080.2 5164.1 5209.7 5081.4 5088.8 5089.4 5118.6 5131.5 5059.5 5078.4 5081.6 5083.4 5127.2 4183.5 4232.1 4336.8 4379.2 4426.7 3819.5 3839.4 3874.6 3899.0 3932.3 4423.8 4426.5 4427.2 4427.4 4475.3 4796.4 4813.3 5204.1 5207.5 5254.4 4347.9 4411.8 4412.2 4414.7 4481.6 4690.3 4691.4 4693.3 4694.9 4747.8 4625.1 4842.0 4875.0 4899.2 4941.0 5089.4 5164.0 5180.7 5186.3 5207.6 4827.3 4832.9 4839.2 4845.4 4887.3 4840.2 5056.4 5057.3 5059.5 5109.7 5023.8 5073.0 5079.9 5121.7 5139.9 4955.2 4958.6 4963.7 4966.2 5018.8 4762.0 4950.6 4960.5 4965.1 5018.2 4756.7 4824.4 4829.2 4889.1 4897.4 4959.8 4970.4 4972.6 4997.0 5014.0 4672.7 4732.5 4852.8 4864.6 4873.1 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | [#1] ext.html Cycles:180 Average:54416.60 Median:207661.72 stddev:115873.90 (55.8%) stddev-sans-first:116128.32 18:31:42 INFO - PROCESS | 3314 | Values: 531.9 557.7 566.2 570.4 573.1 28764.6 38394.4 39418.8 39468.4 43638.1 40621.0 41034.2 41061.8 41083.8 41135.6 7335.0 7643.4 7769.0 7776.0 7776.5 7335.2 7637.2 7642.7 9444.0 9608.0 9735.4 9741.0 9781.8 9790.3 9807.4 9852.3 9859.2 9898.1 9908.1 9918.4 9775.8 9818.8 9844.8 9845.0 9846.1 38700.4 39023.4 39028.6 39051.4 39052.9 3037.7 3124.7 3580.2 3631.0 3685.6 27072.6 29215.6 29497.9 29745.1 30374.4 3307.3 3308.9 3399.2 3413.4 3488.3 254733.0 254833.0 255119.0 255338.0 255350.7 31371.4 32373.2 54483.5 54868.0 54875.0 33906.8 34036.0 34214.8 34260.5 34281.8 11658.1 11672.7 11674.1 11732.0 11732.7 10227.9 10274.9 10290.6 10293.4 10301.4 7263.5 7416.5 7425.1 7545.3 7633.0 3089.1 3096.6 3105.7 3115.9 3170.3 801.5 829.6 893.0 918.2 986.5 27869.9 28617.9 28682.3 28811.9 29406.6 966.1 1006.4 1021.4 1044.0 1069.1 14228.8 14850.4 15034.9 15316.9 15681.2 413335.0 414015.0 414798.0 414822.0 420159.0 44006.3 48081.5 49875.5 50277.0 50434.0 1137.7 1145.6 1174.3 1188.0 1191.6 28547.9 29655.3 29953.6 30479.1 30524.4 42802.6 47452.5 48737.0 48761.5 53785.5 377350.0 383165.0 644143.0 674974.0 679065.0 38191.8 38260.2 38299.4 38564.6 39071.0 42193.8 42420.0 42618.4 42937.8 42991.6 36842.8 37108.6 37194.8 37380.8 37973.4 36804.4 36806.3 37153.6 37555.0 38026.4 36210.8 36286.0 36966.8 37253.0 37266.6 31515.2 32682.7 32850.5 33511.8 33594.3 25547.7 25620.5 25692.9 25972.7 26237.5 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | [#2] jquery.html Cycles:180 Average:26482.27 Median:39799.30 stddev:69859.98 (175.5%) stddev-sans-first:70028.37 18:31:42 INFO - PROCESS | 3314 | Values: 334.8 354.7 363.5 364.8 382.8 12766.5 16846.8 17364.1 18350.4 18376.1 15863.6 15990.8 16411.7 16561.5 17303.5 4990.9 5127.7 5170.1 5193.2 5195.3 3751.4 5151.3 5234.6 5846.0 6164.9 6166.5 6187.1 6193.7 6194.2 6196.1 5951.4 6017.6 6077.8 6077.9 6083.4 5132.3 5323.2 5327.6 5334.0 5339.6 20996.3 21479.2 21545.4 21584.7 21687.8 1917.2 1927.0 1938.1 2132.6 2470.9 13666.9 13762.2 13830.0 13844.7 14564.3 1748.2 1955.0 2112.5 2155.5 2185.1 83931.2 84200.0 86257.6 86403.0 86638.6 16657.9 16679.8 16795.0 17108.7 17604.5 29800.9 29849.4 29858.3 29893.0 29955.7 8441.2 8984.5 9029.3 9145.6 9195.9 7516.6 7536.3 7575.3 7731.3 7760.5 8205.3 8300.0 8545.2 8577.9 8611.6 3129.4 3159.7 3204.9 3315.4 3345.4 792.4 811.3 815.5 916.1 916.2 16670.6 16958.7 17162.1 17508.0 17661.0 835.5 836.7 853.8 857.1 893.7 9327.0 9356.9 10089.9 10377.3 10383.7 416311.0 423250.0 425845.0 427649.0 429639.0 47255.5 47973.3 48276.5 78813.0 80097.6 909.0 923.5 947.2 947.4 956.6 15745.8 15963.3 15998.3 16312.9 16781.5 38515.8 38937.4 39201.8 39541.0 40057.6 52542.0 53119.7 53362.5 53703.5 53934.5 7176.1 7350.4 7402.6 7553.8 7599.8 8836.3 11471.8 11680.9 12355.6 12462.0 10359.5 10488.4 10568.1 10605.7 10857.0 10236.2 10331.7 10607.0 10693.6 10745.1 10143.1 10356.3 10421.6 10858.5 10969.1 7473.3 7514.2 7515.4 7609.0 7829.5 9140.7 10373.9 10449.8 10795.7 10930.9 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | [#3] mootools.html Cycles:180 Average:4934.99 Median:5322.69 stddev:2721.36 (51.1%) stddev-sans-first:2715.46 18:31:42 INFO - PROCESS | 3314 | Values: 1323.3 1366.7 1374.9 1572.3 1619.3 7157.6 7778.2 7794.2 7795.2 7795.9 6369.9 7420.2 7602.4 7633.2 7752.5 2107.5 2144.5 2145.2 2145.3 2148.1 2115.1 2141.5 2181.2 2181.5 2181.8 2133.3 2149.5 2157.3 2157.4 2172.6 2110.2 2113.5 2115.0 2116.6 2116.9 1988.6 2064.6 2066.3 2067.2 2070.8 7603.8 7704.5 7710.4 7712.8 7714.7 4188.2 4259.8 4275.7 4278.3 4284.0 2770.6 2858.7 2895.5 2897.3 2898.5 941.7 995.6 999.7 1017.8 1023.4 9243.7 9384.9 9397.8 9411.2 9434.5 3569.5 3718.6 3745.1 3878.5 3879.1 8196.1 8218.2 8236.3 8255.3 8280.0 4233.2 4234.5 4238.4 4240.0 4259.8 3456.0 3906.3 3907.2 3910.9 4058.4 3537.8 3539.4 3669.9 3687.4 3690.8 1114.1 1119.4 1130.3 1148.6 1173.7 738.4 771.8 818.8 819.0 845.1 4896.5 4918.1 5076.8 5142.6 5311.0 359.5 360.4 362.1 362.7 924.2 3538.3 3721.4 3818.5 3831.3 3836.6 8662.8 8991.2 9014.3 9424.5 9508.3 9098.8 9183.6 9188.9 9190.0 9199.5 6929.9 7082.8 7201.9 7270.6 7274.9 5334.3 5482.5 5528.1 5576.8 5749.8 8600.0 8629.0 8647.5 8686.1 8805.0 8278.7 8521.0 8623.1 8769.6 8897.7 5648.8 6439.3 6533.2 6567.4 6576.9 7194.6 7343.5 7385.8 7387.7 7396.9 6349.1 6402.2 6566.9 6588.1 6767.5 6548.7 6581.8 6609.1 6626.5 6630.1 6088.6 6184.7 6190.3 6192.7 6242.6 4431.8 4570.0 4643.1 4652.6 4700.6 4463.0 4501.6 4503.2 4617.0 4784.4 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | [#4] prototype.html Cycles:180 Average:4239.24 Median:4885.50 stddev:1242.44 (25.4%) stddev-sans-first:1209.98 18:31:42 INFO - PROCESS | 3314 | Values: 285.9 292.1 294.3 297.8 298.8 4887.6 4910.4 4914.7 4916.0 4928.2 4850.1 4853.8 4875.1 4878.2 4881.8 3637.7 3741.5 3762.9 3806.7 3825.2 3814.6 3824.8 3826.1 3829.1 3843.2 3773.9 3801.3 3802.8 3807.9 3817.4 3621.6 3688.8 3809.4 3820.3 3830.8 3338.0 3359.5 3365.3 3370.7 3372.1 4691.6 4728.7 4734.3 4738.8 4750.8 4197.0 4259.5 4311.4 4338.6 4344.3 4982.7 4997.5 5012.6 5019.5 5026.9 3642.5 3658.7 3659.1 3667.3 3689.1 5050.3 5275.8 5296.4 5314.0 5322.5 3638.4 3639.1 3643.8 3649.4 3650.1 3682.3 3692.4 3693.5 3693.8 3698.6 4203.2 4217.9 4232.9 4241.0 4242.8 4958.8 5023.1 5038.7 5054.6 5070.5 1291.8 1299.6 1302.3 1309.1 1310.3 815.9 842.6 850.4 851.0 857.4 4278.7 4400.7 4479.0 4516.9 4551.3 5290.1 5290.6 5292.0 5299.2 5299.3 4467.6 4484.9 4488.4 4503.5 4514.7 2628.6 2777.0 2788.3 2789.8 2791.4 4941.7 4942.3 4943.9 4957.6 4969.9 5137.5 5395.5 5405.8 5424.6 5434.5 4938.2 4952.2 4960.8 4981.9 5008.6 5303.2 5314.8 5341.2 5379.1 5387.7 5026.2 5232.6 5302.9 5313.6 5315.3 4977.8 4980.4 4988.0 4997.7 5002.2 3707.7 3743.4 3768.8 3776.3 3777.0 5136.7 5335.8 5384.8 5398.1 5402.4 5105.2 5108.9 5113.9 5140.3 5173.8 5110.4 5110.6 5126.1 5143.9 5159.0 4964.2 4989.6 5040.5 5050.4 5063.8 4883.4 5003.2 5084.7 5108.6 5155.1 5070.7 5106.2 5116.4 5120.7 5131.8 18:31:42 INFO - PROCESS | 3314 | 18:31:42 INFO - PROCESS | 3314 | [#5] yui.html Cycles:180 Average:18162.33 Median:4054.58 stddev:25933.91 (639.6%) stddev-sans-first:25973.21 18:31:42 INFO - PROCESS | 3314 | Values: 621.5 628.0 635.5 667.2 727.6 12796.6 14087.9 14099.7 14109.4 14116.6 13457.5 13570.9 13610.7 13624.5 13640.2 610.5 732.0 743.6 747.9 751.8 751.0 759.1 759.6 760.5 760.7 805.4 807.6 808.9 809.7 810.1 643.9 793.7 795.4 795.9 817.0 551.1 551.3 551.5 590.3 592.0 13676.6 13693.5 13698.5 13706.7 13717.9 13309.3 13739.5 13801.7 13939.9 13940.4 10483.5 10527.4 10579.4 10590.5 10829.9 3290.5 3301.0 3339.2 3374.7 3416.6 55717.7 65134.3 67027.7 67817.0 68073.0 10377.3 10622.7 10638.6 10787.5 10867.7 19701.9 20067.0 20078.2 20082.7 20088.5 6873.6 6937.4 6938.3 7049.8 7224.6 3762.6 3798.5 3826.6 3864.3 3965.5 2626.3 2741.8 2743.0 2743.4 2743.5 461.9 462.8 463.0 468.6 473.3 453.6 468.7 476.8 529.4 565.6 7547.0 7659.9 7790.4 7858.1 7926.0 450.7 528.5 537.9 574.8 623.5 3910.3 4143.7 4214.0 4215.3 4410.4 102079.3 104108.0 107128.0 107689.5 111214.4 54622.0 71122.2 72544.3 78538.2 89251.6 47940.0 76307.2 79863.6 80679.0 84202.0 7911.6 8435.6 8499.4 8548.3 8743.0 47879.0 48346.8 48835.8 49051.5 49586.0 62583.0 62652.4 66457.7 67589.3 69705.3 10458.5 10463.1 10536.3 10902.6 11241.6 12301.2 12337.0 12417.9 12563.6 12673.3 12138.9 12246.2 12338.3 12375.8 12738.2 12356.4 12360.7 12436.6 12505.8 12549.6 11843.3 12556.7 12592.3 12719.6 12733.7 10366.5 10711.0 10810.4 10846.2 11004.8 10157.5 10721.6 10785.2 10857.4 10871.3 18:31:42 INFO - PROCESS | 3314 | -------- Summary: end -------- 18:31:42 INFO - PROCESS | 3314 | 18:31:43 INFO - TEST-INFO | 3314: exit 0 18:31:43 INFO - TEST-OK | dromaeo_css | took 1136829ms 18:31:43 INFO - TEST-START | kraken 18:31:43 INFO - operating with platform_type : linux_ 18:31:43 INFO - Initialising browser for kraken test... 18:31:43 INFO - TEST-INFO | started process 3506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKeb4fH/profile http://localhost:58442/getInfo.html) 18:31:50 INFO - PROCESS | 3506 | __metrics Screen width/height:1600/1200 18:31:50 INFO - PROCESS | 3506 | colorDepth:24 18:31:50 INFO - PROCESS | 3506 | Browser inner width/height: 1024/697 18:31:50 INFO - PROCESS | 3506 | __metrics 18:31:52 INFO - PROCESS | 3506 | 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" 18:31:52 INFO - PROCESS | 3506 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:53 INFO - PROCESS | 3506 | console.error: PushService: 18:31:53 INFO - PROCESS | 3506 | stateChangeProcessEnqueue: Error transitioning state 18:31:53 INFO - PROCESS | 3506 | UnknownError 18:31:53 INFO - TEST-INFO | 3506: exit 0 18:31:53 INFO - Browser initialized. 18:31:53 INFO - Running cycle 1/1 for kraken test... 18:31:53 INFO - TEST-INFO | started process 3586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKeb4fH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:05 INFO - PROCESS | 3586 | RSS: Main: 157110272 18:32:05 INFO - PROCESS | 3586 | 18:32:08 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58442/tests/kraken/driver.html?testName=audio-beat-detection) 18:32:09 INFO - PROCESS | 3586 | RSS: Main: 212602880 18:32:09 INFO - PROCESS | 3586 | 18:32:11 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58442/tests/kraken/driver.html?testName=audio-dft) 18:32:12 INFO - PROCESS | 3586 | RSS: Main: 187949056 18:32:12 INFO - PROCESS | 3586 | 18:32:15 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58442/tests/kraken/driver.html?testName=audio-fft) 18:32:16 INFO - PROCESS | 3586 | RSS: Main: 270802944 18:32:16 INFO - PROCESS | 3586 | 18:32:18 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58442/tests/kraken/driver.html?testName=audio-oscillator) 18:32:19 INFO - PROCESS | 3586 | RSS: Main: 180838400 18:32:19 INFO - PROCESS | 3586 | 18:32:21 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58442/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:32:22 INFO - PROCESS | 3586 | RSS: Main: 184164352 18:32:22 INFO - PROCESS | 3586 | 18:32:25 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58442/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:26 INFO - PROCESS | 3586 | RSS: Main: 220258304 18:32:26 INFO - PROCESS | 3586 | 18:32:29 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58442/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:30 INFO - PROCESS | 3586 | RSS: Main: 214331392 18:32:30 INFO - PROCESS | 3586 | 18:32:33 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58442/tests/kraken/driver.html?testName=json-parse-financial) 18:32:33 INFO - PROCESS | 3586 | RSS: Main: 217894912 18:32:33 INFO - PROCESS | 3586 | 18:32:35 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58442/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:35 INFO - PROCESS | 3586 | RSS: Main: 181497856 18:32:35 INFO - PROCESS | 3586 | 18:32:37 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:38 INFO - PROCESS | 3586 | RSS: Main: 188964864 18:32:38 INFO - PROCESS | 3586 | 18:32:40 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:41 INFO - PROCESS | 3586 | RSS: Main: 192299008 18:32:41 INFO - PROCESS | 3586 | 18:32:44 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:44 INFO - PROCESS | 3586 | RSS: Main: 188084224 18:32:44 INFO - PROCESS | 3586 | 18:32:47 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:48 INFO - PROCESS | 3586 | RSS: Main: 191291392 18:32:48 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | Cycle 1(1): loaded http://localhost:58442/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58442/tests/kraken/driver.html?testName=ai-astar) 18:32:50 INFO - PROCESS | 3586 | RSS: Main: 242757632 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | __start_tp_report 18:32:50 INFO - PROCESS | 3586 | _x_x_mozilla_page_load 18:32:50 INFO - PROCESS | 3586 | _x_x_mozilla_page_load_details 18:32:50 INFO - PROCESS | 3586 | |i|pagename|runs| 18:32:50 INFO - PROCESS | 3586 | |0;ai-astar;104;115;115;113;113;116;115;113;114;115 18:32:50 INFO - PROCESS | 3586 | |1;audio-beat-detection;147;122;125;188;123;128;149;127;181;124 18:32:50 INFO - PROCESS | 3586 | |2;audio-dft;161;161;162;161;162;162;163;163;163;163 18:32:50 INFO - PROCESS | 3586 | |3;audio-fft;76;76;75;113;76;79;76;76;142;76 18:32:50 INFO - PROCESS | 3586 | |4;audio-oscillator;87;86;134;85;84;85;127;85;85;133 18:32:50 INFO - PROCESS | 3586 | |5;imaging-gaussian-blur;117;117;117;117;117;118;119;119;119;119 18:32:50 INFO - PROCESS | 3586 | |6;imaging-darkroom;145;147;149;148;146;146;145;147;148;147 18:32:50 INFO - PROCESS | 3586 | |7;imaging-desaturate;95;95;96;95;95;95;95;96;95;95 18:32:50 INFO - PROCESS | 3586 | |8;json-parse-financial;63;63;63;63;64;64;63;64;63;63 18:32:50 INFO - PROCESS | 3586 | |9;json-stringify-tinderbox;66;65;65;65;66;65;65;65;72;65 18:32:50 INFO - PROCESS | 3586 | |10;stanford-crypto-aes;79;78;79;79;83;79;91;80;80;83 18:32:50 INFO - PROCESS | 3586 | |11;stanford-crypto-ccm;133;133;139;131;131;130;141;133;150;134 18:32:50 INFO - PROCESS | 3586 | |12;stanford-crypto-pbkdf2;166;168;167;165;169;174;165;167;170;169 18:32:50 INFO - PROCESS | 3586 | |13;stanford-crypto-sha256-iterative;67;72;68;68;70;71;67;67;70;66 18:32:50 INFO - PROCESS | 3586 | __end_tp_report 18:32:50 INFO - PROCESS | 3586 | __start_cc_report 18:32:50 INFO - PROCESS | 3586 | _x_x_mozilla_cycle_collect,1954 18:32:50 INFO - PROCESS | 3586 | __end_cc_report 18:32:50 INFO - PROCESS | 3586 | __startTimestamp1470879170280__endTimestamp 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | ------- Summary: start ------- 18:32:50 INFO - PROCESS | 3586 | Number of tests: 14 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:3.43 (3.0%) stddev-sans-first:1.12 18:32:50 INFO - PROCESS | 3586 | Values: 104.0 115.0 115.0 113.0 113.0 116.0 115.0 113.0 114.0 115.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:137.50 stddev:24.73 (18.0%) stddev-sans-first:26.14 18:32:50 INFO - PROCESS | 3586 | Values: 147.0 122.0 125.0 188.0 123.0 128.0 149.0 127.0 181.0 124.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:0.88 (0.5%) stddev-sans-first:0.83 18:32:50 INFO - PROCESS | 3586 | Values: 161.0 161.0 162.0 161.0 162.0 162.0 163.0 163.0 163.0 163.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#3] audio-fft Cycles:10 Average:86.50 Median:76.00 stddev:22.69 (29.9%) stddev-sans-first:23.74 18:32:50 INFO - PROCESS | 3586 | Values: 76.0 76.0 75.0 113.0 76.0 79.0 76.0 76.0 142.0 76.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#4] audio-oscillator Cycles:10 Average:99.10 Median:85.00 stddev:22.33 (26.3%) stddev-sans-first:23.25 18:32:50 INFO - PROCESS | 3586 | Values: 87.0 86.0 134.0 85.0 84.0 85.0 127.0 85.0 85.0 133.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.50 stddev:0.99 (0.8%) stddev-sans-first:1.00 18:32:50 INFO - PROCESS | 3586 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 119.0 119.0 119.0 119.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:1.32 (0.9%) stddev-sans-first:1.22 18:32:50 INFO - PROCESS | 3586 | Values: 145.0 147.0 149.0 148.0 146.0 146.0 145.0 147.0 148.0 147.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:32:50 INFO - PROCESS | 3586 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 18:32:50 INFO - PROCESS | 3586 | Values: 63.0 63.0 63.0 63.0 64.0 64.0 63.0 64.0 63.0 63.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:65.00 stddev:2.18 (3.4%) stddev-sans-first:2.32 18:32:50 INFO - PROCESS | 3586 | Values: 66.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 72.0 65.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.00 stddev:3.87 (4.8%) stddev-sans-first:4.03 18:32:50 INFO - PROCESS | 3586 | Values: 79.0 78.0 79.0 79.0 83.0 79.0 91.0 80.0 80.0 83.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:133.50 stddev:6.19 (4.6%) stddev-sans-first:6.50 18:32:50 INFO - PROCESS | 3586 | Values: 133.0 133.0 139.0 131.0 131.0 130.0 141.0 133.0 150.0 134.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.50 stddev:2.71 (1.6%) stddev-sans-first:2.77 18:32:50 INFO - PROCESS | 3586 | Values: 166.0 168.0 167.0 165.0 169.0 174.0 165.0 167.0 170.0 169.0 18:32:50 INFO - PROCESS | 3586 | 18:32:50 INFO - PROCESS | 3586 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:2.01 (2.9%) stddev-sans-first:2.05 18:32:50 INFO - PROCESS | 3586 | Values: 67.0 72.0 68.0 68.0 70.0 71.0 67.0 67.0 70.0 66.0 18:32:50 INFO - PROCESS | 3586 | -------- Summary: end -------- 18:32:50 INFO - PROCESS | 3586 | 18:32:51 INFO - TEST-INFO | 3586: exit 0 18:32:51 INFO - TEST-OK | kraken | took 67861ms 18:32:51 INFO - SUITE-END | took 1204s 18:32:51 INFO - Completed test suite (00:20:04) 18:32:51 INFO - javascript benchmark 18:32:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2596.195179494357, 2607.7392260773927, 2640.6567146270986, 2649.7747691446225, 2662.334416395901, 4620.122176786408, 4650.767461626918, 4691.9765401172945, 4734.171519984002, 4734.171519984006, 4755.857324280269, 4776.47458779534, 4811.903761924768, 4829.90340193196, 4839.5644392004715, 4308.159908817782, 4336.111097225069, 4411.029573493843, 4466.3747075409465, 4552.9089418211615, 4454.621357184643, 4479.39528163697, 4496.078303947683, 4500.617447516965, 4520.434945631796, 4230.407742915994, 4269.359596060584, 4270.807813648362, 4326.069894972596, 4421.182081314935, 4399.5380485049245, 4401.559844015573, 4401.933970990438, 4411.669124815651, 4497.325401189815, 4440.933385999213, 4443.800028998703, 4445.110977804452, 4467.5755803198645, 4485.439320084989, 4562.634989200856, 4569.611583015448, 4851.611871050308, 4852.805887764485, 4907.730074845882, 4904.901901961976, 4965.751712414401, 5012.699238045692, 5015.3730783652045, 5027.49725027498, 4993.176125939216, 4994.500549944976, 5009.474005229435, 5036.219385995177, 5063.670861393997, 4580.97709511457, 4714.717116972958, 4717.127331443625, 4761.666683332133, 4816.109019831342, 5284.286621306074, 5307.01820163271, 5348.197770334481, 5359.490848369399, 5362.463753624606, 5077.543021128116, 5078.21287700407, 5114.3095682083185, 5129.3075434816565, 5133.25567792668, 4897.0450762101045, 5037.042961837239, 5080.212567052113, 5164.070467315844, 5209.661372010807, 5081.390233172043, 5088.796448142107, 5089.440161582223, 5118.641695081383, 5131.538161565402, 5059.494050594984, 5078.390593128848, 5081.568066714297, 5083.389993200765, 5127.179651255781, 4183.456150700389, 4232.132412855419, 4336.804843782037, 4379.211741886427, 4426.690131690751, 3819.5034645495907, 3839.443280724335, 3874.612538746103, 3898.9805050974564, 3932.3315036443873, 4423.756693381831, 4426.535213802569, 4427.158839820424, 4427.402300689366, 4475.26158183896, 4796.400449943757, 4813.302071199727, 5204.0892843752945, 5207.479252074762, 5254.369475662868, 4347.913041739211, 4411.801468933906, 4412.161689279027, 4414.735115893057, 4481.596656300949, 4690.343351930733, 4691.390119284471, 4693.342931989524, 4694.929576056362, 4747.7626118693925, 4625.098105869323, 4842.0, 4875.0, 4899.191633380452, 4940.999999999928, 5089.414717307557, 5164.0, 5180.6891586504935, 5186.325777648882, 5207.635465517377, 4827.25177597473, 4832.855014349575, 4839.201531747222, 4845.370101886732, 4887.291342755351, 4840.177169881058, 5056.367954005749, 5057.291979122852, 5059.494050594911, 5109.6678715883345, 5023.799048038038, 5072.9746351268, 5079.9238011429115, 5121.74391280442, 5139.922901156411, 4955.1823949048, 4958.553730164303, 4963.6525443218625, 4966.205407134841, 5018.84943451697, 4762.0, 4950.579200767975, 4960.454350021422, 4965.106280869334, 5018.172001619692, 4756.7145971241835, 4824.445188803333, 4829.1790395633525, 4889.144399730104, 4897.3878265216845, 4959.826406075915, 4970.403551573834, 4972.602191824573, 4996.975015124901, 5014.0, 4672.696274742131, 4732.455767586634, 4852.8301509447, 4864.562189402971, 4873.098476781806], "value": 4685.840625117735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.853740221439, 557.6654007595446, 566.1649067625253, 570.4153242926003, 573.1202604002854, 28764.568531472018, 38394.42408363873, 39418.8029059855, 39468.407973880414, 43638.127237455235, 40621.0, 41034.179316413654, 41061.794691026575, 41083.79458102713, 41135.58864411355, 7334.963325183393, 7643.350315223199, 7768.961155194216, 7776.0, 7776.4945278557, 7335.193128755833, 7637.236276372346, 7642.732504362349, 9444.0, 9608.0, 9735.36720113194, 9740.951295243514, 9781.804363912717, 9790.314677972545, 9807.362521436084, 9852.310338276324, 9859.161971232383, 9898.109170174646, 9908.10827025564, 9918.355306905029, 9775.804483910353, 9818.803623927553, 9844.803103937953, 9845.01549845017, 9846.064623860722, 38700.41949370762, 39023.414648780294, 39028.609713902784, 39051.41422878659, 39052.85209313487, 3037.726604605574, 3124.656287808339, 3580.2481478889463, 3631.0, 3685.594584595692, 27072.59391109135, 29215.56176657352, 29497.85251073731, 29745.10764677063, 30374.392512149632, 3307.255867429833, 3308.9007329780147, 3399.201187720884, 3413.4026545354714, 3488.2500262443696, 254733.0, 254833.0, 255119.0, 255338.0, 255350.72324638444, 31371.372572548877, 32373.19067023343, 54483.45516544863, 54868.0, 54875.0, 33906.83046584751, 34036.0, 34214.82892585571, 34260.48609270863, 34281.82859085689, 11658.125640576991, 11672.70818229534, 11674.124440666985, 11732.002779763816, 11732.70668233301, 10227.897721022844, 10274.948625256826, 10290.588376464857, 10293.43386113771, 10301.433421161759, 7263.527870688388, 7416.517926334771, 7425.1461081975185, 7545.283198096172, 7632.961835190788, 3089.073278016631, 3096.6284045914635, 3105.7049580289836, 3115.8597863096215, 3170.2708377073404, 801.4710291207891, 829.639106988462, 893.0, 918.196577994255, 986.452518852044, 27869.860650696617, 28617.856910715313, 28682.282942926176, 28811.855940720583, 29406.558901616492, 966.0629189685993, 1006.3659894266711, 1021.4484178543652, 1043.9791204176026, 1069.0752499088387, 14228.786568201485, 14850.40598376053, 15034.924825375803, 15316.923415382851, 15681.215939203223, 413335.0, 414015.0, 414798.0, 414822.0, 420159.0, 44006.339904901455, 48081.5191848077, 49875.501244987085, 50277.0, 50434.0, 1137.7212582917239, 1145.5818626201376, 1174.3130268792852, 1187.9821802672966, 1191.6008137273936, 28547.857260713565, 29655.25861853471, 29953.55069673913, 30479.085627431214, 30524.38951220919, 42802.57197427986, 47452.525474746195, 48737.0, 48761.5123848757, 53785.46214537804, 377350.0, 383165.0, 644143.0, 674974.0, 679065.0, 38191.80904095462, 38260.23479530338, 38299.42550861795, 38564.61435385722, 39071.023224419616, 42193.78903105588, 42420.0, 42618.360724588536, 42937.78531107325, 42991.57008430001, 36842.8157859209, 37108.62891371052, 37194.8140259297, 37380.81309593435, 37973.43039854349, 36804.44793328156, 36806.26387472289, 37153.6249132207, 37555.0, 38026.429603555414, 36210.818945906154, 36286.0, 36966.815165923996, 37253.0, 37266.62733372632, 31515.21211969719, 32682.673173268915, 32850.50724239186, 33511.83244083764, 33594.32811343808, 25547.744522554538, 25620.487590248464, 25692.871535642204, 25972.740272597788, 26237.475250495267], "value": 17336.174747031506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.81585128179495, 354.68787467028994, 363.492927366324, 364.8011833550715, 382.8372941499862, 12766.489340426371, 16846.831531684697, 17364.131793410328, 18350.449486515427, 18376.081195940216, 15863.603409914758, 15990.84009159908, 16411.67176656469, 16561.50315490537, 17303.48089557312, 4990.900181996358, 5127.66670166439, 5170.09523333417, 5193.16909294513, 5195.298634684307, 3751.3622684143625, 5151.278820965068, 5234.555062819665, 5846.0, 6164.907526387108, 6166.506679465654, 6187.07193920911, 6193.69031548423, 6194.225721784777, 6196.132541444202, 5951.434613711691, 6017.578769486137, 6077.787277445313, 6077.939220607782, 6083.361247069082, 5132.332796736438, 5323.201519772027, 5327.627066105374, 5334.0, 5339.6262261641705, 20996.265130720352, 21479.248226312004, 21545.353639390843, 21584.676229856566, 21687.783122168734, 1917.2307768603127, 1927.0364817591205, 1938.1278424708937, 2132.6274148213033, 2470.876456177202, 13666.93166534161, 13762.24307663078, 13830.0, 13844.653883652889, 14564.271786410636, 1748.195829918239, 1954.9706754398696, 2112.482441801753, 2155.461134716321, 2185.0713446785053, 83931.16068839356, 84200.0, 86257.56871215666, 86403.0, 86638.56680716557, 16657.91671041637, 16679.833201668072, 16795.0, 17108.74336884948, 17604.471865844047, 29800.850995744884, 29849.403011940074, 29858.253543661584, 29893.0, 29955.700442995727, 8441.198086181803, 8984.46093234408, 9029.325426823947, 9145.588448519833, 9195.862062069074, 7516.624168791539, 7536.284053014955, 7575.280348366897, 7731.304182623591, 7760.456768026297, 8205.343572514184, 8299.95850020746, 8545.188207120314, 8577.871331930026, 8611.569421528899, 3129.4054129715737, 3159.731422829085, 3204.8557814898386, 3315.419801534722, 3345.414552453311, 792.4096548071724, 811.3022800391658, 815.4821688228018, 916.1113719691888, 916.2029034739686, 16670.58323541921, 16958.745618815727, 17162.056086915338, 17508.0, 17661.028643424735, 835.5321020228691, 836.7364280251701, 853.7737499562621, 857.1300130367682, 893.7318804358666, 9326.95336523313, 9356.859647105299, 10089.899101009043, 10377.325473844176, 10383.688489345332, 416311.0, 423250.0, 425845.0, 427649.0, 429639.0, 47255.5274447258, 47973.280400794014, 48276.5172348279, 78813.0, 80097.59498082886, 908.9681861134828, 923.4966943016072, 947.1570302430843, 947.4031360243002, 956.6412595276771, 15745.842541574437, 15963.281652325906, 15998.280077396312, 16312.918435408223, 16781.496555103367, 38515.807420962716, 38937.41593876151, 39201.823945281685, 39541.0, 40057.599424006556, 52542.0, 53119.73440132775, 53362.46637533575, 53703.46296536984, 53934.46065539451, 7176.138863336224, 7350.448716346403, 7402.592857392895, 7553.848923021619, 7599.848003039798, 8836.337274704294, 11471.827922581335, 11680.883191167979, 12355.567555135527, 12462.0, 10359.482025898826, 10488.370697758159, 10568.10171135462, 10605.681829545127, 10857.0, 10236.18110551169, 10331.741706457398, 10607.0, 10693.572257109628, 10745.080036724994, 10143.08712215904, 10356.275060745675, 10421.583136674448, 10858.457077145953, 10969.067629251604, 7473.290037446434, 7514.211007844317, 7515.398768098648, 7608.961955190189, 7829.530228186327, 9140.725778226399, 10373.896261037293, 10449.791004180026, 10795.678929030297, 10930.945345273223], "value": 9158.304372315586, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.2722002898404, 1366.7198224364006, 1374.890008799296, 1572.3160425215026, 1619.3441656129264, 7157.6063316517575, 7778.222177782219, 7794.181610930856, 7795.220477952202, 7795.883061754078, 6369.936300636992, 7420.220876807938, 7602.353799926999, 7633.1680396704805, 7752.496087754306, 2107.546877421354, 2144.517483566193, 2145.1526646974435, 2145.270607993282, 2148.0548558634227, 2115.0693694774327, 2141.4860433496005, 2181.236567201475, 2181.4655409424713, 2181.8472706910525, 2133.264023911756, 2149.4733790221417, 2157.2988778647004, 2157.4067131538795, 2172.565486902618, 2110.2086717480943, 2113.482196861779, 2115.0270875397337, 2116.619008578463, 2116.8518203725744, 1988.5823976964855, 2064.6386882295687, 2066.349100033485, 2067.1524674883376, 2070.8446866485074, 7603.771886843404, 7704.537727736298, 7710.421718371144, 7712.768616941501, 7714.6914123435, 4188.183304255671, 4259.829606815723, 4275.743455392686, 4278.315469524892, 4283.957160428387, 2770.5844123381453, 2858.742713155805, 2895.5367141257507, 2897.3480966782304, 2898.4782739106954, 941.6798288581915, 995.6017592962801, 999.6501224571415, 1017.7557386227326, 1023.3706270643574, 9243.722688319362, 9384.906150938472, 9397.812043759155, 9411.200047995928, 9434.48110353937, 3569.4824250483534, 3718.5723641781, 3745.0637340664834, 3878.4957955466175, 3879.1465877506903, 8196.13940536247, 8218.178182181853, 8236.341092712688, 8255.25702686761, 8280.04779450371, 4233.1956928183545, 4234.470691163605, 4238.4490016298305, 4240.045989652365, 4259.8083086261195, 3456.0495863637702, 3906.3163946309282, 3907.1794923066473, 3910.9217815644097, 4058.3912413138264, 3537.7877327360443, 3539.3542002968743, 3669.889903302905, 3687.4284485904727, 3690.7601005935066, 1114.136544178252, 1119.4234968990977, 1130.2992144870234, 1148.55780524497, 1173.6831055614937, 738.3686947659673, 771.8070482379405, 818.7789296889806, 818.950862948225, 845.116364121592, 4896.534829191221, 4918.114739346952, 5076.771545280398, 5142.588592912558, 5311.044012077786, 359.4733715107328, 360.39093931256616, 362.1200482826704, 362.66091204723665, 924.191332583989, 3538.292341531704, 3721.3673675475234, 3818.5417749870194, 3831.2912111259507, 3836.635519625631, 8662.826743465095, 8991.235744961752, 9014.278857691368, 9424.481653509123, 9508.334416590773, 9098.772530686785, 9183.632654693738, 9188.8621670675, 9190.0, 9199.494027828534, 6929.930700693029, 7082.822929426806, 7201.89197162043, 7270.636468176675, 7274.927250727531, 5334.333208348957, 5482.479164479368, 5528.087865502147, 5576.7654760239775, 5749.798757043483, 8600.0, 8629.0, 8647.481151130953, 8686.131386861263, 8805.031446540746, 8278.71024514139, 8521.0, 8623.137686231326, 8769.561521924006, 8897.688580899638, 5648.774049038157, 6439.323870993666, 6533.216014078151, 6567.408933196035, 6576.868462630625, 7194.6042967635185, 7343.48595598303, 7385.778426647209, 7387.741429049957, 7396.9260307396235, 6349.14286571307, 6402.231732192166, 6566.9014964774615, 6588.143541339595, 6767.492438067263, 6548.672566371567, 6581.80254592363, 6609.074729537965, 6626.53614246998, 6630.071789949508, 6088.6042407243385, 6184.6598437086395, 6190.288116866618, 6192.690365481798, 6242.5942313749465, 4431.7562534060935, 4569.954300457086, 4643.141018911512, 4652.604528615106, 4700.600448961876, 4463.0, 4501.639868810422, 4503.211937910918, 4616.976915115403, 4784.354112194809], "value": 3950.133841152554, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.90006402970187, 292.11052345607635, 294.34508219212256, 297.7528651219488, 298.7520358102775, 4887.560119589236, 4910.404118661436, 4914.722172235218, 4915.975420122903, 4928.236123400874, 4850.126977144113, 4853.757312134397, 4875.122477953967, 4878.243872199815, 4881.803958030278, 3637.7089832813376, 3741.494898188737, 3762.9435558466507, 3806.7335286529956, 3825.196708691178, 3814.561325447581, 3824.7705137691687, 3826.1391186982846, 3829.138443850139, 3843.1737176507045, 3773.9433908491396, 3801.33476641588, 3802.8098595070137, 3807.8667246646373, 3817.351050321452, 3621.6016238213906, 3688.8340024698955, 3809.3714537101346, 3820.3314419976673, 3830.7893065881367, 3338.0319707284857, 3359.5464612277274, 3365.2596428785628, 3370.679785420381, 3372.106391806173, 4691.554302341272, 4728.66899317048, 4734.289856521515, 4738.7679203407015, 4750.809967601292, 4197.0, 4259.488861336628, 4311.396404503372, 4338.566143385668, 4344.304911214191, 4982.725950072744, 4997.450280469146, 5012.598992080625, 5019.548240658358, 5026.924596131061, 3642.4900513928073, 3658.664587425597, 3659.1218107654236, 3667.313036003415, 3689.1330537323706, 5050.3434553507805, 5275.762590683428, 5296.364436267634, 5314.0, 5322.467753224685, 3638.43604241343, 3639.1448009717697, 3643.763155394891, 3649.434337677664, 3650.123970247175, 3682.2509308078447, 3692.372296709566, 3693.501377314028, 3693.8337774800198, 3698.5746639136305, 4203.159368126387, 4217.894552636148, 4232.894177645522, 4240.978795106004, 4242.809073591696, 4958.801647934114, 5023.070731914608, 5038.697678139323, 5054.570361519239, 5070.543651071422, 1291.838520184977, 1299.571141523295, 1302.3358087375318, 1309.109805332383, 1310.33173081729, 815.9143289954588, 842.5534466732641, 850.4174640371366, 850.9702160424356, 857.352698712468, 4278.679099067583, 4400.657799371159, 4479.0, 4516.909661806745, 4551.340055691906, 5290.127129023668, 5290.550303224192, 5292.0, 5299.152135658276, 5299.311089558333, 4467.620252278528, 4484.910301793946, 4488.4165058542185, 4503.482099558527, 4514.72911625297, 2628.579427291624, 2776.9861150694524, 2788.27127181149, 2789.7907656925813, 2791.4417116576765, 4941.678790878654, 4942.283368911488, 4943.898842991714, 4957.628177886673, 4969.925451118236, 5137.460566640449, 5395.514403703685, 5405.78376864921, 5424.593155513352, 5434.456554344613, 4938.160512712848, 4952.177258962429, 4960.826371076995, 4981.900361992667, 5008.624353173599, 5303.15149576066, 5314.840554783363, 5341.225522299323, 5379.058664733734, 5387.730613469233, 5026.246063090565, 5232.581393488588, 5302.867428314323, 5313.601479889101, 5315.335583052119, 4977.751112444435, 4980.402351717817, 4987.975060124676, 4997.650634328784, 5002.174641184164, 3707.703383729241, 3743.401055831054, 3768.773873567485, 3776.320262352693, 3776.981115094407, 5136.688490179359, 5335.813246536352, 5384.822954456889, 5398.1093119634825, 5402.35171779389, 5105.2342148678, 5108.948910510847, 5113.923291150561, 5140.254663073759, 5173.81891633791, 5110.412302585101, 5110.565601923878, 5126.051680439131, 5143.948560514347, 5159.045576568347, 4964.230544265644, 4989.55094041538, 5040.49595040493, 5050.419201791841, 5063.772130254221, 4883.438404583376, 5003.199488081889, 5084.745762711924, 5108.59131269505, 5155.097857874782, 5070.670406423571, 5106.208537676666, 5116.386033675983, 5120.718360490208, 5131.846044618667], "value": 3840.5166645986606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.4686443091157, 627.9560430769842, 635.5265327331139, 667.1960287853137, 727.5634619228464, 12796.552120675778, 14087.859121408783, 14099.718005639881, 14109.365078571464, 14116.647083822909, 13457.528986485475, 13570.864291357108, 13610.727785444285, 13624.455021799116, 13640.181589104635, 610.5298919831749, 731.9670614822346, 743.6096049573963, 747.8803391457368, 751.7519218657843, 751.0386705017559, 759.092884003616, 759.6201899050475, 760.4562737642613, 760.7033257029763, 805.375833728859, 807.6163822184445, 808.9433739638228, 809.6882700160422, 810.05628442864, 643.8647883944377, 793.6825269892032, 795.3954994204418, 795.932345750612, 816.9999999999941, 551.1017042221154, 551.2640624765942, 551.5229326632474, 590.306389991758, 592.0113410604301, 13676.589702308947, 13693.520726774615, 13698.452061917511, 13706.725865482635, 13717.862821371758, 13309.267990260532, 13739.450421983209, 13801.723965520734, 13939.930300348535, 13940.37268322928, 10483.528241229164, 10527.368357898551, 10579.418132002736, 10590.470476476223, 10829.891701083046, 3290.489959203082, 3300.950485742716, 3339.248669049469, 3374.7300215982664, 3416.5558477398026, 55717.72141139268, 65134.3486565133, 67027.66486167586, 67817.0, 68073.0, 10377.273590848568, 10622.681319560426, 10638.574457021632, 10787.460626968621, 10867.673969780919, 19701.901490492455, 20067.0, 20078.19687212495, 20082.69875951862, 20088.49778755543, 6873.553219040847, 6937.445004399737, 6938.340857618518, 7049.753258636026, 7224.602646854473, 3762.567304759933, 3798.506194194737, 3826.6364695353896, 3864.30442520349, 3965.4646622705563, 2626.317157539054, 2741.7806575474315, 2743.0399360223764, 2743.4238809850153, 2743.465024320238, 461.92609182530634, 462.75936513013045, 463.04612498253704, 468.6180762678406, 473.3160582957593, 453.5691093461225, 468.6695879405011, 476.8235752771497, 529.4229290073838, 565.6012511179608, 7547.0, 7659.923400765921, 7790.364023555117, 7858.1356050834365, 7926.0, 450.6845208354165, 528.4900071431088, 537.8843548636984, 574.8275517344863, 623.5261201486812, 3910.3156947534067, 4143.686249546216, 4213.9578604214175, 4215.325547912319, 4410.404595379647, 102079.29550446765, 104108.0, 107128.0, 107689.46155269173, 111214.44392777984, 54622.0, 71122.1765605783, 72544.2745572548, 78538.21461785423, 89251.55374223216, 47940.0, 76307.23692763112, 79863.60068199622, 80679.0, 84202.0, 7911.613372884806, 8435.572121949072, 8499.362547808816, 8548.316134709332, 8743.0, 47879.0, 48346.758266208446, 48835.755821220664, 49051.509484904695, 49586.0, 62583.0, 62652.373476264656, 66457.66771166306, 67589.32410676026, 69705.3029469719, 10458.529366178693, 10463.110635595755, 10536.315139515908, 10902.618408355669, 11241.550337986717, 12301.200421972542, 12337.0, 12417.937910310391, 12563.623091307276, 12673.302968336462, 12138.878611214128, 12246.203996740183, 12338.259704417764, 12375.814362784746, 12738.235705857678, 12356.443960021641, 12360.731136751163, 12436.626901192978, 12505.812412813997, 12549.623511294676, 11843.348615826213, 12556.748865022466, 12592.307423091452, 12719.618411447671, 12733.681657958625, 10366.533505992098, 10711.0, 10810.35137891729, 10846.18653600999, 11004.834927475935, 10157.492125393848, 10721.571137154426, 10785.191110666574, 10857.40284284372, 10871.29336593119], "value": 6590.38113938067, "unit": "score"}], "name": "dromaeo_css", "value": 6485.149908182345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 113.0, 113.0, 116.0, 115.0, 113.0, 114.0, 115.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 125.0, 188.0, 123.0, 128.0, 149.0, 127.0, 181.0, 124.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 163.0, 163.0, 163.0, 163.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 113.0, 76.0, 79.0, 76.0, 76.0, 142.0, 76.0], "value": 86.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 134.0, 85.0, 84.0, 85.0, 127.0, 85.0, 85.0, 133.0], "value": 99.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 119.0, 119.0, 119.0, 119.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 149.0, 148.0, 146.0, 146.0, 145.0, 147.0, 148.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 64.0, 63.0, 63.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 72.0, 65.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 79.0, 83.0, 79.0, 91.0, 80.0, 80.0, 83.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 133.0, 139.0, 131.0, 131.0, 130.0, 141.0, 133.0, 150.0, 134.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 167.0, 165.0, 169.0, 174.0, 165.0, 167.0, 170.0, 169.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 68.0, 68.0, 70.0, 71.0, 67.0, 67.0, 70.0, 66.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1544.6999999999998, "alertThreshold": 2.0}]} 18:32:52 INFO - Return code: 0 18:32:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:52 INFO - # TBPL SUCCESS # 18:32:52 INFO - Running post-action listener: _resource_record_post_action 18:32:52 INFO - [mozharness: 2016-08-11 01:32:52.077539Z] Finished run-tests step (success) 18:32:52 INFO - Running post-run listener: _resource_record_post_run 18:32:52 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 543301632; Read time: 588; Write time: 3541252 18:32:52 INFO - TinderboxPrint: CPU usage
13.3% 18:32:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 588 18:32:52 INFO - TinderboxPrint: I/O write bytes / time
543,301,632 / 3,541,252 18:32:52 INFO - TinderboxPrint: CPU idle
8,494.7 (86.7%) 18:32:52 INFO - TinderboxPrint: CPU user
1,199.8 (12.2%) 18:32:52 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:52 INFO - install - Wall time: 22s; CPU: 21.0%; Read bytes: 0; Write bytes: 176013312; Read time: 0; Write time: 1265928 18:32:52 INFO - run-tests - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 367288320; Read time: 588; Write time: 2275324 18:32:52 INFO - Running post-run listener: _upload_blobber_files 18:32:52 INFO - Blob upload gear active. 18:32:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:52 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 18:32:52 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:32:52 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:32:53 INFO - (blobuploader) - INFO - Open directory for files ... 18:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:32:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:53 INFO - (blobuploader) - INFO - Done attempting. 18:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:32:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:58 INFO - (blobuploader) - INFO - Done attempting. 18:32:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:32:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:32:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:59 INFO - (blobuploader) - INFO - Done attempting. 18:32:59 INFO - (blobuploader) - INFO - Iteration through files over. 18:32:59 INFO - Return code: 0 18:32:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:32:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:32:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef8406af2d0a0343bfb357b4299c05d2e93894bb291fd0ca287833a5587d1e4a4b8f478c07c66ff398c93badd18f81b6650d389799d10751f4c266f76a2603ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5083bdf0cd01a1af340037451b0ee71249f9ef49e6f036310af4f08777ad38920fe46420460696d8a9ec4bda890ea676ae3a2a75b670a17fadc24cb4032a5644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a3973ea08983542a904c0d920e6108bcab267f49262c1a46027653f2bc79a65e9f2d45aeb65cf9b0917e5d8e0bab288846050be9caf6261fc9e6eb01915e020"} 18:32:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:59 INFO - Contents: 18:32:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef8406af2d0a0343bfb357b4299c05d2e93894bb291fd0ca287833a5587d1e4a4b8f478c07c66ff398c93badd18f81b6650d389799d10751f4c266f76a2603ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5083bdf0cd01a1af340037451b0ee71249f9ef49e6f036310af4f08777ad38920fe46420460696d8a9ec4bda890ea676ae3a2a75b670a17fadc24cb4032a5644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a3973ea08983542a904c0d920e6108bcab267f49262c1a46027653f2bc79a65e9f2d45aeb65cf9b0917e5d8e0bab288846050be9caf6261fc9e6eb01915e020"} 18:32:59 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:59 INFO - Copying logs to upload dir... 18:32:59 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.425502 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-10 18:32:59.396417) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 18:32:59.399535) ========= 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=33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef8406af2d0a0343bfb357b4299c05d2e93894bb291fd0ca287833a5587d1e4a4b8f478c07c66ff398c93badd18f81b6650d389799d10751f4c266f76a2603ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5083bdf0cd01a1af340037451b0ee71249f9ef49e6f036310af4f08777ad38920fe46420460696d8a9ec4bda890ea676ae3a2a75b670a17fadc24cb4032a5644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a3973ea08983542a904c0d920e6108bcab267f49262c1a46027653f2bc79a65e9f2d45aeb65cf9b0917e5d8e0bab288846050be9caf6261fc9e6eb01915e020"} build_url:https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/Z2omna9HSpucq4xKY_68Iw/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/Firefox-Non-PGO/sha512/ef8406af2d0a0343bfb357b4299c05d2e93894bb291fd0ca287833a5587d1e4a4b8f478c07c66ff398c93badd18f81b6650d389799d10751f4c266f76a2603ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5083bdf0cd01a1af340037451b0ee71249f9ef49e6f036310af4f08777ad38920fe46420460696d8a9ec4bda890ea676ae3a2a75b670a17fadc24cb4032a5644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a3973ea08983542a904c0d920e6108bcab267f49262c1a46027653f2bc79a65e9f2d45aeb65cf9b0917e5d8e0bab288846050be9caf6261fc9e6eb01915e020"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 18:32:59.424130) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:32:59.424448) ========= 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=33d5923976266a1c518c4bf7000001ab-1470876568.633610-757785325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:32:59.506601) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 18:32:59.506923) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 18:32:59.507198) ========= ========= Total master_lag: 0.18 =========