builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1472521433.67 results: success (0) buildid: 20160829175429 builduid: 670acda1c49c4a198e0dfb8e17b77267 revision: fecb1018cdcbf931db0892b6fba2e8348286f1d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.674424) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.674810) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.675100) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.716227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.716494) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 18:43:54-- 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% 38.3M=0s 2016-08-29 18:43:54 (38.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098429 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.859702) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.860042) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018202 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:53.923745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 18:43:53.924068) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fecb1018cdcbf931db0892b6fba2e8348286f1d8 --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 fecb1018cdcbf931db0892b6fba2e8348286f1d8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 18:43:54,290 truncating revision to first 12 chars 2016-08-29 18:43:54,290 Setting DEBUG logging. 2016-08-29 18:43:54,290 attempt 1/10 2016-08-29 18:43:54,290 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fecb1018cdcb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 18:43:55,557 unpacking tar archive at: mozilla-central-fecb1018cdcb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.612861 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 18:43:55.550830) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:55.551170) ========= script_repo_revision: fecb1018cdcbf931db0892b6fba2e8348286f1d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:55.551560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:55.551829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:43:55.560486) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-08-29 18:43:55.560812) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:43:56 INFO - MultiFileLogger online at 20160829 18:43:56 in /builds/slave/test 18:43:56 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:43:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:43:56 INFO - {'append_to_log': False, 18:43:56 INFO - 'base_work_dir': '/builds/slave/test', 18:43:56 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:43:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:43:56 INFO - 'branch': 'Firefox-Non-PGO', 18:43:56 INFO - 'buildbot_json_path': 'buildprops.json', 18:43:56 INFO - 'config_files': ('talos/linux_config.py',), 18:43:56 INFO - 'default_actions': ('clobber', 18:43:56 INFO - 'read-buildbot-config', 18:43:56 INFO - 'download-and-extract', 18:43:56 INFO - 'populate-webroot', 18:43:56 INFO - 'create-virtualenv', 18:43:56 INFO - 'install', 18:43:56 INFO - 'run-tests'), 18:43:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:43:56 INFO - 'download_minidump_stackwalk': True, 18:43:56 INFO - 'download_symbols': 'ondemand', 18:43:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:43:56 INFO - 'tooltool.py': '/tools/tooltool.py', 18:43:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:43:56 INFO - '/tools/misc-python/virtualenv.py')}, 18:43:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:43:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:43:56 INFO - 'installer_path': 'installer.exe', 18:43:56 INFO - 'log_level': 'info', 18:43:56 INFO - 'log_name': 'talos', 18:43:56 INFO - 'log_to_console': True, 18:43:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:43:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:43:56 INFO - 'opt_config_files': (), 18:43:56 INFO - 'pip_index': False, 18:43:56 INFO - 'sps_profile': False, 18:43:56 INFO - 'sps_profile_interval': 0, 18:43:56 INFO - 'suite': 'dromaeojs', 18:43:56 INFO - 'system_bits': '32', 18:43:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:43:56 INFO - 'title': 'talos-linux64-ix-012', 18:43:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:43:56 INFO - 'use_talos_json': True, 18:43:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:43:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:43:56 INFO - 'work_dir': 'build'} 18:43:56 INFO - [mozharness: 2016-08-30 01:43:56.015312Z] Running clobber step. 18:43:56 INFO - Running pre-action listener: _resource_record_pre_action 18:43:56 INFO - Running main action method: clobber 18:43:56 INFO - rmtree: /builds/slave/test/build 18:43:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:43:56 INFO - Running post-action listener: _resource_record_post_action 18:43:56 INFO - [mozharness: 2016-08-30 01:43:56.474239Z] Finished clobber step (success) 18:43:56 INFO - [mozharness: 2016-08-30 01:43:56.474342Z] Running read-buildbot-config step. 18:43:56 INFO - Running pre-action listener: _resource_record_pre_action 18:43:56 INFO - Running main action method: read_buildbot_config 18:43:56 INFO - Using buildbot properties: 18:43:56 INFO - { 18:43:56 INFO - "project": "", 18:43:56 INFO - "product": "firefox", 18:43:56 INFO - "script_repo_revision": "production", 18:43:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:43:56 INFO - "repository": "", 18:43:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:43:56 INFO - "buildid": "20160829175429", 18:43:56 INFO - "pgo_build": "False", 18:43:56 INFO - "basedir": "/builds/slave/test", 18:43:56 INFO - "buildnumber": 622, 18:43:56 INFO - "slavename": "talos-linux64-ix-012", 18:43:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:43:56 INFO - "platform": "ubuntu64_hw", 18:43:56 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:43:56 INFO - "branch": "mozilla-central", 18:43:56 INFO - "repo_path": "mozilla-central", 18:43:56 INFO - "revision": "fecb1018cdcbf931db0892b6fba2e8348286f1d8", 18:43:56 INFO - "stage_platform": "linux64", 18:43:56 INFO - "builduid": "670acda1c49c4a198e0dfb8e17b77267", 18:43:56 INFO - "slavebuilddir": "test" 18:43:56 INFO - } 18:43:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:43:56 INFO - Running post-action listener: _resource_record_post_action 18:43:56 INFO - [mozharness: 2016-08-30 01:43:56.476792Z] Finished read-buildbot-config step (success) 18:43:56 INFO - [mozharness: 2016-08-30 01:43:56.476884Z] Running download-and-extract step. 18:43:56 INFO - Running pre-action listener: _resource_record_pre_action 18:43:56 INFO - Running main action method: download_and_extract 18:43:56 INFO - mkdir: /builds/slave/test/build/tests 18:43:56 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:43:56 INFO - trying https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:43:56 INFO - Downloading https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:43:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:43:57 INFO - Downloaded 1361 bytes. 18:43:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:43:57 INFO - Using the following test package requirements: 18:43:57 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:43:57 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:43:57 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:43:57 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'jsshell-linux-x86_64.zip'], 18:43:57 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:43:57 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:43:57 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:43:57 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:43:57 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:43:57 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:43:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:43:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:43:57 INFO - trying https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:43:57 INFO - Downloading https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:43:58 INFO - Downloaded 20315589 bytes. 18:43:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:44:02 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:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:02 INFO - trying https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:44:02 INFO - Downloading https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:44:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:44:03 INFO - Downloaded 20315589 bytes. 18:44:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:44: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:44:08 INFO - trying https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:08 INFO - Downloading https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:44:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:44:09 INFO - Downloaded 14413998 bytes. 18:44:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 18:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:09 INFO - trying https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:44:09 INFO - Downloading https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:44:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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:44:10 INFO - Downloaded 55841142 bytes. 18:44:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:44:10 INFO - mkdir: /builds/slave/test/properties 18:44:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:44:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:44:10 INFO - Contents: 18:44:10 INFO - build_url:https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:44:11 INFO - Running post-action listener: _resource_record_post_action 18:44:11 INFO - Running post-action listener: set_extra_try_arguments 18:44:11 INFO - [mozharness: 2016-08-30 01:44:11.292421Z] Finished download-and-extract step (success) 18:44:11 INFO - [mozharness: 2016-08-30 01:44:11.292649Z] Running populate-webroot step. 18:44:11 INFO - Running pre-action listener: _resource_record_pre_action 18:44:11 INFO - Running main action method: populate_webroot 18:44:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:44:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:44:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:44:11 INFO - '--tppagecycles', 18:44:11 INFO - '7'], 18:44:11 INFO - 'tests': ['tsvgm']}}, 18:44:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:44:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:44:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:44:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:44:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['damp', 'tps']}, 18:44:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'tests': ['damp', 'tps']}, 18:44:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['dromaeo_dom']}, 18:44:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:44:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['basic_compositor_video']}, 18:44:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:44:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['a11yr', 18:44:11 INFO - 'ts_paint', 18:44:11 INFO - 'tpaint', 18:44:11 INFO - 'sessionrestore', 18:44:11 INFO - 'sessionrestore_no_auto_restore', 18:44:11 INFO - 'tabpaint']}, 18:44:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:44:11 INFO - 'ts_paint', 18:44:11 INFO - 'tpaint', 18:44:11 INFO - 'sessionrestore', 18:44:11 INFO - 'sessionrestore_no_auto_restore', 18:44:11 INFO - 'tabpaint']}, 18:44:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:44:11 INFO - 'ts_paint', 18:44:11 INFO - 'tpaint', 18:44:11 INFO - 'sessionrestore', 18:44:11 INFO - 'sessionrestore_no_auto_restore', 18:44:11 INFO - 'tabpaint']}, 18:44:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:44:11 INFO - 'ts_paint', 18:44:11 INFO - 'tpaint', 18:44:11 INFO - 'sessionrestore', 18:44:11 INFO - 'sessionrestore_no_auto_restore', 18:44:11 INFO - 'tabpaint']}, 18:44:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['a11yr', 18:44:11 INFO - 'ts_paint', 18:44:11 INFO - 'tpaint', 18:44:11 INFO - 'sessionrestore', 18:44:11 INFO - 'sessionrestore_no_auto_restore', 18:44:11 INFO - 'tabpaint']}, 18:44:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['a11yr', 18:44:11 INFO - 'ts_paint', 18:44:11 INFO - 'tpaint', 18:44:11 INFO - 'sessionrestore', 18:44:11 INFO - 'sessionrestore_no_auto_restore', 18:44:11 INFO - 'tabpaint']}, 18:44:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['tsvgx', 18:44:11 INFO - 'tsvgr_opacity', 18:44:11 INFO - 'tart', 18:44:11 INFO - 'tscrollx', 18:44:11 INFO - 'cart']}, 18:44:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:44:11 INFO - 'tsvgr_opacity', 18:44:11 INFO - 'tart', 18:44:11 INFO - 'tscrollx', 18:44:11 INFO - 'cart']}, 18:44:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'talos_options': ['--disable-e10s'], 18:44:11 INFO - 'tests': ['tp5o']}, 18:44:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'tests': ['tp5o']}, 18:44:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'talos_options': ['--disable-e10s', 18:44:11 INFO - '--xperf_path', 18:44:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:11 INFO - 'tests': ['tp5n']}, 18:44:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:11 INFO - 'talos_options': ['--xperf_path', 18:44:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:11 INFO - 'tests': ['tp5n']}}, 18:44:11 INFO - 'talos.zip': {'path': '', 18:44:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:44:11 INFO - Running post-action listener: _resource_record_post_action 18:44:11 INFO - [mozharness: 2016-08-30 01:44:11.311655Z] Finished populate-webroot step (success) 18:44:11 INFO - [mozharness: 2016-08-30 01:44:11.311745Z] Running create-virtualenv step. 18:44:11 INFO - Running pre-action listener: _resource_record_pre_action 18:44:11 INFO - Running main action method: create_virtualenv 18:44:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:44:11 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:44:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:44:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:44:11 INFO - Using real prefix '/usr' 18:44:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:44:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:44:13 INFO - Installing pip.................done. 18:44:13 INFO - Return code: 0 18:44:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:44: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:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44: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', 'pip>=1.5'] in /builds/slave/test/build 18:44: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 pip>=1.5 18:44:13 INFO - Using env: {'DISPLAY': ':0', 18:44:13 INFO - 'HOME': '/home/cltbld', 18:44:13 INFO - 'LANG': 'en_US.UTF-8', 18:44:13 INFO - 'LANGUAGE': 'en_US:en', 18:44:13 INFO - 'LOGNAME': 'cltbld', 18:44:13 INFO - 'MAIL': '/var/mail/cltbld', 18:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:13 INFO - 'MOZ_NO_REMOTE': '1', 18:44:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:13 INFO - 'NO_EM_RESTART': '1', 18:44:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:13 INFO - 'PWD': '/builds/slave/test', 18:44:13 INFO - 'SHELL': '/bin/bash', 18:44:13 INFO - 'SHLVL': '1', 18:44:13 INFO - 'TERM': 'linux', 18:44:13 INFO - 'TMOUT': '86400', 18:44:13 INFO - 'USER': 'cltbld', 18:44:13 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:13 INFO - '_': '/tools/buildbot/bin/python'} 18:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:14 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:44:14 INFO - Cleaning up... 18:44:14 INFO - Return code: 0 18:44:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:14 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:14 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:44:14 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:44:14 INFO - Using env: {'DISPLAY': ':0', 18:44:14 INFO - 'HOME': '/home/cltbld', 18:44:14 INFO - 'LANG': 'en_US.UTF-8', 18:44:14 INFO - 'LANGUAGE': 'en_US:en', 18:44:14 INFO - 'LOGNAME': 'cltbld', 18:44:14 INFO - 'MAIL': '/var/mail/cltbld', 18:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:14 INFO - 'MOZ_NO_REMOTE': '1', 18:44:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:14 INFO - 'NO_EM_RESTART': '1', 18:44:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:14 INFO - 'PWD': '/builds/slave/test', 18:44:14 INFO - 'SHELL': '/bin/bash', 18:44:14 INFO - 'SHLVL': '1', 18:44:14 INFO - 'TERM': 'linux', 18:44:14 INFO - 'TMOUT': '86400', 18:44:14 INFO - 'USER': 'cltbld', 18:44:14 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:14 INFO - '_': '/tools/buildbot/bin/python'} 18:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:14 INFO - Downloading/unpacking psutil>=3.1.1 18:44:14 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:44:14 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:44:14 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:44:14 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:44:14 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:44:14 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:44:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:44:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:16 INFO - Installing collected packages: psutil 18:44:16 INFO - Running setup.py install for psutil 18:44:16 INFO - building 'psutil._psutil_linux' extension 18:44:16 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:44:16 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:44:16 INFO - building 'psutil._psutil_posix' extension 18:44:16 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:44:16 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:44:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:16 INFO - Successfully installed psutil 18:44:16 INFO - Cleaning up... 18:44:16 INFO - Return code: 0 18:44:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:44: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:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:16 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:16 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:44:16 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:44:16 INFO - Using env: {'DISPLAY': ':0', 18:44:16 INFO - 'HOME': '/home/cltbld', 18:44:16 INFO - 'LANG': 'en_US.UTF-8', 18:44:16 INFO - 'LANGUAGE': 'en_US:en', 18:44:16 INFO - 'LOGNAME': 'cltbld', 18:44:16 INFO - 'MAIL': '/var/mail/cltbld', 18:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:16 INFO - 'MOZ_NO_REMOTE': '1', 18:44:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:16 INFO - 'NO_EM_RESTART': '1', 18:44:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:16 INFO - 'PWD': '/builds/slave/test', 18:44:16 INFO - 'SHELL': '/bin/bash', 18:44:16 INFO - 'SHLVL': '1', 18:44:16 INFO - 'TERM': 'linux', 18:44:16 INFO - 'TMOUT': '86400', 18:44:16 INFO - 'USER': 'cltbld', 18:44:16 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:16 INFO - '_': '/tools/buildbot/bin/python'} 18:44:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:44:17 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:44:17 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:44:17 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:44:17 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:44:17 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:44:17 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:44:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:44:19 INFO - Installing collected packages: mozsystemmonitor 18:44:19 INFO - Running setup.py install for mozsystemmonitor 18:44:19 INFO - Successfully installed mozsystemmonitor 18:44:19 INFO - Cleaning up... 18:44:19 INFO - Return code: 0 18:44:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:44:19 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:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:19 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:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:19 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:19 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:44:19 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:44:19 INFO - Using env: {'DISPLAY': ':0', 18:44:19 INFO - 'HOME': '/home/cltbld', 18:44:19 INFO - 'LANG': 'en_US.UTF-8', 18:44:19 INFO - 'LANGUAGE': 'en_US:en', 18:44:19 INFO - 'LOGNAME': 'cltbld', 18:44:19 INFO - 'MAIL': '/var/mail/cltbld', 18:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:19 INFO - 'MOZ_NO_REMOTE': '1', 18:44:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:19 INFO - 'NO_EM_RESTART': '1', 18:44:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:19 INFO - 'PWD': '/builds/slave/test', 18:44:19 INFO - 'SHELL': '/bin/bash', 18:44:19 INFO - 'SHLVL': '1', 18:44:19 INFO - 'TERM': 'linux', 18:44:19 INFO - 'TMOUT': '86400', 18:44:19 INFO - 'USER': 'cltbld', 18:44:19 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:19 INFO - '_': '/tools/buildbot/bin/python'} 18:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:19 INFO - Downloading/unpacking jsonschema==2.5.1 18:44:19 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:44:19 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:44:19 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:44:19 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:44:19 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:44:19 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:44:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:44:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:44: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:44: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:44: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:44: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:44: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:44: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:44:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:44:21 INFO - warning: no files found matching '*.txt' 18:44:21 INFO - no previously-included directories found matching 'build' 18:44:21 INFO - no previously-included directories found matching 'dist' 18:44:21 INFO - no previously-included directories found matching '.git*' 18:44:21 INFO - Installing collected packages: jsonschema, functools32 18:44:21 INFO - Running setup.py install for functools32 18:44:21 INFO - warning: no files found matching '*.txt' 18:44:21 INFO - no previously-included directories found matching 'build' 18:44:21 INFO - no previously-included directories found matching 'dist' 18:44:21 INFO - no previously-included directories found matching '.git*' 18:44:21 INFO - Successfully installed jsonschema functools32 18:44:21 INFO - Cleaning up... 18:44:21 INFO - Return code: 0 18:44:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:44:21 INFO - Using env: {'DISPLAY': ':0', 18:44:21 INFO - 'HOME': '/home/cltbld', 18:44:21 INFO - 'LANG': 'en_US.UTF-8', 18:44:21 INFO - 'LANGUAGE': 'en_US:en', 18:44:21 INFO - 'LOGNAME': 'cltbld', 18:44:21 INFO - 'MAIL': '/var/mail/cltbld', 18:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:21 INFO - 'MOZ_NO_REMOTE': '1', 18:44:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:21 INFO - 'NO_EM_RESTART': '1', 18:44:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:21 INFO - 'PWD': '/builds/slave/test', 18:44:21 INFO - 'SHELL': '/bin/bash', 18:44:21 INFO - 'SHLVL': '1', 18:44:21 INFO - 'TERM': 'linux', 18:44:21 INFO - 'TMOUT': '86400', 18:44:21 INFO - 'USER': 'cltbld', 18:44:21 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:21 INFO - '_': '/tools/buildbot/bin/python'} 18:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:44:22 INFO - Cleaning up... 18:44:22 INFO - Return code: 0 18:44:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:44:22 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:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:22 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:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:22 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:22 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:44:22 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:44:22 INFO - Using env: {'DISPLAY': ':0', 18:44:22 INFO - 'HOME': '/home/cltbld', 18:44:22 INFO - 'LANG': 'en_US.UTF-8', 18:44:22 INFO - 'LANGUAGE': 'en_US:en', 18:44:22 INFO - 'LOGNAME': 'cltbld', 18:44:22 INFO - 'MAIL': '/var/mail/cltbld', 18:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:22 INFO - 'MOZ_NO_REMOTE': '1', 18:44:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:22 INFO - 'NO_EM_RESTART': '1', 18:44:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:22 INFO - 'PWD': '/builds/slave/test', 18:44:22 INFO - 'SHELL': '/bin/bash', 18:44:22 INFO - 'SHLVL': '1', 18:44:22 INFO - 'TERM': 'linux', 18:44:22 INFO - 'TMOUT': '86400', 18:44:22 INFO - 'USER': 'cltbld', 18:44:22 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:22 INFO - '_': '/tools/buildbot/bin/python'} 18:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:22 INFO - Downloading/unpacking blobuploader==1.2.4 18:44:22 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:44:22 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:44:22 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:44:22 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:44:22 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:44:22 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:44:24 INFO - Downloading blobuploader-1.2.4.tar.gz 18:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:44:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:44:24 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:44:24 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:44:24 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:44:24 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:44:24 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:44:24 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:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:44:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:44:24 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:44:24 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:44:24 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:44:24 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:44:24 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:44:24 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:44:24 INFO - Downloading docopt-0.6.1.tar.gz 18:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:44:25 INFO - Installing collected packages: blobuploader, requests, docopt 18:44:25 INFO - Running setup.py install for blobuploader 18:44:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:44:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:44:25 INFO - Running setup.py install for requests 18:44:25 INFO - Running setup.py install for docopt 18:44:25 INFO - Successfully installed blobuploader requests docopt 18:44:25 INFO - Cleaning up... 18:44:25 INFO - Return code: 0 18:44:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:25 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:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:25 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:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:25 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:25 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:44:25 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:44:25 INFO - Using env: {'DISPLAY': ':0', 18:44:25 INFO - 'HOME': '/home/cltbld', 18:44:25 INFO - 'LANG': 'en_US.UTF-8', 18:44:25 INFO - 'LANGUAGE': 'en_US:en', 18:44:25 INFO - 'LOGNAME': 'cltbld', 18:44:25 INFO - 'MAIL': '/var/mail/cltbld', 18:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:25 INFO - 'MOZ_NO_REMOTE': '1', 18:44:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:25 INFO - 'NO_EM_RESTART': '1', 18:44:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:25 INFO - 'PWD': '/builds/slave/test', 18:44:25 INFO - 'SHELL': '/bin/bash', 18:44:25 INFO - 'SHLVL': '1', 18:44:25 INFO - 'TERM': 'linux', 18:44:25 INFO - 'TMOUT': '86400', 18:44:25 INFO - 'USER': 'cltbld', 18:44:25 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:25 INFO - '_': '/tools/buildbot/bin/python'} 18:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:25 INFO - Running setup.py (path:/tmp/pip-J7YfJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:25 INFO - Running setup.py (path:/tmp/pip-4r6lZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:25 INFO - Running setup.py (path:/tmp/pip-9Wz8MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:26 INFO - Running setup.py (path:/tmp/pip-eFYxmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:26 INFO - Running setup.py (path:/tmp/pip-z4rhYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:26 INFO - Running setup.py (path:/tmp/pip-JMCXmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:26 INFO - Running setup.py (path:/tmp/pip-dq6dum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:26 INFO - Running setup.py (path:/tmp/pip-rgN8Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:26 INFO - Running setup.py (path:/tmp/pip-MjkTvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:26 INFO - Running setup.py (path:/tmp/pip-nVQLM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:26 INFO - Running setup.py (path:/tmp/pip-8ha85x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:26 INFO - Running setup.py (path:/tmp/pip-gMjDUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:26 INFO - Running setup.py (path:/tmp/pip-wbjIiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:26 INFO - Running setup.py (path:/tmp/pip-j8CzuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:26 INFO - Running setup.py (path:/tmp/pip-WqQzwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:26 INFO - Running setup.py (path:/tmp/pip-MvCTsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:26 INFO - Running setup.py (path:/tmp/pip-AS1Qkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:44:27 INFO - Running setup.py install for manifestparser 18:44:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozcrash 18:44:27 INFO - Running setup.py install for mozdebug 18:44:27 INFO - Running setup.py install for mozdevice 18:44:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozfile 18:44:27 INFO - Running setup.py install for mozhttpd 18:44:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozinfo 18:44:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozInstall 18:44:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozleak 18:44:27 INFO - Running setup.py install for mozlog 18:44:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Running setup.py install for moznetwork 18:44:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Running setup.py install for mozprocess 18:44:28 INFO - Running setup.py install for mozprofile 18:44:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Running setup.py install for mozrunner 18:44:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Running setup.py install for mozscreenshot 18:44:28 INFO - Running setup.py install for moztest 18:44:28 INFO - Running setup.py install for mozversion 18:44:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:44:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:44:28 INFO - Cleaning up... 18:44:28 INFO - Return code: 0 18:44:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:28 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:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:28 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:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:28 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:28 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:44:28 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:44:28 INFO - Using env: {'DISPLAY': ':0', 18:44:28 INFO - 'HOME': '/home/cltbld', 18:44:28 INFO - 'LANG': 'en_US.UTF-8', 18:44:28 INFO - 'LANGUAGE': 'en_US:en', 18:44:28 INFO - 'LOGNAME': 'cltbld', 18:44:28 INFO - 'MAIL': '/var/mail/cltbld', 18:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:28 INFO - 'MOZ_NO_REMOTE': '1', 18:44:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:28 INFO - 'NO_EM_RESTART': '1', 18:44:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:28 INFO - 'PWD': '/builds/slave/test', 18:44:28 INFO - 'SHELL': '/bin/bash', 18:44:28 INFO - 'SHLVL': '1', 18:44:28 INFO - 'TERM': 'linux', 18:44:28 INFO - 'TMOUT': '86400', 18:44:28 INFO - 'USER': 'cltbld', 18:44:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:28 INFO - '_': '/tools/buildbot/bin/python'} 18:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:29 INFO - Running setup.py (path:/tmp/pip-dOdcA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:29 INFO - Running setup.py (path:/tmp/pip-cXULzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:29 INFO - Running setup.py (path:/tmp/pip-XuTIly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:29 INFO - Running setup.py (path:/tmp/pip-F4pkR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:29 INFO - Running setup.py (path:/tmp/pip-t2c5EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:29 INFO - Running setup.py (path:/tmp/pip-wNmNYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:29 INFO - Running setup.py (path:/tmp/pip-hTJXf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:29 INFO - Running setup.py (path:/tmp/pip-wOSoWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:29 INFO - Running setup.py (path:/tmp/pip-HvZaTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:29 INFO - Running setup.py (path:/tmp/pip-58ntJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:29 INFO - Running setup.py (path:/tmp/pip-9GgWv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:29 INFO - Running setup.py (path:/tmp/pip-BTynRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:29 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:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:29 INFO - Running setup.py (path:/tmp/pip-xNeTnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:30 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:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:30 INFO - Running setup.py (path:/tmp/pip-mM9bBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:30 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:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:30 INFO - Running setup.py (path:/tmp/pip-EJ6ZCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:30 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:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:30 INFO - Running setup.py (path:/tmp/pip-wmnnFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:30 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:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:30 INFO - Running setup.py (path:/tmp/pip-mjgr_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:30 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:44:30 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:44:30 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:44:30 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:44:30 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:44:30 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:44:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:44:30 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:44:30 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:44:30 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:44:30 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:44:30 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:44:30 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:44:32 INFO - Downloading blessings-1.6.tar.gz 18:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:44:32 INFO - Installing collected packages: blessings 18:44:32 INFO - Running setup.py install for blessings 18:44:32 INFO - Successfully installed blessings 18:44:32 INFO - Cleaning up... 18:44:32 INFO - Return code: 0 18:44:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:44:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:44:32 INFO - Reading from file tmpfile_stdout 18:44:32 INFO - Current package versions: 18:44:32 INFO - argparse == 1.2.1 18:44:32 INFO - blessings == 1.6 18:44:32 INFO - blobuploader == 1.2.4 18:44:32 INFO - docopt == 0.6.1 18:44:32 INFO - functools32 == 3.2.3-2 18:44:32 INFO - jsonschema == 2.5.1 18:44:32 INFO - manifestparser == 1.1 18:44:32 INFO - mozInstall == 1.12 18:44:32 INFO - mozcrash == 0.17 18:44:32 INFO - mozdebug == 0.1 18:44:32 INFO - mozdevice == 0.48 18:44:32 INFO - mozfile == 1.2 18:44:32 INFO - mozhttpd == 0.7 18:44:32 INFO - mozinfo == 0.9 18:44:32 INFO - mozleak == 0.1 18:44:32 INFO - mozlog == 3.3 18:44:32 INFO - moznetwork == 0.27 18:44:32 INFO - mozprocess == 0.23 18:44:32 INFO - mozprofile == 0.28 18:44:32 INFO - mozrunner == 6.12 18:44:32 INFO - mozscreenshot == 0.1 18:44:32 INFO - mozsystemmonitor == 0.3 18:44:32 INFO - moztest == 0.7 18:44:32 INFO - mozversion == 1.4 18:44:32 INFO - psutil == 3.1.1 18:44:32 INFO - requests == 1.2.3 18:44:32 INFO - wsgiref == 0.1.2 18:44:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:32 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:32 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:44:32 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:44:32 INFO - Using env: {'DISPLAY': ':0', 18:44:32 INFO - 'HOME': '/home/cltbld', 18:44:32 INFO - 'LANG': 'en_US.UTF-8', 18:44:32 INFO - 'LANGUAGE': 'en_US:en', 18:44:32 INFO - 'LOGNAME': 'cltbld', 18:44:32 INFO - 'MAIL': '/var/mail/cltbld', 18:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:32 INFO - 'MOZ_NO_REMOTE': '1', 18:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:32 INFO - 'NO_EM_RESTART': '1', 18:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:32 INFO - 'PWD': '/builds/slave/test', 18:44:32 INFO - 'SHELL': '/bin/bash', 18:44:32 INFO - 'SHLVL': '1', 18:44:32 INFO - 'TERM': 'linux', 18:44:32 INFO - 'TMOUT': '86400', 18:44:32 INFO - 'USER': 'cltbld', 18:44:32 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:32 INFO - '_': '/tools/buildbot/bin/python'} 18:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 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:44:32 INFO - Cleaning up... 18:44:32 INFO - Return code: 0 18:44:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:32 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 0x7f5582baaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', '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:44:32 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:44:32 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:44:32 INFO - Using env: {'DISPLAY': ':0', 18:44:32 INFO - 'HOME': '/home/cltbld', 18:44:32 INFO - 'LANG': 'en_US.UTF-8', 18:44:32 INFO - 'LANGUAGE': 'en_US:en', 18:44:32 INFO - 'LOGNAME': 'cltbld', 18:44:32 INFO - 'MAIL': '/var/mail/cltbld', 18:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:32 INFO - 'MOZ_NO_REMOTE': '1', 18:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:32 INFO - 'NO_EM_RESTART': '1', 18:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:32 INFO - 'PWD': '/builds/slave/test', 18:44:32 INFO - 'SHELL': '/bin/bash', 18:44:32 INFO - 'SHLVL': '1', 18:44:32 INFO - 'TERM': 'linux', 18:44:32 INFO - 'TMOUT': '86400', 18:44:32 INFO - 'USER': 'cltbld', 18:44:32 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:32 INFO - '_': '/tools/buildbot/bin/python'} 18:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:44:33 INFO - Cleaning up... 18:44:33 INFO - Return code: 0 18:44:33 INFO - Running post-action listener: _resource_record_post_action 18:44:33 INFO - Running post-action listener: _start_resource_monitoring 18:44:33 INFO - Starting resource monitoring. 18:44:33 INFO - [mozharness: 2016-08-30 01:44:33.146138Z] Finished create-virtualenv step (success) 18:44:33 INFO - [mozharness: 2016-08-30 01:44:33.146601Z] Running install step. 18:44:33 INFO - Running pre-action listener: _resource_record_pre_action 18:44:33 INFO - Running main action method: install 18:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:44:33 INFO - Reading from file tmpfile_stdout 18:44:33 INFO - Detecting whether we're running mozinstall >=1.0... 18:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:44:33 INFO - Reading from file tmpfile_stdout 18:44:33 INFO - Output received: 18:44:33 INFO - Usage: mozinstall [options] installer 18:44:33 INFO - Options: 18:44:33 INFO - -h, --help show this help message and exit 18:44:33 INFO - -d DEST, --destination=DEST 18:44:33 INFO - Directory to install application into. [default: 18:44:33 INFO - "/builds/slave/test"] 18:44:33 INFO - --app=APP Application being installed. [default: firefox] 18:44:33 INFO - mkdir: /builds/slave/test/build/application 18:44:33 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:44:33 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:44:49 INFO - Reading from file tmpfile_stdout 18:44:49 INFO - Output received: 18:44:49 INFO - /builds/slave/test/build/application/firefox/firefox 18:44:49 INFO - Running post-action listener: _resource_record_post_action 18:44:49 INFO - [mozharness: 2016-08-30 01:44:49.589776Z] Finished install step (success) 18:44:49 INFO - [mozharness: 2016-08-30 01:44:49.590059Z] Running run-tests step. 18:44:49 INFO - Running pre-action listener: _resource_record_pre_action 18:44:49 INFO - Running main action method: run_tests 18:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:44:49 INFO - Python 2.7.3 18:44:49 INFO - Return code: 0 18:44:49 INFO - grabbing minidump binary from tooltool 18:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:49 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 0x1392090>, '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 0x13959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1395e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:44:49 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:44:49 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:44:49 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:44:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:44:49 INFO - Return code: 0 18:44:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:44:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:44:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:44:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:44:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:44:49 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/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:44:49 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/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:44:49 INFO - Using env: {'DISPLAY': ':0', 18:44:49 INFO - 'HOME': '/home/cltbld', 18:44:49 INFO - 'LANG': 'en_US.UTF-8', 18:44:49 INFO - 'LANGUAGE': 'en_US:en', 18:44:49 INFO - 'LOGNAME': 'cltbld', 18:44:49 INFO - 'MAIL': '/var/mail/cltbld', 18:44:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:44:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:49 INFO - 'MOZ_NO_REMOTE': '1', 18:44:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:49 INFO - 'NO_EM_RESTART': '1', 18:44:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:49 INFO - 'PWD': '/builds/slave/test', 18:44:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:44:49 INFO - 'SHELL': '/bin/bash', 18:44:49 INFO - 'SHLVL': '1', 18:44:49 INFO - 'TERM': 'linux', 18:44:49 INFO - 'TMOUT': '86400', 18:44:49 INFO - 'USER': 'cltbld', 18:44:49 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340', 18:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:49 INFO - '_': '/tools/buildbot/bin/python'} 18:44:49 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/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:44:49 INFO - mozversion application_buildid: 20160829175429 18:44:49 INFO - mozversion application_changeset: fecb1018cdcbf931db0892b6fba2e8348286f1d8 18:44:49 INFO - mozversion application_display_name: Nightly 18:44:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:44:49 INFO - mozversion application_name: Firefox 18:44:49 INFO - mozversion application_remotingname: firefox 18:44:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:44:49 INFO - mozversion application_vendor: Mozilla 18:44:49 INFO - mozversion application_version: 51.0a1 18:44:49 INFO - mozversion platform_buildid: 20160829175429 18:44:49 INFO - mozversion platform_changeset: fecb1018cdcbf931db0892b6fba2e8348286f1d8 18:44:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:44:49 INFO - mozversion platform_version: 51.0a1 18:44:49 INFO - using testdate: 1472521489 18:44:49 INFO - actual date: 1472521489 18:44:49 INFO - starting webserver on 'localhost:44124' 18:44:49 INFO - SUITE-START | Running 2 tests 18:44:49 INFO - TEST-START | dromaeo_css 18:44:49 INFO - operating with platform_type : linux_ 18:44:49 INFO - Initialising browser for dromaeo_css test... 18:44:49 INFO - TEST-INFO | started process 4233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvGwX_I/profile http://localhost:44124/getInfo.html) 18:44:54 INFO - PROCESS | 4233 | __metrics Screen width/height:1600/1200 18:44:54 INFO - PROCESS | 4233 | colorDepth:24 18:44:54 INFO - PROCESS | 4233 | Browser inner width/height: 1024/697 18:44:54 INFO - PROCESS | 4233 | __metrics 18:44:54 INFO - PROCESS | 4233 | 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:44:54 INFO - PROCESS | 4233 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:44:55 INFO - PROCESS | 4233 | console.error: PushService: 18:44:55 INFO - PROCESS | 4233 | stateChangeProcessEnqueue: Error transitioning state 18:44:55 INFO - PROCESS | 4233 | UnknownError 18:44:55 INFO - TEST-INFO | 4233: exit 0 18:44:55 INFO - Browser initialized. 18:44:55 INFO - Running cycle 1/1 for dromaeo_css test... 18:44:55 INFO - TEST-INFO | started process 4315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvGwX_I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:07 INFO - PROCESS | 4315 | RSS: Main: 181981184 18:45:07 INFO - PROCESS | 4315 | 18:45:58 INFO - PROCESS | 4315 | 1472521558996 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:48:12 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:44124/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44124/tests/dromaeo/cssquery-ext.html) 18:48:13 INFO - PROCESS | 4315 | RSS: Main: 280543232 18:48:13 INFO - PROCESS | 4315 | 18:51:16 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:44124/tests/dromaeo/cssquery-ext.html (next: http://localhost:44124/tests/dromaeo/cssquery-jquery.html) 18:51:16 INFO - PROCESS | 4315 | RSS: Main: 269647872 18:51:16 INFO - PROCESS | 4315 | 18:54:18 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:44124/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44124/tests/dromaeo/cssquery-mootools.html) 18:54:19 INFO - PROCESS | 4315 | RSS: Main: 217559040 18:54:19 INFO - PROCESS | 4315 | 18:57:21 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:44124/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44124/tests/dromaeo/cssquery-prototype.html) 18:57:22 INFO - PROCESS | 4315 | RSS: Main: 218648576 18:57:22 INFO - PROCESS | 4315 | 19:00:25 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:44124/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44124/tests/dromaeo/cssquery-yui.html) 19:00:26 INFO - PROCESS | 4315 | RSS: Main: 218730496 19:00:26 INFO - PROCESS | 4315 | 19:03:28 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:44124/tests/dromaeo/cssquery-yui.html (next: http://localhost:44124/tests/dromaeo/cssquery-dojo.html) 19:03:29 INFO - PROCESS | 4315 | RSS: Main: 295960576 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | __start_tp_report 19:03:29 INFO - PROCESS | 4315 | _x_x_mozilla_page_load 19:03:29 INFO - PROCESS | 4315 | _x_x_mozilla_page_load_details 19:03:29 INFO - PROCESS | 4315 | |i|pagename|runs| 19:03:29 INFO - PROCESS | 4315 | |0;dojo.html;2576.9803609157975;2596.195179494357;2613.6602241708574;2651.4697060587887;2670.5460071787793;4693.8591842244705;4707.90584188316;4756.096341695084;4783.115123702118;4855.951440485598;4729.645276604251;4774.618030557555;4783.354247176639;4794.9451120753465;4851.272309153629;4357.520672725997;4404.801783919731;4449.733016019033;4510.323451482271;4583.289590113538;4479.395281637003;4516.390287311204;4521.140983213196;4555.088982203572;4558.498565157846;4333.674974376934;4367.432233809616;4368.847090351855;4371.125774845043;4427.358033085185;4381.627561657263;4395.494518130424;4500.797464114123;4501.122281155176;4514.255147900592;4422.027154026109;4426.513083560806;4427.734335939822;4435.933460998088;4483.39474170986;4670.789814458358;4818.951810481885;4820.68665536739;4820.831270905536;4823.155947709172;5110.923336149961;5174.197999310113;5179.14544100223;5179.2749015137915;5182.274481572583;5181.611379146579;5186.948130518685;5195.194744814522;5204.245384419276;5227.294315267466;4695.882602934954;4764.714117152913;4871.244957031595;4873.147199240118;4923.040007198598;5238.345206849144;5239.056969745482;5262.842114736564;5326.520613144836;5328.17413300939;5038.168702164176;5048.848534543969;5051.444341122474;5052.5705315048635;5116.769745361467;5021.84934451967;5083.415407228216;5134.5378915897745;5146.614003949719;5154.768035438415;5050.924236136461;5095.0574143783515;5095.974520127375;5152.922706159336;5159.664621799571;5060.493950604984;5074.137396642506;5078.847634570969;5079.415867175248;5138.768755406016;4257.638100761408;4320.395144679747;4373.497047839475;4387.583179597933;4434.401355816988;3742.5883152853166;3771.7171212159196;3784.810759462016;3827.2345530893376;3885.805709714503;4392.1874453226255;4394.494633117232;4401.537838526972;4437.8224871004795;4449.710768800018;5010.223415370623;5113.463086375951;5118.513741194581;5124.7950081996305;5127.282180494733;4379.912401752011;4389.297712366006;4429.712068715523;4482.417285752831;4483.2826747720865;4606.147862645421;4623.930641040387;4630.120277147331;4633.30500424939;4692.5307469253485;4816.084943860656;4829.589484893753;4835.516448355136;4842.6368022397755;4901.5588597026435;5049.394072711299;5083.364579427572;5093;5098.566621837185;5174.637775355689;4802.375691160127;4809.158397280532;4810.254410566367;4860.586850117638;4863.537963893566;5036.697798132123;5043.672161309503;5045.495450454925;5050.368703912011;5056.469070747518;4969.4782047884455;5068;5088.745562721775;5109.616778741535;5168.715720635401;4996.3005179274205;5008.874778130576;5012.3233363495465;5014.724190169576;5041.470645582161;5010.073136469765;5016.297718319511;5018.096742586369;5030.522100400456;5067.341245638044;4800.711957282574;4911.754412279443;4947.628927830499;4962.280469331999;4987.600991920565;5069.138246498031;5092.516210959953;5096.184610462308;5097.796088156432;5102.795888164432;4944.678595891256;4949.8515044547285;4950.306957026091;4951.455339912679;4998.150314446625 19:03:29 INFO - PROCESS | 4315 | |1;ext.html;563.9125935479997;571.1575426246286;572.8052462162864;575.2062154227166;582.2198254339186;28143.437131257364;38571;40362;40365.59634403655;43919.12161756763;39786.8010659947;40151;40185;40357.79821100891;40402;7127.037849890276;7664;7823.295903368696;7823.452358334919;7839.059312882462;10030.542517083324;10386.117180039706;10387.68836934893;10392.584296628163;10393.480325983708;10116.393016419039;10214.795704085915;10216.335938163995;10220.948895255513;10222.948885255564;10028.498575071253;10089.445080520569;10110.494475276208;10114.34256773311;10115.797684046278;9946.254030947708;10008.849867251996;10014.499275036293;10015.89984100157;10019.849702254473;38304.42543361852;38595.61404385948;38605.227895442215;38617.42073868894;38654.80672596619;2552.8723563821736;2561.5261176682375;2576.780973617245;2590.7538783815694;2636.275024368295;28639.856800716072;29169.416611667646;29220.834099755983;29309.413811723858;29464.852675736485;3377.7128944039787;3391.7937599310735;3460.775049621792;3467.8959631211105;3484.1289677580603;178735;179168.10415947967;179234;179313;179431;52870.735646321526;53088.203676944875;53213.7339313301;53348.46651533512;53364;31960.84019579887;33458.330833383196;33463.66536334654;55817.441825582035;55955.44044559502;14201.85798142026;14385.064970777075;14471.78292325616;14476.420943162366;14487.565373038826;9405.294602904809;10300;10425.322354046963;10927.836082458769;11209;18053.638927221382;18101;18108.9094554529;18119.45641630753;18164.727529087075;5369.838904832861;6900.482463815234;6928.099347084947;6961.443084553223;7022.6488675566015;730.7442395161651;799.3884678221166;881.6737807011447;922.6355589542093;990.4948476277151;27172.728272717417;27528.449431011268;28124.71875281262;28570;28694.713052869203;871.0897112517308;875.6935072724623;954.2318433660831;968.9273304502191;969.8545218217324;14174.716505670036;14813.629659258604;14856.182909940057;15334.309956052004;15494.845051549566;331826;333077;333254;333851;333968;50011.49988500068;50427.74786126046;50495.24257136146;50507.24239136416;50547;1043.1341986151476;1095.1239008793093;1115.8716747574133;1117.620009196877;1134.5575225661999;27400.93082779692;28524.714752852207;29424;29526.557101643062;29849.10452686423;41235.793821031904;41883.58116418797;42086.1582768349;42096.3685544711;42125.051246412455;293432;294609;294621;295064;444819;36844.44733329056;37164.628353716114;37221.81389093146;37367.81316093511;37550.2489950194;42984.78507607442;43184;43288.567114328456;43531.56468435275;43601.34597981096;36156.4576531347;36553;36600.81699591485;36608.08479788026;38112.809435952644;35271.64728352786;35690.286194275446;36017;36171;36480;33527;33823.83088084544;35114.12214694653;35600.64399356077;36181.457278140326;30840.691593083782;33450.163745906546;33551.49672754862;33597.83201083979;33657.326853462306;24461.633075503527;25878.482430351665;25927.740722593288;25974.480510390065;26225.475490490466 19:03:29 INFO - PROCESS | 4315 | |2;jquery.html;329.2098962490025;347.08196819412655;347.10793261318423;353.74530338156524;382.79520456555747;11900.702482437944;15438.691226175484;15801.155791957442;18045.90977045113;18228.179731912063;16927;16951.745723814154;17109.743353849703;17292.221850016747;17408.651826963454;4927.408710954691;5068.6958782473075;5149.459306772791;5171.301874246985;5176.2235664650225;3599.5500562429697;5091.71995540247;5175.378954525462;5768.307803063617;6047.909281360783;6033.487153591928;6034.4568988791;6038.215032045828;6039.426254505837;6051.818445446644;5931.495822855062;5938.613990090674;6010.489108425789;6036.3360030396625;6048;5093.108705976476;5263.157894736862;5281.445448227919;5288.23320618512;5296.020236256267;20536.897315513328;20811;20819.375418737312;20860.895695521576;20899;1620.5948512871782;1722.8277172282797;1818.3272189289914;1877.662020836249;2537.8223524353302;8346;8968;8989.37074404792;9087.091290870927;9162.496062716547;1633.8039435267724;1713.194798444729;1772.840444360001;1773.3172728499492;1787.892726436418;73033;73656.63171684108;73763;74503.62748186279;75483;9365.438073715599;9488.573014214377;9552.61789528425;9784.755381115387;9818.558164882597;12884.742305153844;12911.935440322739;12948.223106613634;12980.35098245084;12993;6446.903296450557;6840.931590684129;6992.1958974718555;6994.4404447644065;7072.328128827753;6520.510961677893;6544.312847151075;6561.80314590563;6726.495512836557;6733.091032710521;4064.3700226464944;4072.5927407259037;4137.81379837908;4139.730917490354;4155.480564929384;1664.7253203221576;1715.2024308696516;1717.3645751072183;1718.862491000717;1747.8776485645883;811.290121143999;811.3914564076942;829.2163905109613;903.408267584733;904.7104926423482;15771.763423548657;16387.180640967905;16898;17306.567335816704;17793.110344482724;814.3200427642867;834.1825011488775;837.0624900111792;855.5850412549909;888.7333799860016;9023.187913087722;9559.474228917337;9712.70861874145;9776.071273229032;9907.405555666539;276884;278406;376810;381341;381530;77909;79400;80155;81596;82442;891.8439273127307;923.7852224324992;932.2961164320894;945.8628498867625;948.6347756113946;15621.218939052775;15641.530754077396;16249;16365.099919504542;16497.83502165011;25846.353841154123;26140.60789088203;34749;36358;36759;47813;47871.281930771765;49918.500814991385;55735.44264557465;57703.4229657698;6961.547499412522;7237.095363079615;7299.5255308404785;7463.664135113824;7464.290892365217;10844.3931142287;11525.94237028843;11960.58137965167;11984.161108722306;12305.200161989442;10484.895151048391;10609.681709548726;10751.6236931707;11121.443927803739;11193.832092518456;9967;10702.73243168927;10733.355998640107;10799.56801727922;10862.565497380016;10605.46972651349;10632.202584806017;10711.517981690688;10814.459277036274;11143.888561114285;7475.925240747741;7479.813004674927;7573.46985710995;7630.961845190738;7640.197779233323;9024.639014439612;10592.629257975934;10632.36205827622;10690.558786093925;10732.73168170802 19:03:29 INFO - PROCESS | 4315 | |3;mootools.html;1236.2644226685131;1349.2376807103988;1388.1601631013236;1432.878205352545;1667.9166041697913;7405.259474052592;7784.766457006292;7833.80415489613;7844;7850.528968261909;7567;7614.314711675948;7645.235476452352;7653.464257501977;7656.38748900088;1892.2809332453717;2116.6507526258147;2143.7749036351192;2155.1487162570775;2159.0500179920787;2214.678871563622;2223.633100538409;2234.6089434349005;2235;2237.161064600775;2093.549886774343;2108.441263065289;2128.265748316836;2130.371540395587;2130.659094544873;2041.1223174035158;2122.7771084036262;2125.298651445027;2129.776373480793;2130.4247853079737;2131.030381176561;2155.224119317045;2156.1914282144194;2156.7088443060143;2158.665406861939;7840.2159784021715;7878.2515661012685;7911.683532658687;7919.881201781978;7931;4442.489113751928;4564.269716845289;4564.726116433025;4568.908621827578;4570.291604801228;2758.6965433802266;2901.2021694034097;2905.1575043237435;2907.200519857035;2929.868155932988;834.2825170353491;854.7649396415973;878.5168157513342;878.7583414561046;882.4969767232616;10200.591976321013;10248.538815753309;10273.486325683685;10291.742706432398;10347.482625868677;3657.104009517685;3843.673287770515;3955.6637685796454;3969.523657161159;3988.3847041948056;8196.508209507449;8199.795005124919;8216.301614362847;8218.465799723119;8220.506769593843;4463.218936686067;4467.972366355756;4477.529859364785;4479.10417916418;4484.439445069366;3697.852085916587;4031.294523458383;4120.25835349634;4168.312228482328;4175;3372.7976321420792;3379.3410284994684;3479.860805567749;3517.138301116243;3517.771344862627;1041.4274445587066;1042.3797840285017;1044.38903241603;1048.208602505119;1057.2229411382627;621.9564630475824;654.9214094308713;658.1772784019975;664.9534532582672;667.7762949412001;4690.319903614025;4841.1285968526;4893.926591101136;4900.289458028566;5212.218167274863;384.1241968312253;398.36261980831017;399.12193175015085;400.0897957147513;400.0917916329973;3247.1882029492626;3455.6889879910927;3581.068922080278;3623.8731644392847;3691.8154092295276;9464.290178236659;9508.191803696764;9619.230461563058;9634.951825240829;9702.078302561245;9462.384944978554;9462.858057129148;9472.384295020802;9487.383320084173;9498.715038548855;7020.438364930793;7192.604406757574;7206.639668016578;7207.306283023485;7218.602976836324;5056.7471626419265;5118.718470484159;5179.559737422362;5213.348331458567;5398.055340315351;8573.61418736146;8818.91181088181;8850.247728942855;8851.159139881702;8886.955565222133;8696.391252612257;8932.19610235082;8954.149355811187;8999.595018224065;9013.233875120426;6152.938470615416;6890.8621827564175;7037.612931288828;7085.645717713991;7101.964490177723;7015.789526314219;7314.195438501654;7322.780316590511;7325.450591205575;7329.670164842488;6276.1840960676645;6314.494840412664;6351.301356850835;6388.2972872984865;6549.508786840911;6001;6248.593841400295;6336.239651241881;6383.808485745435;6401.711922963571;5684.147377893349;5891.263592050994;5891.823245302477;5893.675847828411;5896.115582662635;4169.937450938178;4277.006095732937;4334.26317526015;4355.041890784022;4396.736195828261;4160.625543701081;4192.727472714264;4319.676024298127;4364.607185353333;4438.911221775482 19:03:29 INFO - PROCESS | 4315 | |4;prototype.html;270.86186045116995;273.67432755021525;280.1902501769885;281.6310633070677;282.3125688947412;4853.80584776609;4890.804367825287;4891.584215341701;4894.902101957963;4901.411830580335;4816.277558366247;4819.903601927959;4825.155597770385;4836.588889944359;4847.2002119650215;3754.1740817020213;3757.6242375762477;3763.3978563429855;3765.435184722286;3776.7922764247955;3793.9430908536256;3822.197338558906;3832.770033797981;3840.0975770693863;3842.51968503937;3442.6041005284337;3659.56085269768;3694.20574576466;3702.185928894836;3774.5281839770028;3646.9817650911577;3654.817259137059;3667.771296615637;3668.303022425744;3683.3001729671278;3000.924976875596;3195.121341631047;3200.1679563313473;3201.8707559472923;3216.678332166788;4511.954880451219;4608.493065762764;4624.982503849148;4637.884052898671;4648.418947631546;4144;4154.667626589866;4173.791310434466;4180.519240287375;4189.790510474494;4925.804816474985;5014.272930425104;5025.522575355372;5027.145385284511;5032.6477146599755;3384.289299247161;3414.163529935157;3431.262278610111;3441.1569165554347;3442.242706604543;5166.3800343958965;5179.766910489037;5185.714785686823;5188.792448302063;5211.1922651989;3503.7911920387423;3510.017195185352;3510.280392519527;3538.9469157962653;3551.5027896094566;3476.956912926112;3568.5360903082433;3587.6412358764437;3595.227026189382;3600.801955892451;4124.381342798604;4127.690423218271;4135.359019352005;4139.3583994480905;4165.937510937339;4963.726995015309;4970.403551573834;4974.452810190876;4998.000799680084;5005.799768009311;1224.718314787586;1237.3936770982334;1241.205628397826;1244.296972210698;1244.595506460404;766.9309762121436;778.746907255133;780.7469012235281;784.5175217241356;789.3211837819471;4014.8996275092773;4050.6151915567975;4051.8987025324604;4056.6146216109423;4056.7160298778804;4570.657200709894;4579.6794224403975;4587.632989360844;4623.42207224097;4627.380416854074;3963.4253033310583;3977.880663580097;4000.119973605802;4013.8394464221105;4014.979925100356;2588.967660386625;2711.5797051457057;2737.1241202815304;2739.76711979484;2742.506348857225;4612.331211974312;4846.642939976812;4859.465458799529;4861.756912154449;4883.679098213178;5159.406668233201;5237.947620523746;5294.544000399921;5300.257963885059;5307.9734601326745;4851.277796382304;4871.220604703301;4877.3418486924;4889.90220195587;4890.217565189624;4981.676191047571;5132.640715149903;5140.845774626767;5146.511081447256;5172.275881376686;5066.341375621146;5069.986002799528;5073.264376665289;5078.593712502917;5085.796568137233;4860.635452341159;4868.586170175433;4873.244647079709;4873.975630121969;4874.562004208838;3345.514900339486;3509.350770107538;3509.3683137035578;3530.417481115587;3567.661072198137;5148.9742551287;5152.793888244429;5157.406898206755;5172.586193104467;5190.97404512975;5003.2745251938995;5061.9493805061475;5062.114130027304;5068.87107414894;5072.112380333353;4828.734419606955;4991.376077990251;5005.224190250371;5019.494151754386;5031.421386540448;4937.481564435826;4937.580305674058;4949.109160351171;4951.529604687549;4958.256261560795;5052.823151189691;5053.595712343077;5054.545090941833;5059.6458247923765;5075.264086707481;4673.602743766818;4929.728864912464;4930.704157750546;4960.801567937242;4963.932754457809 19:03:29 INFO - PROCESS | 4315 | |5;yui.html;525.976331065102;564.8559617297589;570.0992431957508;581.2298704216913;596.0909612840418;12557.937210313947;13496.932515337434;13501.662458438532;13505.05464617477;13547.458101675933;12338.938305308484;12759;12829.42267597958;12832.358382080905;12905.354732263397;616.0512810272198;748.5396481164086;761.3908872901684;762.7254188492166;763.1452772894338;743.379278302618;763.1147868472573;765.8430021845509;770.183605378298;770.445279398832;817.3624572833199;819.8565251081067;820.9179082091773;821.0516853034725;821.2608652212996;637.4199478474566;814.9796255093611;816.0574536410426;816.0574536410426;816.2939057715093;551.7406818795159;567.2852206220192;576.0638961687258;578.1154833105355;579.4813641790574;12410.81383779244;12466.81299780504;12521.56174533887;12560.120791544596;12575.496980120784;10892.237543371966;11547.595834145764;11608.883911160865;11882.465289061927;12011.339376334297;11198.160137989684;11306.321620702784;11473.942630286878;11508.136889733303;11586.304821710724;4096;4112.979435102835;4139.648129908961;4175.039740859589;4187.937180942288;66484.66757666181;67026.32973670299;67369.66315168442;67502.32497675059;67556;9380.812383752287;9537.284703647254;9726.854097188549;10013.899861001297;10231.846522302172;17758.11209439523;17799.555011124565;17821.376251831123;17835.821641783674;17856.19647115883;6472.15861937945;6513.185851768529;6630.336966303427;6734.663266836638;7035.683394247271;3597.5862775780597;3606.891793246207;3614.0964758810296;3671.1372827385544;3688.6495782900583;2794.944101117966;2933.6479622445017;2938.676745557987;2939.2211064068038;2949.6018037564654;520.123591747906;540.3030091182409;551.8841043380854;552.326162082259;556.5130510803048;395.29793110945144;467.37371921625186;492.7191500844556;529.4335061484252;549.7031602934451;7628.80927976805;7823.048209038211;8043.678252869937;8175.836483270301;8514.318854491627;428.47083851443443;447.75821056629707;496.4390239029873;521.7260938007577;563.0119140907763;3413.3855905936675;3714.220013797079;3863.671587915059;3899.980500097481;3937;53806.73096634492;53872;53981;55047.449525505035;66258;80529.59735201404;80921.59539202384;81260.18739812645;82108.17891821005;83933;61934.38065619286;64361.67819160874;71941;74641;75568.86646700304;7749.612519373896;8047.879281810895;8409.62156702962;8489;8624.396292259482;31776.205594860312;41041.1791764169;43316.35025474684;44382;44422.555774443135;60858.69570652118;64404.67797660982;64459.355406445335;65665;67542.3245767536;9526.904730952601;9779.706608801747;9817.312788104764;9864.852027219455;10177.287589868638;11931.105167112675;12024.819627705416;12055.81916271239;12096.213746106476;12870.806937896126;10443.68668939933;10572.41851698164;10969.83545246806;10987.890121099006;11014.614488492864;10036.64871729486;10200.2349823765;10244.129249013917;10530.21023423231;11372.829407558727;10444.373337599769;10977;11044.889551104387;11180.161487888277;11187.10503159729;9347.953260233655;9370.797862502443;9486.952565237405;10150.23873209527;10324.122449591869;8579.871301930601;8751.474911505331;9138.17756401927;9650.806983860424;9771.218302535923 19:03:29 INFO - PROCESS | 4315 | __end_tp_report 19:03:29 INFO - PROCESS | 4315 | __start_cc_report 19:03:29 INFO - PROCESS | 4315 | _x_x_mozilla_cycle_collect,909 19:03:29 INFO - PROCESS | 4315 | __end_cc_report 19:03:29 INFO - PROCESS | 4315 | __startTimestamp1472522609199__endTimestamp 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | ------- Summary: start ------- 19:03:29 INFO - PROCESS | 4315 | Number of tests: 6 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | [#0] dojo.html Cycles:165 Average:4748.85 Median:4871.24 stddev:501.60 (10.3%) stddev-sans-first:473.32 19:03:29 INFO - PROCESS | 4315 | Values: 2577.0 2596.2 2613.7 2651.5 2670.5 4693.9 4707.9 4756.1 4783.1 4856.0 4729.6 4774.6 4783.4 4794.9 4851.3 4357.5 4404.8 4449.7 4510.3 4583.3 4479.4 4516.4 4521.1 4555.1 4558.5 4333.7 4367.4 4368.8 4371.1 4427.4 4381.6 4395.5 4500.8 4501.1 4514.3 4422.0 4426.5 4427.7 4435.9 4483.4 4670.8 4819.0 4820.7 4820.8 4823.2 5110.9 5174.2 5179.1 5179.3 5182.3 5181.6 5186.9 5195.2 5204.2 5227.3 4695.9 4764.7 4871.2 4873.1 4923.0 5238.3 5239.1 5262.8 5326.5 5328.2 5038.2 5048.8 5051.4 5052.6 5116.8 5021.8 5083.4 5134.5 5146.6 5154.8 5050.9 5095.1 5096.0 5152.9 5159.7 5060.5 5074.1 5078.8 5079.4 5138.8 4257.6 4320.4 4373.5 4387.6 4434.4 3742.6 3771.7 3784.8 3827.2 3885.8 4392.2 4394.5 4401.5 4437.8 4449.7 5010.2 5113.5 5118.5 5124.8 5127.3 4379.9 4389.3 4429.7 4482.4 4483.3 4606.1 4623.9 4630.1 4633.3 4692.5 4816.1 4829.6 4835.5 4842.6 4901.6 5049.4 5083.4 5093.0 5098.6 5174.6 4802.4 4809.2 4810.3 4860.6 4863.5 5036.7 5043.7 5045.5 5050.4 5056.5 4969.5 5068.0 5088.7 5109.6 5168.7 4996.3 5008.9 5012.3 5014.7 5041.5 5010.1 5016.3 5018.1 5030.5 5067.3 4800.7 4911.8 4947.6 4962.3 4987.6 5069.1 5092.5 5096.2 5097.8 5102.8 4944.7 4949.9 4950.3 4951.5 4998.2 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | [#1] ext.html Cycles:180 Average:44353.70 Median:33627.58 stddev:76150.73 (226.5%) stddev-sans-first:76293.37 19:03:29 INFO - PROCESS | 4315 | Values: 563.9 571.2 572.8 575.2 582.2 28143.4 38571.0 40362.0 40365.6 43919.1 39786.8 40151.0 40185.0 40357.8 40402.0 7127.0 7664.0 7823.3 7823.5 7839.1 10030.5 10386.1 10387.7 10392.6 10393.5 10116.4 10214.8 10216.3 10220.9 10222.9 10028.5 10089.4 10110.5 10114.3 10115.8 9946.3 10008.8 10014.5 10015.9 10019.8 38304.4 38595.6 38605.2 38617.4 38654.8 2552.9 2561.5 2576.8 2590.8 2636.3 28639.9 29169.4 29220.8 29309.4 29464.9 3377.7 3391.8 3460.8 3467.9 3484.1 178735.0 179168.1 179234.0 179313.0 179431.0 52870.7 53088.2 53213.7 53348.5 53364.0 31960.8 33458.3 33463.7 55817.4 55955.4 14201.9 14385.1 14471.8 14476.4 14487.6 9405.3 10300.0 10425.3 10927.8 11209.0 18053.6 18101.0 18108.9 18119.5 18164.7 5369.8 6900.5 6928.1 6961.4 7022.6 730.7 799.4 881.7 922.6 990.5 27172.7 27528.4 28124.7 28570.0 28694.7 871.1 875.7 954.2 968.9 969.9 14174.7 14813.6 14856.2 15334.3 15494.8 331826.0 333077.0 333254.0 333851.0 333968.0 50011.5 50427.7 50495.2 50507.2 50547.0 1043.1 1095.1 1115.9 1117.6 1134.6 27400.9 28524.7 29424.0 29526.6 29849.1 41235.8 41883.6 42086.2 42096.4 42125.1 293432.0 294609.0 294621.0 295064.0 444819.0 36844.4 37164.6 37221.8 37367.8 37550.2 42984.8 43184.0 43288.6 43531.6 43601.3 36156.5 36553.0 36600.8 36608.1 38112.8 35271.6 35690.3 36017.0 36171.0 36480.0 33527.0 33823.8 35114.1 35600.6 36181.5 30840.7 33450.2 33551.5 33597.8 33657.3 24461.6 25878.5 25927.7 25974.5 26225.5 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | [#2] jquery.html Cycles:180 Average:22986.45 Median:26399.35 stddev:57174.52 (216.6%) stddev-sans-first:57309.60 19:03:29 INFO - PROCESS | 4315 | Values: 329.2 347.1 347.1 353.7 382.8 11900.7 15438.7 15801.2 18045.9 18228.2 16927.0 16951.7 17109.7 17292.2 17408.7 4927.4 5068.7 5149.5 5171.3 5176.2 3599.6 5091.7 5175.4 5768.3 6047.9 6033.5 6034.5 6038.2 6039.4 6051.8 5931.5 5938.6 6010.5 6036.3 6048.0 5093.1 5263.2 5281.4 5288.2 5296.0 20536.9 20811.0 20819.4 20860.9 20899.0 1620.6 1722.8 1818.3 1877.7 2537.8 8346.0 8968.0 8989.4 9087.1 9162.5 1633.8 1713.2 1772.8 1773.3 1787.9 73033.0 73656.6 73763.0 74503.6 75483.0 9365.4 9488.6 9552.6 9784.8 9818.6 12884.7 12911.9 12948.2 12980.4 12993.0 6446.9 6840.9 6992.2 6994.4 7072.3 6520.5 6544.3 6561.8 6726.5 6733.1 4064.4 4072.6 4137.8 4139.7 4155.5 1664.7 1715.2 1717.4 1718.9 1747.9 811.3 811.4 829.2 903.4 904.7 15771.8 16387.2 16898.0 17306.6 17793.1 814.3 834.2 837.1 855.6 888.7 9023.2 9559.5 9712.7 9776.1 9907.4 276884.0 278406.0 376810.0 381341.0 381530.0 77909.0 79400.0 80155.0 81596.0 82442.0 891.8 923.8 932.3 945.9 948.6 15621.2 15641.5 16249.0 16365.1 16497.8 25846.4 26140.6 34749.0 36358.0 36759.0 47813.0 47871.3 49918.5 55735.4 57703.4 6961.5 7237.1 7299.5 7463.7 7464.3 10844.4 11525.9 11960.6 11984.2 12305.2 10484.9 10609.7 10751.6 11121.4 11193.8 9967.0 10702.7 10733.4 10799.6 10862.6 10605.5 10632.2 10711.5 10814.5 11143.9 7475.9 7479.8 7573.5 7631.0 7640.2 9024.6 10592.6 10632.4 10690.6 10732.7 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | [#3] mootools.html Cycles:180 Average:4969.10 Median:4765.72 stddev:2841.08 (59.6%) stddev-sans-first:2835.20 19:03:29 INFO - PROCESS | 4315 | Values: 1236.3 1349.2 1388.2 1432.9 1667.9 7405.3 7784.8 7833.8 7844.0 7850.5 7567.0 7614.3 7645.2 7653.5 7656.4 1892.3 2116.7 2143.8 2155.1 2159.1 2214.7 2223.6 2234.6 2235.0 2237.2 2093.5 2108.4 2128.3 2130.4 2130.7 2041.1 2122.8 2125.3 2129.8 2130.4 2131.0 2155.2 2156.2 2156.7 2158.7 7840.2 7878.3 7911.7 7919.9 7931.0 4442.5 4564.3 4564.7 4568.9 4570.3 2758.7 2901.2 2905.2 2907.2 2929.9 834.3 854.8 878.5 878.8 882.5 10200.6 10248.5 10273.5 10291.7 10347.5 3657.1 3843.7 3955.7 3969.5 3988.4 8196.5 8199.8 8216.3 8218.5 8220.5 4463.2 4468.0 4477.5 4479.1 4484.4 3697.9 4031.3 4120.3 4168.3 4175.0 3372.8 3379.3 3479.9 3517.1 3517.8 1041.4 1042.4 1044.4 1048.2 1057.2 622.0 654.9 658.2 665.0 667.8 4690.3 4841.1 4893.9 4900.3 5212.2 384.1 398.4 399.1 400.1 400.1 3247.2 3455.7 3581.1 3623.9 3691.8 9464.3 9508.2 9619.2 9635.0 9702.1 9462.4 9462.9 9472.4 9487.4 9498.7 7020.4 7192.6 7206.6 7207.3 7218.6 5056.7 5118.7 5179.6 5213.3 5398.1 8573.6 8818.9 8850.2 8851.2 8887.0 8696.4 8932.2 8954.1 8999.6 9013.2 6152.9 6890.9 7037.6 7085.6 7102.0 7015.8 7314.2 7322.8 7325.5 7329.7 6276.2 6314.5 6351.3 6388.3 6549.5 6001.0 6248.6 6336.2 6383.8 6401.7 5684.1 5891.3 5891.8 5893.7 5896.1 4169.9 4277.0 4334.3 4355.0 4396.7 4160.6 4192.7 4319.7 4364.6 4438.9 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | [#4] prototype.html Cycles:180 Average:4100.45 Median:4661.01 stddev:1220.18 (26.2%) stddev-sans-first:1189.26 19:03:29 INFO - PROCESS | 4315 | Values: 270.9 273.7 280.2 281.6 282.3 4853.8 4890.8 4891.6 4894.9 4901.4 4816.3 4819.9 4825.2 4836.6 4847.2 3754.2 3757.6 3763.4 3765.4 3776.8 3793.9 3822.2 3832.8 3840.1 3842.5 3442.6 3659.6 3694.2 3702.2 3774.5 3647.0 3654.8 3667.8 3668.3 3683.3 3000.9 3195.1 3200.2 3201.9 3216.7 4512.0 4608.5 4625.0 4637.9 4648.4 4144.0 4154.7 4173.8 4180.5 4189.8 4925.8 5014.3 5025.5 5027.1 5032.6 3384.3 3414.2 3431.3 3441.2 3442.2 5166.4 5179.8 5185.7 5188.8 5211.2 3503.8 3510.0 3510.3 3538.9 3551.5 3477.0 3568.5 3587.6 3595.2 3600.8 4124.4 4127.7 4135.4 4139.4 4165.9 4963.7 4970.4 4974.5 4998.0 5005.8 1224.7 1237.4 1241.2 1244.3 1244.6 766.9 778.7 780.7 784.5 789.3 4014.9 4050.6 4051.9 4056.6 4056.7 4570.7 4579.7 4587.6 4623.4 4627.4 3963.4 3977.9 4000.1 4013.8 4015.0 2589.0 2711.6 2737.1 2739.8 2742.5 4612.3 4846.6 4859.5 4861.8 4883.7 5159.4 5237.9 5294.5 5300.3 5308.0 4851.3 4871.2 4877.3 4889.9 4890.2 4981.7 5132.6 5140.8 5146.5 5172.3 5066.3 5070.0 5073.3 5078.6 5085.8 4860.6 4868.6 4873.2 4874.0 4874.6 3345.5 3509.4 3509.4 3530.4 3567.7 5149.0 5152.8 5157.4 5172.6 5191.0 5003.3 5061.9 5062.1 5068.9 5072.1 4828.7 4991.4 5005.2 5019.5 5031.4 4937.5 4937.6 4949.1 4951.5 4958.3 5052.8 5053.6 5054.5 5059.6 5075.3 4673.6 4929.7 4930.7 4960.8 4963.9 19:03:29 INFO - PROCESS | 4315 | 19:03:29 INFO - PROCESS | 4315 | [#5] yui.html Cycles:180 Average:16286.76 Median:53839.37 stddev:22312.29 (41.4%) stddev-sans-first:22343.49 19:03:29 INFO - PROCESS | 4315 | Values: 526.0 564.9 570.1 581.2 596.1 12557.9 13496.9 13501.7 13505.1 13547.5 12338.9 12759.0 12829.4 12832.4 12905.4 616.1 748.5 761.4 762.7 763.1 743.4 763.1 765.8 770.2 770.4 817.4 819.9 820.9 821.1 821.3 637.4 815.0 816.1 816.1 816.3 551.7 567.3 576.1 578.1 579.5 12410.8 12466.8 12521.6 12560.1 12575.5 10892.2 11547.6 11608.9 11882.5 12011.3 11198.2 11306.3 11473.9 11508.1 11586.3 4096.0 4113.0 4139.6 4175.0 4187.9 66484.7 67026.3 67369.7 67502.3 67556.0 9380.8 9537.3 9726.9 10013.9 10231.8 17758.1 17799.6 17821.4 17835.8 17856.2 6472.2 6513.2 6630.3 6734.7 7035.7 3597.6 3606.9 3614.1 3671.1 3688.6 2794.9 2933.6 2938.7 2939.2 2949.6 520.1 540.3 551.9 552.3 556.5 395.3 467.4 492.7 529.4 549.7 7628.8 7823.0 8043.7 8175.8 8514.3 428.5 447.8 496.4 521.7 563.0 3413.4 3714.2 3863.7 3900.0 3937.0 53806.7 53872.0 53981.0 55047.4 66258.0 80529.6 80921.6 81260.2 82108.2 83933.0 61934.4 64361.7 71941.0 74641.0 75568.9 7749.6 8047.9 8409.6 8489.0 8624.4 31776.2 41041.2 43316.4 44382.0 44422.6 60858.7 64404.7 64459.4 65665.0 67542.3 9526.9 9779.7 9817.3 9864.9 10177.3 11931.1 12024.8 12055.8 12096.2 12870.8 10443.7 10572.4 10969.8 10987.9 11014.6 10036.6 10200.2 10244.1 10530.2 11372.8 10444.4 10977.0 11044.9 11180.2 11187.1 9348.0 9370.8 9487.0 10150.2 10324.1 8579.9 8751.5 9138.2 9650.8 9771.2 19:03:29 INFO - PROCESS | 4315 | -------- Summary: end -------- 19:03:29 INFO - PROCESS | 4315 | 19:03:30 INFO - TEST-INFO | 4315: exit 0 19:03:30 INFO - TEST-OK | dromaeo_css | took 1120346ms 19:03:30 INFO - TEST-START | kraken 19:03:30 INFO - operating with platform_type : linux_ 19:03:30 INFO - Initialising browser for kraken test... 19:03:30 INFO - TEST-INFO | started process 4617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK_Ko5h/profile http://localhost:44124/getInfo.html) 19:03:34 INFO - PROCESS | 4617 | __metrics Screen width/height:1600/1200 19:03:34 INFO - PROCESS | 4617 | colorDepth:24 19:03:34 INFO - PROCESS | 4617 | Browser inner width/height: 1024/697 19:03:34 INFO - PROCESS | 4617 | __metrics 19:03:34 INFO - PROCESS | 4617 | 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" 19:03:34 INFO - PROCESS | 4617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:03:35 INFO - PROCESS | 4617 | console.error: PushService: 19:03:35 INFO - PROCESS | 4617 | stateChangeProcessEnqueue: Error transitioning state 19:03:35 INFO - PROCESS | 4617 | UnknownError 19:03:35 INFO - TEST-INFO | 4617: exit 0 19:03:35 INFO - Browser initialized. 19:03:35 INFO - Running cycle 1/1 for kraken test... 19:03:35 INFO - TEST-INFO | started process 4696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK_Ko5h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:03:48 INFO - PROCESS | 4696 | RSS: Main: 182149120 19:03:48 INFO - PROCESS | 4696 | 19:03:50 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44124/tests/kraken/driver.html?testName=audio-beat-detection) 19:03:51 INFO - PROCESS | 4696 | RSS: Main: 239161344 19:03:51 INFO - PROCESS | 4696 | 19:03:54 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44124/tests/kraken/driver.html?testName=audio-dft) 19:03:55 INFO - PROCESS | 4696 | RSS: Main: 225357824 19:03:55 INFO - PROCESS | 4696 | 19:03:58 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44124/tests/kraken/driver.html?testName=audio-fft) 19:03:59 INFO - PROCESS | 4696 | RSS: Main: 303878144 19:03:59 INFO - PROCESS | 4696 | 19:04:01 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44124/tests/kraken/driver.html?testName=audio-oscillator) 19:04:01 INFO - PROCESS | 4696 | RSS: Main: 213811200 19:04:01 INFO - PROCESS | 4696 | 19:04:04 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44124/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:04:04 INFO - PROCESS | 4696 | RSS: Main: 215281664 19:04:04 INFO - PROCESS | 4696 | 19:04:07 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44124/tests/kraken/driver.html?testName=imaging-darkroom) 19:04:08 INFO - PROCESS | 4696 | RSS: Main: 254275584 19:04:08 INFO - PROCESS | 4696 | 19:04:11 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44124/tests/kraken/driver.html?testName=imaging-desaturate) 19:04:12 INFO - PROCESS | 4696 | RSS: Main: 253050880 19:04:12 INFO - PROCESS | 4696 | 19:04:15 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44124/tests/kraken/driver.html?testName=json-parse-financial) 19:04:16 INFO - PROCESS | 4696 | RSS: Main: 252297216 19:04:16 INFO - PROCESS | 4696 | 19:04:17 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44124/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:04:18 INFO - PROCESS | 4696 | RSS: Main: 215289856 19:04:18 INFO - PROCESS | 4696 | 19:04:20 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:04:20 INFO - PROCESS | 4696 | RSS: Main: 222289920 19:04:20 INFO - PROCESS | 4696 | 19:04:23 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:04:23 INFO - PROCESS | 4696 | RSS: Main: 223436800 19:04:23 INFO - PROCESS | 4696 | 19:04:26 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:04:27 INFO - PROCESS | 4696 | RSS: Main: 224161792 19:04:27 INFO - PROCESS | 4696 | 19:04:30 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:04:30 INFO - PROCESS | 4696 | RSS: Main: 221507584 19:04:30 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | Cycle 1(1): loaded http://localhost:44124/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44124/tests/kraken/driver.html?testName=ai-astar) 19:04:32 INFO - PROCESS | 4696 | RSS: Main: 272760832 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | __start_tp_report 19:04:32 INFO - PROCESS | 4696 | _x_x_mozilla_page_load 19:04:32 INFO - PROCESS | 4696 | _x_x_mozilla_page_load_details 19:04:32 INFO - PROCESS | 4696 | |i|pagename|runs| 19:04:32 INFO - PROCESS | 4696 | |0;ai-astar;116;116;132;116;114;115;113;115;113;114 19:04:32 INFO - PROCESS | 4696 | |1;audio-beat-detection;157;127;151;184;123;156;148;124;198;122 19:04:32 INFO - PROCESS | 4696 | |2;audio-dft;164;161;163;160;161;160;163;161;162;161 19:04:32 INFO - PROCESS | 4696 | |3;audio-fft;76;78;77;174;78;78;76;76;158;77 19:04:32 INFO - PROCESS | 4696 | |4;audio-oscillator;84;85;150;82;83;83;144;85;84;135 19:04:32 INFO - PROCESS | 4696 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;116;117 19:04:32 INFO - PROCESS | 4696 | |6;imaging-darkroom;146;146;146;146;147;146;146;146;146;146 19:04:32 INFO - PROCESS | 4696 | |7;imaging-desaturate;94;95;96;95;95;95;96;95;95;95 19:04:32 INFO - PROCESS | 4696 | |8;json-parse-financial;65;65;64;65;66;65;65;66;66;65 19:04:32 INFO - PROCESS | 4696 | |9;json-stringify-tinderbox;65;66;67;66;66;65;65;67;66;65 19:04:32 INFO - PROCESS | 4696 | |10;stanford-crypto-aes;84;82;81;81;82;82;81;82;82;83 19:04:32 INFO - PROCESS | 4696 | |11;stanford-crypto-ccm;133;147;132;134;142;132;136;133;172;138 19:04:32 INFO - PROCESS | 4696 | |12;stanford-crypto-pbkdf2;173;172;174;173;175;173;171;171;172;168 19:04:32 INFO - PROCESS | 4696 | |13;stanford-crypto-sha256-iterative;71;69;69;71;71;69;69;69;69;68 19:04:32 INFO - PROCESS | 4696 | __end_tp_report 19:04:32 INFO - PROCESS | 4696 | __start_cc_report 19:04:32 INFO - PROCESS | 4696 | _x_x_mozilla_cycle_collect,1970 19:04:32 INFO - PROCESS | 4696 | __end_cc_report 19:04:32 INFO - PROCESS | 4696 | __startTimestamp1472522672796__endTimestamp 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | ------- Summary: start ------- 19:04:32 INFO - PROCESS | 4696 | Number of tests: 14 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#0] ai-astar Cycles:10 Average:116.40 Median:115.50 stddev:5.60 (4.8%) stddev-sans-first:5.94 19:04:32 INFO - PROCESS | 4696 | Values: 116.0 116.0 132.0 116.0 114.0 115.0 113.0 115.0 113.0 114.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#1] audio-beat-detection Cycles:10 Average:149.00 Median:153.50 stddev:26.37 (17.2%) stddev-sans-first:27.81 19:04:32 INFO - PROCESS | 4696 | Values: 157.0 127.0 151.0 184.0 123.0 156.0 148.0 124.0 198.0 122.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.35 (0.8%) stddev-sans-first:1.12 19:04:32 INFO - PROCESS | 4696 | Values: 164.0 161.0 163.0 160.0 161.0 160.0 163.0 161.0 162.0 161.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#3] audio-fft Cycles:10 Average:94.80 Median:77.00 stddev:37.72 (49.0%) stddev-sans-first:39.39 19:04:32 INFO - PROCESS | 4696 | Values: 76.0 78.0 77.0 174.0 78.0 78.0 76.0 76.0 158.0 77.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#4] audio-oscillator Cycles:10 Average:101.50 Median:83.50 stddev:28.87 (34.6%) stddev-sans-first:29.92 19:04:32 INFO - PROCESS | 4696 | Values: 84.0 85.0 150.0 82.0 83.0 83.0 144.0 85.0 84.0 135.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:04:32 INFO - PROCESS | 4696 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 19:04:32 INFO - PROCESS | 4696 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 19:04:32 INFO - PROCESS | 4696 | Values: 94.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 19:04:32 INFO - PROCESS | 4696 | Values: 65.0 65.0 64.0 65.0 66.0 65.0 65.0 66.0 66.0 65.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 19:04:32 INFO - PROCESS | 4696 | Values: 65.0 66.0 67.0 66.0 66.0 65.0 65.0 67.0 66.0 65.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:0.94 (1.1%) stddev-sans-first:0.67 19:04:32 INFO - PROCESS | 4696 | Values: 84.0 82.0 81.0 81.0 82.0 82.0 81.0 82.0 82.0 83.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:137.00 stddev:12.29 (9.0%) stddev-sans-first:12.78 19:04:32 INFO - PROCESS | 4696 | Values: 133.0 147.0 132.0 134.0 142.0 132.0 136.0 133.0 172.0 138.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:173.00 stddev:1.93 (1.1%) stddev-sans-first:2.03 19:04:32 INFO - PROCESS | 4696 | Values: 173.0 172.0 174.0 173.0 175.0 173.0 171.0 171.0 172.0 168.0 19:04:32 INFO - PROCESS | 4696 | 19:04:32 INFO - PROCESS | 4696 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 19:04:32 INFO - PROCESS | 4696 | Values: 71.0 69.0 69.0 71.0 71.0 69.0 69.0 69.0 69.0 68.0 19:04:32 INFO - PROCESS | 4696 | -------- Summary: end -------- 19:04:32 INFO - PROCESS | 4696 | 19:04:34 INFO - TEST-INFO | 4696: exit 0 19:04:34 INFO - TEST-OK | kraken | took 63854ms 19:04:34 INFO - SUITE-END | took 1184s 19:04:34 INFO - Completed test suite (00:19:44) 19:04:34 INFO - javascript benchmark 19:04:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2576.9803609157975, 2596.195179494357, 2613.6602241708574, 2651.4697060587887, 2670.5460071787793, 4693.8591842244705, 4707.90584188316, 4756.096341695084, 4783.115123702118, 4855.951440485598, 4729.645276604251, 4774.618030557555, 4783.354247176639, 4794.9451120753465, 4851.272309153629, 4357.520672725997, 4404.801783919731, 4449.733016019033, 4510.323451482271, 4583.289590113538, 4479.395281637003, 4516.390287311204, 4521.140983213196, 4555.088982203572, 4558.498565157846, 4333.674974376934, 4367.432233809616, 4368.847090351855, 4371.125774845043, 4427.358033085185, 4381.627561657263, 4395.494518130424, 4500.797464114123, 4501.122281155176, 4514.255147900592, 4422.027154026109, 4426.513083560806, 4427.734335939822, 4435.933460998088, 4483.39474170986, 4670.789814458358, 4818.951810481885, 4820.68665536739, 4820.831270905536, 4823.155947709172, 5110.923336149961, 5174.197999310113, 5179.14544100223, 5179.2749015137915, 5182.274481572583, 5181.611379146579, 5186.948130518685, 5195.194744814522, 5204.245384419276, 5227.294315267466, 4695.882602934954, 4764.714117152913, 4871.244957031595, 4873.147199240118, 4923.040007198598, 5238.345206849144, 5239.056969745482, 5262.842114736564, 5326.520613144836, 5328.17413300939, 5038.168702164176, 5048.848534543969, 5051.444341122474, 5052.5705315048635, 5116.769745361467, 5021.84934451967, 5083.415407228216, 5134.5378915897745, 5146.614003949719, 5154.768035438415, 5050.924236136461, 5095.0574143783515, 5095.974520127375, 5152.922706159336, 5159.664621799571, 5060.493950604984, 5074.137396642506, 5078.847634570969, 5079.415867175248, 5138.768755406016, 4257.638100761408, 4320.395144679747, 4373.497047839475, 4387.583179597933, 4434.401355816988, 3742.5883152853166, 3771.7171212159196, 3784.810759462016, 3827.2345530893376, 3885.805709714503, 4392.1874453226255, 4394.494633117232, 4401.537838526972, 4437.8224871004795, 4449.710768800018, 5010.223415370623, 5113.463086375951, 5118.513741194581, 5124.7950081996305, 5127.282180494733, 4379.912401752011, 4389.297712366006, 4429.712068715523, 4482.417285752831, 4483.2826747720865, 4606.147862645421, 4623.930641040387, 4630.120277147331, 4633.30500424939, 4692.5307469253485, 4816.084943860656, 4829.589484893753, 4835.516448355136, 4842.6368022397755, 4901.5588597026435, 5049.394072711299, 5083.364579427572, 5093.0, 5098.566621837185, 5174.637775355689, 4802.375691160127, 4809.158397280532, 4810.254410566367, 4860.586850117638, 4863.537963893566, 5036.697798132123, 5043.672161309503, 5045.495450454925, 5050.368703912011, 5056.469070747518, 4969.4782047884455, 5068.0, 5088.745562721775, 5109.616778741535, 5168.715720635401, 4996.3005179274205, 5008.874778130576, 5012.3233363495465, 5014.724190169576, 5041.470645582161, 5010.073136469765, 5016.297718319511, 5018.096742586369, 5030.522100400456, 5067.341245638044, 4800.711957282574, 4911.754412279443, 4947.628927830499, 4962.280469331999, 4987.600991920565, 5069.138246498031, 5092.516210959953, 5096.184610462308, 5097.796088156432, 5102.795888164432, 4944.678595891256, 4949.8515044547285, 4950.306957026091, 4951.455339912679, 4998.150314446625], "value": 4715.967826311024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.9125935479997, 571.1575426246286, 572.8052462162864, 575.2062154227166, 582.2198254339186, 28143.437131257364, 38571.0, 40362.0, 40365.59634403655, 43919.12161756763, 39786.8010659947, 40151.0, 40185.0, 40357.79821100891, 40402.0, 7127.037849890276, 7664.0, 7823.295903368696, 7823.452358334919, 7839.059312882462, 10030.542517083324, 10386.117180039706, 10387.68836934893, 10392.584296628163, 10393.480325983708, 10116.393016419039, 10214.795704085915, 10216.335938163995, 10220.948895255513, 10222.948885255564, 10028.498575071253, 10089.445080520569, 10110.494475276208, 10114.34256773311, 10115.797684046278, 9946.254030947708, 10008.849867251996, 10014.499275036293, 10015.89984100157, 10019.849702254473, 38304.42543361852, 38595.61404385948, 38605.227895442215, 38617.42073868894, 38654.80672596619, 2552.8723563821736, 2561.5261176682375, 2576.780973617245, 2590.7538783815694, 2636.275024368295, 28639.856800716072, 29169.416611667646, 29220.834099755983, 29309.413811723858, 29464.852675736485, 3377.7128944039787, 3391.7937599310735, 3460.775049621792, 3467.8959631211105, 3484.1289677580603, 178735.0, 179168.10415947967, 179234.0, 179313.0, 179431.0, 52870.735646321526, 53088.203676944875, 53213.7339313301, 53348.46651533512, 53364.0, 31960.84019579887, 33458.330833383196, 33463.66536334654, 55817.441825582035, 55955.44044559502, 14201.85798142026, 14385.064970777075, 14471.78292325616, 14476.420943162366, 14487.565373038826, 9405.294602904809, 10300.0, 10425.322354046963, 10927.836082458769, 11209.0, 18053.638927221382, 18101.0, 18108.9094554529, 18119.45641630753, 18164.727529087075, 5369.838904832861, 6900.482463815234, 6928.099347084947, 6961.443084553223, 7022.6488675566015, 730.7442395161651, 799.3884678221166, 881.6737807011447, 922.6355589542093, 990.4948476277151, 27172.728272717417, 27528.449431011268, 28124.71875281262, 28570.0, 28694.713052869203, 871.0897112517308, 875.6935072724623, 954.2318433660831, 968.9273304502191, 969.8545218217324, 14174.716505670036, 14813.629659258604, 14856.182909940057, 15334.309956052004, 15494.845051549566, 331826.0, 333077.0, 333254.0, 333851.0, 333968.0, 50011.49988500068, 50427.74786126046, 50495.24257136146, 50507.24239136416, 50547.0, 1043.1341986151476, 1095.1239008793093, 1115.8716747574133, 1117.620009196877, 1134.5575225661999, 27400.93082779692, 28524.714752852207, 29424.0, 29526.557101643062, 29849.10452686423, 41235.793821031904, 41883.58116418797, 42086.1582768349, 42096.3685544711, 42125.051246412455, 293432.0, 294609.0, 294621.0, 295064.0, 444819.0, 36844.44733329056, 37164.628353716114, 37221.81389093146, 37367.81316093511, 37550.2489950194, 42984.78507607442, 43184.0, 43288.567114328456, 43531.56468435275, 43601.34597981096, 36156.4576531347, 36553.0, 36600.81699591485, 36608.08479788026, 38112.809435952644, 35271.64728352786, 35690.286194275446, 36017.0, 36171.0, 36480.0, 33527.0, 33823.83088084544, 35114.12214694653, 35600.64399356077, 36181.457278140326, 30840.691593083782, 33450.163745906546, 33551.49672754862, 33597.83201083979, 33657.326853462306, 24461.633075503527, 25878.482430351665, 25927.740722593288, 25974.480510390065, 26225.475490490466], "value": 17675.615448449647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.2098962490025, 347.08196819412655, 347.10793261318423, 353.74530338156524, 382.79520456555747, 11900.702482437944, 15438.691226175484, 15801.155791957442, 18045.90977045113, 18228.179731912063, 16927.0, 16951.745723814154, 17109.743353849703, 17292.221850016747, 17408.651826963454, 4927.408710954691, 5068.6958782473075, 5149.459306772791, 5171.301874246985, 5176.2235664650225, 3599.5500562429697, 5091.71995540247, 5175.378954525462, 5768.307803063617, 6047.909281360783, 6033.487153591928, 6034.4568988791, 6038.215032045828, 6039.426254505837, 6051.818445446644, 5931.495822855062, 5938.613990090674, 6010.489108425789, 6036.3360030396625, 6048.0, 5093.108705976476, 5263.157894736862, 5281.445448227919, 5288.23320618512, 5296.020236256267, 20536.897315513328, 20811.0, 20819.375418737312, 20860.895695521576, 20899.0, 1620.5948512871782, 1722.8277172282797, 1818.3272189289914, 1877.662020836249, 2537.8223524353302, 8346.0, 8968.0, 8989.37074404792, 9087.091290870927, 9162.496062716547, 1633.8039435267724, 1713.194798444729, 1772.840444360001, 1773.3172728499492, 1787.892726436418, 73033.0, 73656.63171684108, 73763.0, 74503.62748186279, 75483.0, 9365.438073715599, 9488.573014214377, 9552.61789528425, 9784.755381115387, 9818.558164882597, 12884.742305153844, 12911.935440322739, 12948.223106613634, 12980.35098245084, 12993.0, 6446.903296450557, 6840.931590684129, 6992.1958974718555, 6994.4404447644065, 7072.328128827753, 6520.510961677893, 6544.312847151075, 6561.80314590563, 6726.495512836557, 6733.091032710521, 4064.3700226464944, 4072.5927407259037, 4137.81379837908, 4139.730917490354, 4155.480564929384, 1664.7253203221576, 1715.2024308696516, 1717.3645751072183, 1718.862491000717, 1747.8776485645883, 811.290121143999, 811.3914564076942, 829.2163905109613, 903.408267584733, 904.7104926423482, 15771.763423548657, 16387.180640967905, 16898.0, 17306.567335816704, 17793.110344482724, 814.3200427642867, 834.1825011488775, 837.0624900111792, 855.5850412549909, 888.7333799860016, 9023.187913087722, 9559.474228917337, 9712.70861874145, 9776.071273229032, 9907.405555666539, 276884.0, 278406.0, 376810.0, 381341.0, 381530.0, 77909.0, 79400.0, 80155.0, 81596.0, 82442.0, 891.8439273127307, 923.7852224324992, 932.2961164320894, 945.8628498867625, 948.6347756113946, 15621.218939052775, 15641.530754077396, 16249.0, 16365.099919504542, 16497.83502165011, 25846.353841154123, 26140.60789088203, 34749.0, 36358.0, 36759.0, 47813.0, 47871.281930771765, 49918.500814991385, 55735.44264557465, 57703.4229657698, 6961.547499412522, 7237.095363079615, 7299.5255308404785, 7463.664135113824, 7464.290892365217, 10844.3931142287, 11525.94237028843, 11960.58137965167, 11984.161108722306, 12305.200161989442, 10484.895151048391, 10609.681709548726, 10751.6236931707, 11121.443927803739, 11193.832092518456, 9967.0, 10702.73243168927, 10733.355998640107, 10799.56801727922, 10862.565497380016, 10605.46972651349, 10632.202584806017, 10711.517981690688, 10814.459277036274, 11143.888561114285, 7475.925240747741, 7479.813004674927, 7573.46985710995, 7630.961845190738, 7640.197779233323, 9024.639014439612, 10592.629257975934, 10632.36205827622, 10690.558786093925, 10732.73168170802], "value": 8123.89776704869, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.2644226685131, 1349.2376807103988, 1388.1601631013236, 1432.878205352545, 1667.9166041697913, 7405.259474052592, 7784.766457006292, 7833.80415489613, 7844.0, 7850.528968261909, 7567.0, 7614.314711675948, 7645.235476452352, 7653.464257501977, 7656.38748900088, 1892.2809332453717, 2116.6507526258147, 2143.7749036351192, 2155.1487162570775, 2159.0500179920787, 2214.678871563622, 2223.633100538409, 2234.6089434349005, 2235.0, 2237.161064600775, 2093.549886774343, 2108.441263065289, 2128.265748316836, 2130.371540395587, 2130.659094544873, 2041.1223174035158, 2122.7771084036262, 2125.298651445027, 2129.776373480793, 2130.4247853079737, 2131.030381176561, 2155.224119317045, 2156.1914282144194, 2156.7088443060143, 2158.665406861939, 7840.2159784021715, 7878.2515661012685, 7911.683532658687, 7919.881201781978, 7931.0, 4442.489113751928, 4564.269716845289, 4564.726116433025, 4568.908621827578, 4570.291604801228, 2758.6965433802266, 2901.2021694034097, 2905.1575043237435, 2907.200519857035, 2929.868155932988, 834.2825170353491, 854.7649396415973, 878.5168157513342, 878.7583414561046, 882.4969767232616, 10200.591976321013, 10248.538815753309, 10273.486325683685, 10291.742706432398, 10347.482625868677, 3657.104009517685, 3843.673287770515, 3955.6637685796454, 3969.523657161159, 3988.3847041948056, 8196.508209507449, 8199.795005124919, 8216.301614362847, 8218.465799723119, 8220.506769593843, 4463.218936686067, 4467.972366355756, 4477.529859364785, 4479.10417916418, 4484.439445069366, 3697.852085916587, 4031.294523458383, 4120.25835349634, 4168.312228482328, 4175.0, 3372.7976321420792, 3379.3410284994684, 3479.860805567749, 3517.138301116243, 3517.771344862627, 1041.4274445587066, 1042.3797840285017, 1044.38903241603, 1048.208602505119, 1057.2229411382627, 621.9564630475824, 654.9214094308713, 658.1772784019975, 664.9534532582672, 667.7762949412001, 4690.319903614025, 4841.1285968526, 4893.926591101136, 4900.289458028566, 5212.218167274863, 384.1241968312253, 398.36261980831017, 399.12193175015085, 400.0897957147513, 400.0917916329973, 3247.1882029492626, 3455.6889879910927, 3581.068922080278, 3623.8731644392847, 3691.8154092295276, 9464.290178236659, 9508.191803696764, 9619.230461563058, 9634.951825240829, 9702.078302561245, 9462.384944978554, 9462.858057129148, 9472.384295020802, 9487.383320084173, 9498.715038548855, 7020.438364930793, 7192.604406757574, 7206.639668016578, 7207.306283023485, 7218.602976836324, 5056.7471626419265, 5118.718470484159, 5179.559737422362, 5213.348331458567, 5398.055340315351, 8573.61418736146, 8818.91181088181, 8850.247728942855, 8851.159139881702, 8886.955565222133, 8696.391252612257, 8932.19610235082, 8954.149355811187, 8999.595018224065, 9013.233875120426, 6152.938470615416, 6890.8621827564175, 7037.612931288828, 7085.645717713991, 7101.964490177723, 7015.789526314219, 7314.195438501654, 7322.780316590511, 7325.450591205575, 7329.670164842488, 6276.1840960676645, 6314.494840412664, 6351.301356850835, 6388.2972872984865, 6549.508786840911, 6001.0, 6248.593841400295, 6336.239651241881, 6383.808485745435, 6401.711922963571, 5684.147377893349, 5891.263592050994, 5891.823245302477, 5893.675847828411, 5896.115582662635, 4169.937450938178, 4277.006095732937, 4334.26317526015, 4355.041890784022, 4396.736195828261, 4160.625543701081, 4192.727472714264, 4319.676024298127, 4364.607185353333, 4438.911221775482], "value": 3896.879508008432, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.86186045116995, 273.67432755021525, 280.1902501769885, 281.6310633070677, 282.3125688947412, 4853.80584776609, 4890.804367825287, 4891.584215341701, 4894.902101957963, 4901.411830580335, 4816.277558366247, 4819.903601927959, 4825.155597770385, 4836.588889944359, 4847.2002119650215, 3754.1740817020213, 3757.6242375762477, 3763.3978563429855, 3765.435184722286, 3776.7922764247955, 3793.9430908536256, 3822.197338558906, 3832.770033797981, 3840.0975770693863, 3842.51968503937, 3442.6041005284337, 3659.56085269768, 3694.20574576466, 3702.185928894836, 3774.5281839770028, 3646.9817650911577, 3654.817259137059, 3667.771296615637, 3668.303022425744, 3683.3001729671278, 3000.924976875596, 3195.121341631047, 3200.1679563313473, 3201.8707559472923, 3216.678332166788, 4511.954880451219, 4608.493065762764, 4624.982503849148, 4637.884052898671, 4648.418947631546, 4144.0, 4154.667626589866, 4173.791310434466, 4180.519240287375, 4189.790510474494, 4925.804816474985, 5014.272930425104, 5025.522575355372, 5027.145385284511, 5032.6477146599755, 3384.289299247161, 3414.163529935157, 3431.262278610111, 3441.1569165554347, 3442.242706604543, 5166.3800343958965, 5179.766910489037, 5185.714785686823, 5188.792448302063, 5211.1922651989, 3503.7911920387423, 3510.017195185352, 3510.280392519527, 3538.9469157962653, 3551.5027896094566, 3476.956912926112, 3568.5360903082433, 3587.6412358764437, 3595.227026189382, 3600.801955892451, 4124.381342798604, 4127.690423218271, 4135.359019352005, 4139.3583994480905, 4165.937510937339, 4963.726995015309, 4970.403551573834, 4974.452810190876, 4998.000799680084, 5005.799768009311, 1224.718314787586, 1237.3936770982334, 1241.205628397826, 1244.296972210698, 1244.595506460404, 766.9309762121436, 778.746907255133, 780.7469012235281, 784.5175217241356, 789.3211837819471, 4014.8996275092773, 4050.6151915567975, 4051.8987025324604, 4056.6146216109423, 4056.7160298778804, 4570.657200709894, 4579.6794224403975, 4587.632989360844, 4623.42207224097, 4627.380416854074, 3963.4253033310583, 3977.880663580097, 4000.119973605802, 4013.8394464221105, 4014.979925100356, 2588.967660386625, 2711.5797051457057, 2737.1241202815304, 2739.76711979484, 2742.506348857225, 4612.331211974312, 4846.642939976812, 4859.465458799529, 4861.756912154449, 4883.679098213178, 5159.406668233201, 5237.947620523746, 5294.544000399921, 5300.257963885059, 5307.9734601326745, 4851.277796382304, 4871.220604703301, 4877.3418486924, 4889.90220195587, 4890.217565189624, 4981.676191047571, 5132.640715149903, 5140.845774626767, 5146.511081447256, 5172.275881376686, 5066.341375621146, 5069.986002799528, 5073.264376665289, 5078.593712502917, 5085.796568137233, 4860.635452341159, 4868.586170175433, 4873.244647079709, 4873.975630121969, 4874.562004208838, 3345.514900339486, 3509.350770107538, 3509.3683137035578, 3530.417481115587, 3567.661072198137, 5148.9742551287, 5152.793888244429, 5157.406898206755, 5172.586193104467, 5190.97404512975, 5003.2745251938995, 5061.9493805061475, 5062.114130027304, 5068.87107414894, 5072.112380333353, 4828.734419606955, 4991.376077990251, 5005.224190250371, 5019.494151754386, 5031.421386540448, 4937.481564435826, 4937.580305674058, 4949.109160351171, 4951.529604687549, 4958.256261560795, 5052.823151189691, 5053.595712343077, 5054.545090941833, 5059.6458247923765, 5075.264086707481, 4673.602743766818, 4929.728864912464, 4930.704157750546, 4960.801567937242, 4963.932754457809], "value": 3704.532595188406, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.976331065102, 564.8559617297589, 570.0992431957508, 581.2298704216913, 596.0909612840418, 12557.937210313947, 13496.932515337434, 13501.662458438532, 13505.05464617477, 13547.458101675933, 12338.938305308484, 12759.0, 12829.42267597958, 12832.358382080905, 12905.354732263397, 616.0512810272198, 748.5396481164086, 761.3908872901684, 762.7254188492166, 763.1452772894338, 743.379278302618, 763.1147868472573, 765.8430021845509, 770.183605378298, 770.445279398832, 817.3624572833199, 819.8565251081067, 820.9179082091773, 821.0516853034725, 821.2608652212996, 637.4199478474566, 814.9796255093611, 816.0574536410426, 816.0574536410426, 816.2939057715093, 551.7406818795159, 567.2852206220192, 576.0638961687258, 578.1154833105355, 579.4813641790574, 12410.81383779244, 12466.81299780504, 12521.56174533887, 12560.120791544596, 12575.496980120784, 10892.237543371966, 11547.595834145764, 11608.883911160865, 11882.465289061927, 12011.339376334297, 11198.160137989684, 11306.321620702784, 11473.942630286878, 11508.136889733303, 11586.304821710724, 4096.0, 4112.979435102835, 4139.648129908961, 4175.039740859589, 4187.937180942288, 66484.66757666181, 67026.32973670299, 67369.66315168442, 67502.32497675059, 67556.0, 9380.812383752287, 9537.284703647254, 9726.854097188549, 10013.899861001297, 10231.846522302172, 17758.11209439523, 17799.555011124565, 17821.376251831123, 17835.821641783674, 17856.19647115883, 6472.15861937945, 6513.185851768529, 6630.336966303427, 6734.663266836638, 7035.683394247271, 3597.5862775780597, 3606.891793246207, 3614.0964758810296, 3671.1372827385544, 3688.6495782900583, 2794.944101117966, 2933.6479622445017, 2938.676745557987, 2939.2211064068038, 2949.6018037564654, 520.123591747906, 540.3030091182409, 551.8841043380854, 552.326162082259, 556.5130510803048, 395.29793110945144, 467.37371921625186, 492.7191500844556, 529.4335061484252, 549.7031602934451, 7628.80927976805, 7823.048209038211, 8043.678252869937, 8175.836483270301, 8514.318854491627, 428.47083851443443, 447.75821056629707, 496.4390239029873, 521.7260938007577, 563.0119140907763, 3413.3855905936675, 3714.220013797079, 3863.671587915059, 3899.980500097481, 3937.0, 53806.73096634492, 53872.0, 53981.0, 55047.449525505035, 66258.0, 80529.59735201404, 80921.59539202384, 81260.18739812645, 82108.17891821005, 83933.0, 61934.38065619286, 64361.67819160874, 71941.0, 74641.0, 75568.86646700304, 7749.612519373896, 8047.879281810895, 8409.62156702962, 8489.0, 8624.396292259482, 31776.205594860312, 41041.1791764169, 43316.35025474684, 44382.0, 44422.555774443135, 60858.69570652118, 64404.67797660982, 64459.355406445335, 65665.0, 67542.3245767536, 9526.904730952601, 9779.706608801747, 9817.312788104764, 9864.852027219455, 10177.287589868638, 11931.105167112675, 12024.819627705416, 12055.81916271239, 12096.213746106476, 12870.806937896126, 10443.68668939933, 10572.41851698164, 10969.83545246806, 10987.890121099006, 11014.614488492864, 10036.64871729486, 10200.2349823765, 10244.129249013917, 10530.21023423231, 11372.829407558727, 10444.373337599769, 10977.0, 11044.889551104387, 11180.161487888277, 11187.10503159729, 9347.953260233655, 9370.797862502443, 9486.952565237405, 10150.23873209527, 10324.122449591869, 8579.871301930601, 8751.474911505331, 9138.17756401927, 9650.806983860424, 9771.218302535923], "value": 6236.5660961996955, "unit": "score"}], "name": "dromaeo_css", "value": 6273.739352114597, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 132.0, 116.0, 114.0, 115.0, 113.0, 115.0, 113.0, 114.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 127.0, 151.0, 184.0, 123.0, 156.0, 148.0, 124.0, 198.0, 122.0], "value": 149.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 163.0, 160.0, 161.0, 160.0, 163.0, 161.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 174.0, 78.0, 78.0, 76.0, 76.0, 158.0, 77.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 150.0, 82.0, 83.0, 83.0, 144.0, 85.0, 84.0, 135.0], "value": 101.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 65.0, 66.0, 65.0, 65.0, 66.0, 66.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 66.0, 66.0, 65.0, 65.0, 67.0, 66.0, 65.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 81.0, 81.0, 82.0, 82.0, 81.0, 82.0, 82.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 147.0, 132.0, 134.0, 142.0, 132.0, 136.0, 133.0, 172.0, 138.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 174.0, 173.0, 175.0, 173.0, 171.0, 171.0, 172.0, 168.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 71.0, 71.0, 69.0, 69.0, 69.0, 69.0, 68.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1575.9, "alertThreshold": 2.0}]} 19:04:34 INFO - Return code: 0 19:04:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:04:34 INFO - # TBPL SUCCESS # 19:04:34 INFO - Running post-action listener: _resource_record_post_action 19:04:34 INFO - [mozharness: 2016-08-30 02:04:34.587471Z] Finished run-tests step (success) 19:04:34 INFO - Running post-run listener: _resource_record_post_run 19:04:34 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 420331520; Read time: 12; Write time: 273732 19:04:34 INFO - TinderboxPrint: CPU usage
13.0% 19:04:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:04:34 INFO - TinderboxPrint: I/O write bytes / time
420,331,520 / 273,732 19:04:34 INFO - TinderboxPrint: CPU idle
8,332.5 (87.0%) 19:04:34 INFO - TinderboxPrint: CPU user
1,202.0 (12.5%) 19:04:34 INFO - TinderboxPrint: Swap in / out
0 / 0 19:04:34 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 113995776; Read time: 0; Write time: 103148 19:04:34 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 294576128; Read time: 0; Write time: 167508 19:04:35 INFO - Running post-run listener: _upload_blobber_files 19:04:35 INFO - Blob upload gear active. 19:04:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:04:35 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 19:04:35 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'] 19:04:35 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 19:04:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:04:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:04:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:04:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:36 INFO - (blobuploader) - INFO - Done attempting. 19:04:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:04:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:04:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:40 INFO - (blobuploader) - INFO - Done attempting. 19:04:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:04:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:04:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:41 INFO - (blobuploader) - INFO - Done attempting. 19:04:41 INFO - (blobuploader) - INFO - Iteration through files over. 19:04:41 INFO - Return code: 0 19:04:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:04:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:04:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dad115e7d5c35b86a327dc94383f8790e59875236a1b660df5ca5205ba6b0dcf131f97f64cbca80e3540c9ac92eab9e9426ac5fb863e9b9f7f643157caa7e073", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2396b51ac639ed55d2ce80ee5e4ab0448fd3fc4ad77b6694aa75defb3f620db61302ef9ecefa185e469e8a1ae194e776790487f8b07c9b81f3be4e1a7921ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4647219cb9ef370dcccf0465acd6f02815cd674e376f8c3b13f5348e1236aa5542298c982ddfb0d2a70711e2c9878993e3eef30cb9b0a0a64e0cb910a67c23f"} 19:04:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:04:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:04:41 INFO - Contents: 19:04:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dad115e7d5c35b86a327dc94383f8790e59875236a1b660df5ca5205ba6b0dcf131f97f64cbca80e3540c9ac92eab9e9426ac5fb863e9b9f7f643157caa7e073", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2396b51ac639ed55d2ce80ee5e4ab0448fd3fc4ad77b6694aa75defb3f620db61302ef9ecefa185e469e8a1ae194e776790487f8b07c9b81f3be4e1a7921ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4647219cb9ef370dcccf0465acd6f02815cd674e376f8c3b13f5348e1236aa5542298c982ddfb0d2a70711e2c9878993e3eef30cb9b0a0a64e0cb910a67c23f"} 19:04:41 INFO - Running post-run listener: copy_logs_to_upload_dir 19:04:41 INFO - Copying logs to upload dir... 19:04:41 INFO - mkdir: /builds/slave/test/build/upload/logs 19:04:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.031667 ========= master_lag: 33.80 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-08-29 19:05:15.394230) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 19:05:15.397696) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340 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/dad115e7d5c35b86a327dc94383f8790e59875236a1b660df5ca5205ba6b0dcf131f97f64cbca80e3540c9ac92eab9e9426ac5fb863e9b9f7f643157caa7e073", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2396b51ac639ed55d2ce80ee5e4ab0448fd3fc4ad77b6694aa75defb3f620db61302ef9ecefa185e469e8a1ae194e776790487f8b07c9b81f3be4e1a7921ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4647219cb9ef370dcccf0465acd6f02815cd674e376f8c3b13f5348e1236aa5542298c982ddfb0d2a70711e2c9878993e3eef30cb9b0a0a64e0cb910a67c23f"} build_url:https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010133 build_url: 'https://queue.taskcluster.net/v1/task/H1ZrjPmkSvG9XeEowLypcA/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/dad115e7d5c35b86a327dc94383f8790e59875236a1b660df5ca5205ba6b0dcf131f97f64cbca80e3540c9ac92eab9e9426ac5fb863e9b9f7f643157caa7e073", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2396b51ac639ed55d2ce80ee5e4ab0448fd3fc4ad77b6694aa75defb3f620db61302ef9ecefa185e469e8a1ae194e776790487f8b07c9b81f3be4e1a7921ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4647219cb9ef370dcccf0465acd6f02815cd674e376f8c3b13f5348e1236aa5542298c982ddfb0d2a70711e2c9878993e3eef30cb9b0a0a64e0cb910a67c23f"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 19:05:15.513694) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-29 19:05:15.514118) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1472521366.541736-1982539340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004531 ========= master_lag: 3.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-29 19:05:18.532029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 19:05:18.532375) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 19:05:18.537835) ========= ========= Total master_lag: 37.03 =========