builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1468907951.04 results: success (0) buildid: 20160718193132 builduid: 63fddb1cd36a48a0a6c1a63e55f7bc6d revision: fc189ba3703da6ae63a9fd39977c889e1970a486 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.042517) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.043048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.043347) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.057869) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.058228) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 22:59:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 78.1K=0.2s 2016-07-18 22:59:11 (78.1 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.257376 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.329889) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.330226) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014793 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:11.381788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 22:59:11.382273) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev fc189ba3703da6ae63a9fd39977c889e1970a486 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev fc189ba3703da6ae63a9fd39977c889e1970a486 --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=a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 22:59:11,820 truncating revision to first 12 chars 2016-07-18 22:59:11,820 Setting DEBUG logging. 2016-07-18 22:59:11,820 attempt 1/10 2016-07-18 22:59:11,820 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/fc189ba3703d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 22:59:12,814 unpacking tar archive at: mozilla-inbound-fc189ba3703d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.328727 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 22:59:12.724605) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:12.724935) ========= script_repo_revision: fc189ba3703da6ae63a9fd39977c889e1970a486 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:12.725343) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:12.725615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 22:59:12.734665) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-07-18 22:59:12.735065) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:59:13 INFO - MultiFileLogger online at 20160718 22:59:13 in /builds/slave/test 22:59:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:59:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:59:13 INFO - {'append_to_log': False, 22:59:13 INFO - 'base_work_dir': '/builds/slave/test', 22:59:13 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:59:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:59:13 INFO - 'branch': 'Mozilla-Inbound', 22:59:13 INFO - 'buildbot_json_path': 'buildprops.json', 22:59:13 INFO - 'config_files': ('talos/linux_config.py',), 22:59:13 INFO - 'default_actions': ('clobber', 22:59:13 INFO - 'read-buildbot-config', 22:59:13 INFO - 'download-and-extract', 22:59:13 INFO - 'populate-webroot', 22:59:13 INFO - 'create-virtualenv', 22:59:13 INFO - 'install', 22:59:13 INFO - 'run-tests'), 22:59:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:59:13 INFO - 'download_minidump_stackwalk': True, 22:59:13 INFO - 'download_symbols': 'ondemand', 22:59:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:59:13 INFO - 'tooltool.py': '/tools/tooltool.py', 22:59:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:59:13 INFO - '/tools/misc-python/virtualenv.py')}, 22:59:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:59:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:59:13 INFO - 'installer_path': 'installer.exe', 22:59:13 INFO - 'log_level': 'info', 22:59:13 INFO - 'log_name': 'talos', 22:59:13 INFO - 'log_to_console': True, 22:59:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:59:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:59:13 INFO - 'opt_config_files': (), 22:59:13 INFO - 'pip_index': False, 22:59:13 INFO - 'sps_profile': False, 22:59:13 INFO - 'sps_profile_interval': 0, 22:59:13 INFO - 'suite': 'dromaeojs', 22:59:13 INFO - 'system_bits': '32', 22:59:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:59:13 INFO - 'title': 'talos-linux64-ix-002', 22:59:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:59:13 INFO - 'use_talos_json': True, 22:59:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:59:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:59:13 INFO - 'work_dir': 'build'} 22:59:13 INFO - ##### 22:59:13 INFO - ##### Running clobber step. 22:59:13 INFO - ##### 22:59:13 INFO - Running pre-action listener: _resource_record_pre_action 22:59:13 INFO - Running main action method: clobber 22:59:13 INFO - rmtree: /builds/slave/test/build 22:59:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:59:13 INFO - Running post-action listener: _resource_record_post_action 22:59:13 INFO - ##### 22:59:13 INFO - ##### Finished clobber step (success) 22:59:13 INFO - ##### 22:59:13 INFO - ##### 22:59:13 INFO - ##### Running read-buildbot-config step. 22:59:13 INFO - ##### 22:59:13 INFO - Running pre-action listener: _resource_record_pre_action 22:59:13 INFO - Running main action method: read_buildbot_config 22:59:13 INFO - Using buildbot properties: 22:59:13 INFO - { 22:59:13 INFO - "project": "", 22:59:13 INFO - "product": "firefox", 22:59:13 INFO - "script_repo_revision": "production", 22:59:13 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:59:13 INFO - "repository": "", 22:59:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:59:13 INFO - "buildid": "20160718193132", 22:59:13 INFO - "pgo_build": "True", 22:59:13 INFO - "basedir": "/builds/slave/test", 22:59:13 INFO - "buildnumber": 1261, 22:59:13 INFO - "slavename": "talos-linux64-ix-002", 22:59:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:59:13 INFO - "platform": "ubuntu64_hw", 22:59:13 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:59:13 INFO - "branch": "mozilla-inbound", 22:59:13 INFO - "repo_path": "integration/mozilla-inbound", 22:59:13 INFO - "revision": "fc189ba3703da6ae63a9fd39977c889e1970a486", 22:59:13 INFO - "stage_platform": "linux64-pgo", 22:59:13 INFO - "builduid": "63fddb1cd36a48a0a6c1a63e55f7bc6d", 22:59:13 INFO - "slavebuilddir": "test" 22:59:13 INFO - } 22:59:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:59:13 INFO - Running post-action listener: _resource_record_post_action 22:59:13 INFO - ##### 22:59:13 INFO - ##### Finished read-buildbot-config step (success) 22:59:13 INFO - ##### 22:59:13 INFO - ##### 22:59:13 INFO - ##### Running download-and-extract step. 22:59:13 INFO - ##### 22:59:13 INFO - Running pre-action listener: _resource_record_pre_action 22:59:13 INFO - Running main action method: download_and_extract 22:59:13 INFO - mkdir: /builds/slave/test/build/tests 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:13 INFO - https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:59:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:59:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:59:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:59:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:59:14 INFO - Downloaded 1361 bytes. 22:59:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:59:14 INFO - Using the following test package requirements: 22:59:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:59:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:59:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'jsshell-linux-x86_64.zip'], 22:59:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:59:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:59:14 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:59:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:59:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:59:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:14 INFO - https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:59:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:59:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:59:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:59:15 INFO - Downloaded 24336005 bytes. 22:59:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:59:16 INFO - Return code: 0 22:59:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:16 INFO - https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:59:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:59:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:59:17 INFO - Downloaded 24336005 bytes. 22:59:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:59:19 INFO - Return code: 0 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:19 INFO - https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:59:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:59:20 INFO - Downloaded 14403317 bytes. 22:59:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:59:20 INFO - Return code: 0 22:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:20 INFO - https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:59:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:59:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:59:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:59:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:59:22 INFO - Downloaded 60848221 bytes. 22:59:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:59:22 INFO - mkdir: /builds/slave/test/properties 22:59:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:59:22 INFO - Writing to file /builds/slave/test/properties/build_url 22:59:22 INFO - Contents: 22:59:22 INFO - build_url:https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:59:22 INFO - Running post-action listener: _resource_record_post_action 22:59:22 INFO - Running post-action listener: set_extra_try_arguments 22:59:22 INFO - ##### 22:59:22 INFO - ##### Finished download-and-extract step (success) 22:59:22 INFO - ##### 22:59:22 INFO - ##### 22:59:22 INFO - ##### Running populate-webroot step. 22:59:22 INFO - ##### 22:59:22 INFO - Running pre-action listener: _resource_record_pre_action 22:59:22 INFO - Running main action method: populate_webroot 22:59:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:59:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:59:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:59:22 INFO - '--tppagecycles', 22:59:22 INFO - '7'], 22:59:22 INFO - 'tests': ['tsvgm']}}, 22:59:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:59:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:59:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:59:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:59:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['damp', 'tps']}, 22:59:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'tests': ['damp', 'tps']}, 22:59:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['dromaeo_dom']}, 22:59:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:59:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['basic_compositor_video']}, 22:59:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:59:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['a11yr', 22:59:22 INFO - 'ts_paint', 22:59:22 INFO - 'tpaint', 22:59:22 INFO - 'sessionrestore', 22:59:22 INFO - 'sessionrestore_no_auto_restore', 22:59:22 INFO - 'tabpaint']}, 22:59:22 INFO - 'other-e10s': {'tests': ['a11yr', 22:59:22 INFO - 'ts_paint', 22:59:22 INFO - 'tpaint', 22:59:22 INFO - 'sessionrestore', 22:59:22 INFO - 'sessionrestore_no_auto_restore', 22:59:22 INFO - 'tabpaint']}, 22:59:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:59:22 INFO - 'ts_paint', 22:59:22 INFO - 'tpaint', 22:59:22 INFO - 'sessionrestore', 22:59:22 INFO - 'sessionrestore_no_auto_restore', 22:59:22 INFO - 'tabpaint']}, 22:59:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:59:22 INFO - 'ts_paint', 22:59:22 INFO - 'tpaint', 22:59:22 INFO - 'sessionrestore', 22:59:22 INFO - 'sessionrestore_no_auto_restore', 22:59:22 INFO - 'tabpaint']}, 22:59:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['a11yr', 22:59:22 INFO - 'ts_paint', 22:59:22 INFO - 'tpaint', 22:59:22 INFO - 'sessionrestore', 22:59:22 INFO - 'sessionrestore_no_auto_restore', 22:59:22 INFO - 'tabpaint']}, 22:59:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['a11yr', 22:59:22 INFO - 'ts_paint', 22:59:22 INFO - 'tpaint', 22:59:22 INFO - 'sessionrestore', 22:59:22 INFO - 'sessionrestore_no_auto_restore', 22:59:22 INFO - 'tabpaint']}, 22:59:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['tsvgx', 22:59:22 INFO - 'tsvgr_opacity', 22:59:22 INFO - 'tart', 22:59:22 INFO - 'tscrollx', 22:59:22 INFO - 'cart']}, 22:59:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:59:22 INFO - 'tsvgr_opacity', 22:59:22 INFO - 'tart', 22:59:22 INFO - 'tscrollx', 22:59:22 INFO - 'cart']}, 22:59:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'talos_options': ['--disable-e10s'], 22:59:22 INFO - 'tests': ['tp5o']}, 22:59:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'tests': ['tp5o']}, 22:59:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'talos_options': ['--disable-e10s', 22:59:22 INFO - '--xperf_path', 22:59:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:22 INFO - 'tests': ['tp5n']}, 22:59:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:22 INFO - 'talos_options': ['--xperf_path', 22:59:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:22 INFO - 'tests': ['tp5n']}}, 22:59:22 INFO - 'talos.zip': {'path': '', 22:59:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:59:22 INFO - Running post-action listener: _resource_record_post_action 22:59:22 INFO - ##### 22:59:22 INFO - ##### Finished populate-webroot step (success) 22:59:22 INFO - ##### 22:59:22 INFO - ##### 22:59:22 INFO - ##### Running create-virtualenv step. 22:59:22 INFO - ##### 22:59:22 INFO - Running pre-action listener: _resource_record_pre_action 22:59:22 INFO - Running main action method: create_virtualenv 22:59:22 INFO - Creating virtualenv /builds/slave/test/build/venv 22:59:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:59:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:59:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:59:22 INFO - Using real prefix '/usr' 22:59:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:59:23 INFO - Installing distribute.............................................................................................................................................................................................done. 22:59:25 INFO - Installing pip.................done. 22:59:25 INFO - Return code: 0 22:59:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:25 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:59:25 INFO - Using env: {'DISPLAY': ':0', 22:59:25 INFO - 'HOME': '/home/cltbld', 22:59:25 INFO - 'LANG': 'en_US.UTF-8', 22:59:25 INFO - 'LANGUAGE': 'en_US:en', 22:59:25 INFO - 'LOGNAME': 'cltbld', 22:59:25 INFO - 'MAIL': '/var/mail/cltbld', 22:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:25 INFO - 'MOZ_NO_REMOTE': '1', 22:59:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:25 INFO - 'NO_EM_RESTART': '1', 22:59:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:25 INFO - 'PWD': '/builds/slave/test', 22:59:25 INFO - 'SHELL': '/bin/bash', 22:59:25 INFO - 'SHLVL': '1', 22:59:25 INFO - 'TERM': 'linux', 22:59:25 INFO - 'TMOUT': '86400', 22:59:25 INFO - 'USER': 'cltbld', 22:59:25 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:25 INFO - '_': '/tools/buildbot/bin/python'} 22:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:59:25 INFO - Cleaning up... 22:59:25 INFO - Return code: 0 22:59:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:25 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:59:25 INFO - Using env: {'DISPLAY': ':0', 22:59:25 INFO - 'HOME': '/home/cltbld', 22:59:25 INFO - 'LANG': 'en_US.UTF-8', 22:59:25 INFO - 'LANGUAGE': 'en_US:en', 22:59:25 INFO - 'LOGNAME': 'cltbld', 22:59:25 INFO - 'MAIL': '/var/mail/cltbld', 22:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:25 INFO - 'MOZ_NO_REMOTE': '1', 22:59:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:25 INFO - 'NO_EM_RESTART': '1', 22:59:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:25 INFO - 'PWD': '/builds/slave/test', 22:59:25 INFO - 'SHELL': '/bin/bash', 22:59:25 INFO - 'SHLVL': '1', 22:59:25 INFO - 'TERM': 'linux', 22:59:25 INFO - 'TMOUT': '86400', 22:59:25 INFO - 'USER': 'cltbld', 22:59:25 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:25 INFO - '_': '/tools/buildbot/bin/python'} 22:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:25 INFO - Downloading/unpacking psutil>=3.1.1 22:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:59:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:27 INFO - Installing collected packages: psutil 22:59:27 INFO - Running setup.py install for psutil 22:59:28 INFO - building 'psutil._psutil_linux' extension 22:59:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:59:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:59:28 INFO - building 'psutil._psutil_posix' extension 22:59:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:59:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:59:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:28 INFO - Successfully installed psutil 22:59:28 INFO - Cleaning up... 22:59:28 INFO - Return code: 0 22:59:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:28 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:59:28 INFO - Using env: {'DISPLAY': ':0', 22:59:28 INFO - 'HOME': '/home/cltbld', 22:59:28 INFO - 'LANG': 'en_US.UTF-8', 22:59:28 INFO - 'LANGUAGE': 'en_US:en', 22:59:28 INFO - 'LOGNAME': 'cltbld', 22:59:28 INFO - 'MAIL': '/var/mail/cltbld', 22:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:28 INFO - 'MOZ_NO_REMOTE': '1', 22:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:28 INFO - 'NO_EM_RESTART': '1', 22:59:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:28 INFO - 'PWD': '/builds/slave/test', 22:59:28 INFO - 'SHELL': '/bin/bash', 22:59:28 INFO - 'SHLVL': '1', 22:59:28 INFO - 'TERM': 'linux', 22:59:28 INFO - 'TMOUT': '86400', 22:59:28 INFO - 'USER': 'cltbld', 22:59:28 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:28 INFO - '_': '/tools/buildbot/bin/python'} 22:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:59:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:59:30 INFO - Installing collected packages: mozsystemmonitor 22:59:30 INFO - Running setup.py install for mozsystemmonitor 22:59:30 INFO - Successfully installed mozsystemmonitor 22:59:30 INFO - Cleaning up... 22:59:30 INFO - Return code: 0 22:59:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:30 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:59:30 INFO - Using env: {'DISPLAY': ':0', 22:59:30 INFO - 'HOME': '/home/cltbld', 22:59:30 INFO - 'LANG': 'en_US.UTF-8', 22:59:30 INFO - 'LANGUAGE': 'en_US:en', 22:59:30 INFO - 'LOGNAME': 'cltbld', 22:59:30 INFO - 'MAIL': '/var/mail/cltbld', 22:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:30 INFO - 'MOZ_NO_REMOTE': '1', 22:59:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:30 INFO - 'NO_EM_RESTART': '1', 22:59:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:30 INFO - 'PWD': '/builds/slave/test', 22:59:30 INFO - 'SHELL': '/bin/bash', 22:59:30 INFO - 'SHLVL': '1', 22:59:30 INFO - 'TERM': 'linux', 22:59:30 INFO - 'TMOUT': '86400', 22:59:30 INFO - 'USER': 'cltbld', 22:59:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:30 INFO - '_': '/tools/buildbot/bin/python'} 22:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:31 INFO - Downloading/unpacking jsonschema==2.5.1 22:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:59:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:33 INFO - Downloading functools32-3.2.3-2.tar.gz 22:59:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:59:33 INFO - warning: no files found matching '*.txt' 22:59:33 INFO - no previously-included directories found matching 'build' 22:59:33 INFO - no previously-included directories found matching 'dist' 22:59:33 INFO - no previously-included directories found matching '.git*' 22:59:33 INFO - Installing collected packages: jsonschema, functools32 22:59:33 INFO - Running setup.py install for functools32 22:59:33 INFO - warning: no files found matching '*.txt' 22:59:33 INFO - no previously-included directories found matching 'build' 22:59:33 INFO - no previously-included directories found matching 'dist' 22:59:33 INFO - no previously-included directories found matching '.git*' 22:59:33 INFO - Successfully installed jsonschema functools32 22:59:33 INFO - Cleaning up... 22:59:33 INFO - Return code: 0 22:59:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:59:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:33 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:59:33 INFO - Using env: {'DISPLAY': ':0', 22:59:33 INFO - 'HOME': '/home/cltbld', 22:59:33 INFO - 'LANG': 'en_US.UTF-8', 22:59:33 INFO - 'LANGUAGE': 'en_US:en', 22:59:33 INFO - 'LOGNAME': 'cltbld', 22:59:33 INFO - 'MAIL': '/var/mail/cltbld', 22:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:33 INFO - 'MOZ_NO_REMOTE': '1', 22:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:33 INFO - 'NO_EM_RESTART': '1', 22:59:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:33 INFO - 'PWD': '/builds/slave/test', 22:59:33 INFO - 'SHELL': '/bin/bash', 22:59:33 INFO - 'SHLVL': '1', 22:59:33 INFO - 'TERM': 'linux', 22:59:33 INFO - 'TMOUT': '86400', 22:59:33 INFO - 'USER': 'cltbld', 22:59:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:33 INFO - '_': '/tools/buildbot/bin/python'} 22:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:33 INFO - Downloading/unpacking blobuploader==1.2.4 22:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:35 INFO - Downloading blobuploader-1.2.4.tar.gz 22:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:59:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:59:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:36 INFO - Downloading docopt-0.6.1.tar.gz 22:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:59:36 INFO - Installing collected packages: blobuploader, requests, docopt 22:59:36 INFO - Running setup.py install for blobuploader 22:59:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:59:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:59:36 INFO - Running setup.py install for requests 22:59:36 INFO - Running setup.py install for docopt 22:59:36 INFO - Successfully installed blobuploader requests docopt 22:59:36 INFO - Cleaning up... 22:59:36 INFO - Return code: 0 22:59:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:36 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:59:36 INFO - Using env: {'DISPLAY': ':0', 22:59:36 INFO - 'HOME': '/home/cltbld', 22:59:36 INFO - 'LANG': 'en_US.UTF-8', 22:59:36 INFO - 'LANGUAGE': 'en_US:en', 22:59:36 INFO - 'LOGNAME': 'cltbld', 22:59:36 INFO - 'MAIL': '/var/mail/cltbld', 22:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:36 INFO - 'MOZ_NO_REMOTE': '1', 22:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:36 INFO - 'NO_EM_RESTART': '1', 22:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:36 INFO - 'PWD': '/builds/slave/test', 22:59:36 INFO - 'SHELL': '/bin/bash', 22:59:36 INFO - 'SHLVL': '1', 22:59:36 INFO - 'TERM': 'linux', 22:59:36 INFO - 'TMOUT': '86400', 22:59:36 INFO - 'USER': 'cltbld', 22:59:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:36 INFO - '_': '/tools/buildbot/bin/python'} 22:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:37 INFO - Running setup.py (path:/tmp/pip-fTdL5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:37 INFO - Running setup.py (path:/tmp/pip-dYdB1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:37 INFO - Running setup.py (path:/tmp/pip-0lp8P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:37 INFO - Running setup.py (path:/tmp/pip-KVDp6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:37 INFO - Running setup.py (path:/tmp/pip-E0FKLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:37 INFO - Running setup.py (path:/tmp/pip-64JUT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:37 INFO - Running setup.py (path:/tmp/pip-8Q6NIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:37 INFO - Running setup.py (path:/tmp/pip-V328pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:37 INFO - Running setup.py (path:/tmp/pip-Tehz6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:37 INFO - Running setup.py (path:/tmp/pip-CUbuU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:37 INFO - Running setup.py (path:/tmp/pip-QK03pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:37 INFO - Running setup.py (path:/tmp/pip-5621_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:37 INFO - Running setup.py (path:/tmp/pip-cecsf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:38 INFO - Running setup.py (path:/tmp/pip-W0LPiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:38 INFO - Running setup.py (path:/tmp/pip-ZOfjpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:38 INFO - Running setup.py (path:/tmp/pip-hyRA0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:38 INFO - Running setup.py (path:/tmp/pip-5oN07x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:59:38 INFO - Running setup.py install for manifestparser 22:59:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:59:38 INFO - Running setup.py install for mozcrash 22:59:38 INFO - Running setup.py install for mozdebug 22:59:38 INFO - Running setup.py install for mozdevice 22:59:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:59:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:59:38 INFO - Running setup.py install for mozfile 22:59:38 INFO - Running setup.py install for mozhttpd 22:59:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:59:38 INFO - Running setup.py install for mozinfo 22:59:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Running setup.py install for mozInstall 22:59:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Running setup.py install for mozleak 22:59:39 INFO - Running setup.py install for mozlog 22:59:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Running setup.py install for moznetwork 22:59:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Running setup.py install for mozprocess 22:59:39 INFO - Running setup.py install for mozprofile 22:59:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Running setup.py install for mozrunner 22:59:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:59:39 INFO - Running setup.py install for mozscreenshot 22:59:39 INFO - Running setup.py install for moztest 22:59:40 INFO - Running setup.py install for mozversion 22:59:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:59:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:59:40 INFO - Cleaning up... 22:59:40 INFO - Return code: 0 22:59:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:40 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:59:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:59:40 INFO - Using env: {'DISPLAY': ':0', 22:59:40 INFO - 'HOME': '/home/cltbld', 22:59:40 INFO - 'LANG': 'en_US.UTF-8', 22:59:40 INFO - 'LANGUAGE': 'en_US:en', 22:59:40 INFO - 'LOGNAME': 'cltbld', 22:59:40 INFO - 'MAIL': '/var/mail/cltbld', 22:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:40 INFO - 'MOZ_NO_REMOTE': '1', 22:59:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:40 INFO - 'NO_EM_RESTART': '1', 22:59:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:40 INFO - 'PWD': '/builds/slave/test', 22:59:40 INFO - 'SHELL': '/bin/bash', 22:59:40 INFO - 'SHLVL': '1', 22:59:40 INFO - 'TERM': 'linux', 22:59:40 INFO - 'TMOUT': '86400', 22:59:40 INFO - 'USER': 'cltbld', 22:59:40 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:40 INFO - '_': '/tools/buildbot/bin/python'} 22:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:40 INFO - Running setup.py (path:/tmp/pip-GSz1aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:40 INFO - Running setup.py (path:/tmp/pip-8gXvAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:40 INFO - Running setup.py (path:/tmp/pip-N8AXRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:40 INFO - Running setup.py (path:/tmp/pip-m6B_WY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:40 INFO - Running setup.py (path:/tmp/pip-a_I_3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:40 INFO - Running setup.py (path:/tmp/pip-UQFpE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:40 INFO - Running setup.py (path:/tmp/pip-0gq6LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:40 INFO - Running setup.py (path:/tmp/pip-0ywb1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:40 INFO - Running setup.py (path:/tmp/pip-hRDBCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:41 INFO - Running setup.py (path:/tmp/pip-NWucET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:41 INFO - Running setup.py (path:/tmp/pip-az6G5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:41 INFO - Running setup.py (path:/tmp/pip-dlzgvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:41 INFO - Running setup.py (path:/tmp/pip-QYXYGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:41 INFO - Running setup.py (path:/tmp/pip-T3NiOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:41 INFO - Running setup.py (path:/tmp/pip-oOzm39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:41 INFO - Running setup.py (path:/tmp/pip-hU5AzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:41 INFO - Running setup.py (path:/tmp/pip-unsB9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:59:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:43 INFO - Downloading blessings-1.6.tar.gz 22:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:59:43 INFO - Installing collected packages: blessings 22:59:43 INFO - Running setup.py install for blessings 22:59:43 INFO - Successfully installed blessings 22:59:43 INFO - Cleaning up... 22:59:43 INFO - Return code: 0 22:59:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:59:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:59:43 INFO - Reading from file tmpfile_stdout 22:59:43 INFO - Current package versions: 22:59:43 INFO - argparse == 1.2.1 22:59:43 INFO - blessings == 1.6 22:59:43 INFO - blobuploader == 1.2.4 22:59:43 INFO - docopt == 0.6.1 22:59:43 INFO - functools32 == 3.2.3-2 22:59:43 INFO - jsonschema == 2.5.1 22:59:43 INFO - manifestparser == 1.1 22:59:43 INFO - mozInstall == 1.12 22:59:43 INFO - mozcrash == 0.17 22:59:43 INFO - mozdebug == 0.1 22:59:43 INFO - mozdevice == 0.48 22:59:43 INFO - mozfile == 1.2 22:59:43 INFO - mozhttpd == 0.7 22:59:43 INFO - mozinfo == 0.9 22:59:43 INFO - mozleak == 0.1 22:59:43 INFO - mozlog == 3.2 22:59:43 INFO - moznetwork == 0.27 22:59:43 INFO - mozprocess == 0.23 22:59:43 INFO - mozprofile == 0.28 22:59:43 INFO - mozrunner == 6.12 22:59:43 INFO - mozscreenshot == 0.1 22:59:43 INFO - mozsystemmonitor == 0.3 22:59:43 INFO - moztest == 0.7 22:59:43 INFO - mozversion == 1.4 22:59:43 INFO - psutil == 3.1.1 22:59:43 INFO - requests == 1.2.3 22:59:43 INFO - wsgiref == 0.1.2 22:59:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:43 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:59:43 INFO - Using env: {'DISPLAY': ':0', 22:59:43 INFO - 'HOME': '/home/cltbld', 22:59:43 INFO - 'LANG': 'en_US.UTF-8', 22:59:43 INFO - 'LANGUAGE': 'en_US:en', 22:59:43 INFO - 'LOGNAME': 'cltbld', 22:59:43 INFO - 'MAIL': '/var/mail/cltbld', 22:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:43 INFO - 'MOZ_NO_REMOTE': '1', 22:59:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:43 INFO - 'NO_EM_RESTART': '1', 22:59:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:43 INFO - 'PWD': '/builds/slave/test', 22:59:43 INFO - 'SHELL': '/bin/bash', 22:59:43 INFO - 'SHLVL': '1', 22:59:43 INFO - 'TERM': 'linux', 22:59:43 INFO - 'TMOUT': '86400', 22:59:43 INFO - 'USER': 'cltbld', 22:59:43 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:43 INFO - '_': '/tools/buildbot/bin/python'} 22:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:59:44 INFO - Cleaning up... 22:59:44 INFO - Return code: 0 22:59:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:59:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:44 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 0x2008030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:59:44 INFO - Using env: {'DISPLAY': ':0', 22:59:44 INFO - 'HOME': '/home/cltbld', 22:59:44 INFO - 'LANG': 'en_US.UTF-8', 22:59:44 INFO - 'LANGUAGE': 'en_US:en', 22:59:44 INFO - 'LOGNAME': 'cltbld', 22:59:44 INFO - 'MAIL': '/var/mail/cltbld', 22:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:44 INFO - 'MOZ_NO_REMOTE': '1', 22:59:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:44 INFO - 'NO_EM_RESTART': '1', 22:59:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:44 INFO - 'PWD': '/builds/slave/test', 22:59:44 INFO - 'SHELL': '/bin/bash', 22:59:44 INFO - 'SHLVL': '1', 22:59:44 INFO - 'TERM': 'linux', 22:59:44 INFO - 'TMOUT': '86400', 22:59:44 INFO - 'USER': 'cltbld', 22:59:44 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 22:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:44 INFO - '_': '/tools/buildbot/bin/python'} 22:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:59:44 INFO - Cleaning up... 22:59:44 INFO - Return code: 0 22:59:44 INFO - Running post-action listener: _resource_record_post_action 22:59:44 INFO - Running post-action listener: _start_resource_monitoring 22:59:44 INFO - Starting resource monitoring. 22:59:44 INFO - ##### 22:59:44 INFO - ##### Finished create-virtualenv step (success) 22:59:44 INFO - ##### 22:59:44 INFO - ##### 22:59:44 INFO - ##### Running install step. 22:59:44 INFO - ##### 22:59:44 INFO - Running pre-action listener: _resource_record_pre_action 22:59:44 INFO - Running main action method: install 22:59:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:59:44 INFO - Reading from file tmpfile_stdout 22:59:44 INFO - Detecting whether we're running mozinstall >=1.0... 22:59:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:59:44 INFO - Reading from file tmpfile_stdout 22:59:44 INFO - Output received: 22:59:44 INFO - Usage: mozinstall [options] installer 22:59:44 INFO - Options: 22:59:44 INFO - -h, --help show this help message and exit 22:59:44 INFO - -d DEST, --destination=DEST 22:59:44 INFO - Directory to install application into. [default: 22:59:44 INFO - "/builds/slave/test"] 22:59:44 INFO - --app=APP Application being installed. [default: firefox] 22:59:44 INFO - mkdir: /builds/slave/test/build/application 22:59:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:00:01 INFO - Reading from file tmpfile_stdout 23:00:01 INFO - Output received: 23:00:01 INFO - /builds/slave/test/build/application/firefox/firefox 23:00:01 INFO - Running post-action listener: _resource_record_post_action 23:00:01 INFO - ##### 23:00:01 INFO - ##### Finished install step (success) 23:00:01 INFO - ##### 23:00:01 INFO - ##### 23:00:01 INFO - ##### Running run-tests step. 23:00:01 INFO - ##### 23:00:01 INFO - Running pre-action listener: _resource_record_pre_action 23:00:01 INFO - Running main action method: run_tests 23:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:00:01 INFO - Python 2.7.3 23:00:01 INFO - Return code: 0 23:00:01 INFO - grabbing minidump binary from tooltool 23:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:01 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 0x22c73b0>, '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 0x22c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:00:01 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 23:00:01 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 23:00:01 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 23:00:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:00:01 INFO - Return code: 0 23:00:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:00:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:00:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:00:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:00:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 23:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 23:00:01 INFO - Using env: {'DISPLAY': ':0', 23:00:01 INFO - 'HOME': '/home/cltbld', 23:00:01 INFO - 'LANG': 'en_US.UTF-8', 23:00:01 INFO - 'LANGUAGE': 'en_US:en', 23:00:01 INFO - 'LOGNAME': 'cltbld', 23:00:01 INFO - 'MAIL': '/var/mail/cltbld', 23:00:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:00:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:01 INFO - 'MOZ_NO_REMOTE': '1', 23:00:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:01 INFO - 'NO_EM_RESTART': '1', 23:00:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:02 INFO - 'PWD': '/builds/slave/test', 23:00:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:00:02 INFO - 'SHELL': '/bin/bash', 23:00:02 INFO - 'SHLVL': '1', 23:00:02 INFO - 'TERM': 'linux', 23:00:02 INFO - 'TMOUT': '86400', 23:00:02 INFO - 'USER': 'cltbld', 23:00:02 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841', 23:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:02 INFO - '_': '/tools/buildbot/bin/python'} 23:00:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 23:00:02 INFO - mozversion application_buildid: 20160718193132 23:00:02 INFO - mozversion application_changeset: fc189ba3703da6ae63a9fd39977c889e1970a486 23:00:02 INFO - mozversion application_display_name: Nightly 23:00:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:00:02 INFO - mozversion application_name: Firefox 23:00:02 INFO - mozversion application_remotingname: firefox 23:00:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:00:02 INFO - mozversion application_vendor: Mozilla 23:00:02 INFO - mozversion application_version: 50.0a1 23:00:02 INFO - mozversion platform_buildid: 20160718193132 23:00:02 INFO - mozversion platform_changeset: fc189ba3703da6ae63a9fd39977c889e1970a486 23:00:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:00:02 INFO - mozversion platform_version: 50.0a1 23:00:02 INFO - using testdate: 1468908002 23:00:02 INFO - actual date: 1468908002 23:00:02 INFO - starting webserver on 'localhost:34688' 23:00:02 INFO - SUITE-START | Running 2 tests 23:00:02 INFO - TEST-START | dromaeo_css 23:00:02 INFO - operating with platform_type : linux_ 23:00:02 INFO - Initialising browser for dromaeo_css test... 23:00:02 INFO - TEST-INFO | started process 17038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaZg61L/profile http://localhost:34688/getInfo.html) 23:00:06 INFO - PROCESS | 17038 | __metrics Screen width/height:1600/1200 23:00:06 INFO - PROCESS | 17038 | colorDepth:24 23:00:06 INFO - PROCESS | 17038 | Browser inner width/height: 1024/697 23:00:06 INFO - PROCESS | 17038 | __metrics 23:00:07 INFO - PROCESS | 17038 | 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" 23:00:07 INFO - PROCESS | 17038 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:00:08 INFO - TEST-INFO | 17038: exit 0 23:00:08 INFO - Browser initialized. 23:00:08 INFO - Running cycle 1/1 for dromaeo_css test... 23:00:08 INFO - TEST-INFO | started process 17122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaZg61L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:00:19 INFO - PROCESS | 17122 | RSS: Main: 157810688 23:00:19 INFO - PROCESS | 17122 | 23:01:11 INFO - PROCESS | 17122 | 1468908071246 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] 23:03:19 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:34688/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34688/tests/dromaeo/cssquery-ext.html) 23:03:20 INFO - PROCESS | 17122 | RSS: Main: 248102912 23:03:20 INFO - PROCESS | 17122 | 23:06:22 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:34688/tests/dromaeo/cssquery-ext.html (next: http://localhost:34688/tests/dromaeo/cssquery-jquery.html) 23:06:23 INFO - PROCESS | 17122 | RSS: Main: 254267392 23:06:23 INFO - PROCESS | 17122 | 23:09:25 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:34688/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34688/tests/dromaeo/cssquery-mootools.html) 23:09:25 INFO - PROCESS | 17122 | RSS: Main: 253263872 23:09:25 INFO - PROCESS | 17122 | 23:12:28 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:34688/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34688/tests/dromaeo/cssquery-prototype.html) 23:12:28 INFO - PROCESS | 17122 | RSS: Main: 244887552 23:12:28 INFO - PROCESS | 17122 | 23:15:31 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:34688/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34688/tests/dromaeo/cssquery-yui.html) 23:15:31 INFO - PROCESS | 17122 | RSS: Main: 209436672 23:15:31 INFO - PROCESS | 17122 | 23:18:34 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:34688/tests/dromaeo/cssquery-yui.html (next: http://localhost:34688/tests/dromaeo/cssquery-dojo.html) 23:18:35 INFO - PROCESS | 17122 | RSS: Main: 299819008 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | __start_tp_report 23:18:35 INFO - PROCESS | 17122 | _x_x_mozilla_page_load 23:18:35 INFO - PROCESS | 17122 | _x_x_mozilla_page_load_details 23:18:35 INFO - PROCESS | 17122 | |i|pagename|runs| 23:18:35 INFO - PROCESS | 17122 | |0;dojo.html;3052.236940764809;3064.4330798802216;3104.348086901752;3111.6577176510586;3121.843907804609;5367.409584945663;5403.189521571766;5439.048166570854;5498.862528436791;5535.861603459915;5345.091334473139;5352.411234764173;5468.9726551367285;5484.890302193954;5515.889682206353;4816.157172494817;4900.607951363901;4910.975445122769;4930.383702037246;5094.541491265785;5000.999800039988;5006.374203224597;5023.346964894558;5040.36995375578;5062.873428164307;4843.055604157191;4895.045466134123;4901.142300097486;4921.557059864629;4927.95072049282;4914.385701787277;4926.704397736112;4979.7012179268995;5003.4746351633285;5034.572061374787;4850.126977144113;4939.3084968104495;4942.703437793743;4956.033573453178;4968.677035992685;5317.680939143625;5388.838334849961;5412.161115027137;5467.45325467454;5474.589405794582;5783.045797443417;5810.709464526799;5828.03837366834;5833.591648584601;5837.182794408786;5711.828645140652;5754.050581654022;5887.411258874121;5929.051351783737;5930.140129681172;5388.595855310868;5431.076716958166;5471.616986810965;5482.342118945712;5484.91772623411;6011.5190784737115;6012.518998480111;6019.518438524908;6023.0965355196195;6024;5462.863428414322;5577.135543990688;5583.860403489945;5763.423657634287;5774.595778295479;5827.504662103684;5832.708364581754;5836.007878660638;5837.124431335249;5839.9124013139835;5691.203231547586;5725.112607545837;5733.397993210653;5736.110902809987;5753.395893431213;5664.122061080539;5722.856928576819;5729.054705973551;5763.452472015152;5777.855553611109;4396.956030439718;4414.558544145624;4420.668449866273;4420.911581768347;4464.933026004613;3924.234774219057;3939.901502462404;3955.0507878109047;3979.1643754811234;4005.7796821175116;5359.035373632705;5449.836504904859;5462.617616766867;5485.561155107582;5518.44815518453;5860.941390586039;5861.589688721834;5870.559708021916;5871.677057761779;5882.646891271934;5499.532539734168;5510.586705997066;5511;5512.062949298629;5512.972435137799;4902.28916807061;4993.101241776515;5036.748162591855;5044;5096.643234973591;6078.149059131725;6083.270007599028;6086.543509236825;6089.330173680892;6124.234470691164;5911.201987731602;5919.496842768241;5924.644521328734;5929.584929054925;5929.940700592939;5831.970840145772;5869.031609784338;5897.557683173865;5917.497012753792;6073.787417440369;5636.2954630671165;5660.858478537904;5662.546996240373;5663.943360566342;5679.517241034393;5758.193852860519;5794.62334948227;5800.390958949419;5813.476787089182;5817.0983497557945;5583.134614134816;5721.313442386941;5782.248307720138;5786;5804.709764511842;5775.075987841925;5786.739596718242;5788.884222315614;5789.8552536186935;5793.3337666167245;5489.176623506506;5713.342965559014;5726.74229659658;5731.054376027909;5737.053386191398;5701.971490142523;5749.108888122348;5775.595708300379;5803.506701930383;5860.208871802422;5649;5673.149027645886;5683.431656834284;5691.943080569141;5694.515966142924 23:18:35 INFO - PROCESS | 17122 | |1;ext.html;639.9536757401648;646.2955378637286;659.0345144363506;660.752217918281;668.6823758714614;42838.78580607096;44947.32579011314;45080.32379514306;48383.27425088618;48493;45659.315110273375;46329;46331.536684633225;46357.76821115898;46364.304535432;9000.77498062547;9129.817403651923;9139.223166030895;9141.542922853863;9142.497162656051;8911.554422278892;8960.8655870162;8978.685745998891;8991.145841145113;8995.28037756978;8984.472639651274;9012;9019.458832470073;9020.2783777298;9024.548772561378;8975.192232699022;8979.640814367418;8986.236169925563;9014.774630634221;9018.098190180996;8924.598393072327;8961.731148065568;8982.281417486585;9017.504037278013;9021.954890225572;43619;43877.612238775866;43968.78015609901;43977.56022439767;44158.77920610408;3584.6415358464205;3598.8740394086344;4033.8714469644565;4255.893602659959;4371.759553224571;32623.347533049207;33218.50172247418;33519.83240083808;33820.15449613755;35018.124546886276;4408.118376324748;4485.887852803673;4487.932681009787;4579.954200457986;4608.1705293047235;346188;348038;350384;350935;352561;58215.12677309844;58275.70862145747;58634.12048819271;58940;58968;53593.732031340376;59144.704276479206;60278.69860650669;60475.395246047854;66593.66703166453;17171.742423863652;17303.91348043252;17353.56616084583;17359.65280694379;17381;13247;13280.269585172715;13328.600141995756;13337.933310333387;13340.73318533624;15011.774823377658;16684.833151668572;16730.74903876443;17016.57458563546;17565.473035808947;5893.114203454853;5900.672348721503;5941.465268125803;6105.114758360101;6117.327094019654;1007.461948023666;1014.5840205515851;1029.2435060230725;1031.4584842957506;1089.096050278267;32653.836730816194;33724.32551349008;33854.32291354159;34006.65993340084;34051.48922766161;1151.6084531259412;1153.0832987774738;1176.817593273044;1240.299230934537;1273.8280332155225;16951.237194326284;17729.7340539892;17747.46757597248;17766.733498997524;17777;520888;527483;527813;528245;528272;59460;60986.0852087219;61102.69448652817;61109;61122.38877611167;1241.7268200995766;1336.8395792504766;1371.2047012732592;1388.5209602687057;1389.8818600419077;32451.67548324581;34652.30695386028;34942.77646401481;34959.825200873835;35361.29277414489;51721.93112275467;52131.47868521266;52301.215481768566;52329;52373.47626523686;369774;370336;372226;372927;373183;31897.36205275928;42572;43203;46141.30788038249;47236.52763472459;49509;50247.246291306394;50818.237726434876;51119.488805111476;51335.48664513307;42841.78579107209;43098.78450607727;43236.567634323255;45046;45150.32274515951;41893.371599426646;42939.35590966076;43537.346939795294;43890;44374;41848.1630367397;42439;42468.78765606152;43221.7838910816;43406.56593434025;37288.44067338938;38209.31217458481;38483.42274865823;38684.22631547297;38700.47967210022;28487.572686409305;28713.28216794597;28742.712572874003;29228.707712922598;29229.85385073061 23:18:35 INFO - PROCESS | 17122 | |2;jquery.html;394.99012524686896;395.70717668924993;396.9206158768247;400.8436709683224;417.9477565304337;13411.73231598198;18969.905150474246;20517.97410129493;20524.38426847195;21676.89161554194;15619.531414057568;19714.70427943582;19842.30551930683;20221.797782022175;20603.896980515077;5779.682117483537;5951.9107213391835;5956;5957.910631340534;5983.970080149593;5873.353931067579;5894.705264736767;5918.2898052233795;5930.377310382412;5932.11018347247;4242.193983143208;6283;6662.933370666304;6674.098996635465;6731.259561448237;6557.934420655804;6579.04603832444;6594.769183078593;7218.386437152848;7607.80980475487;7170.318819712119;7410.296021877913;7418.332350088464;7419.777406677755;7480.251974802476;27913.720862791317;28246.576301355497;28711.56932646012;28942.276443088882;29015.12954611365;2632.7630513253707;2683.9463210735676;2722.9046983355684;2727.3318037081044;2802.495550800855;16177.272022759003;16366.509004729876;16580.668386632322;16916.577085572837;17267.913660431743;2107.115011695076;2109.2301310021885;2131.5949969505746;2178.59695956247;2194.122351059573;96077;101234.49382753113;101561.4921925393;103047;105703;17883.821161788215;18449.7232541512;18576.907115464335;18889.149988250563;19200;33949.321013580084;34142;34273.82863085669;34290.65709342925;34422;10070.546825392876;10598.470076496144;10651.467426628638;10674.412907290018;10773.83839242412;8845.601947912359;8863.556822158866;8899.777505562413;9053.206056486484;9202.447853128833;7431.340872374807;8753.212210900921;9003.594838232295;9354.485503297385;9458.43249405038;3465.1856813648774;3519.384107781128;3524.718022558189;3631.5460567429072;3673.0413941179195;874.7725591346168;958.4057884111755;1009.6213919780082;1075.983860242097;1096.1176253116319;19647.607047858965;20115.42862829002;20545.69181462279;20669.17323307051;21046.78953210448;1023.6161439460202;1026.609888242463;1054.8154073037188;1055.720234137954;1076.8115579773507;9740.415575065377;11398.1451391146;11407;11457.88542114585;11480.94259528697;479942.80228790455;490729;499648;501305;502429;95832.52083739676;96995;97903.51048244713;99316;100020.49825764746;953.8950715421298;955.7515046088067;962.9614815407306;965.5510187762724;1002.6891663584312;18206.635867282846;18226.36207732723;18541.35105271309;18561.90719046396;18816;43242;43546.78226608847;45059.32411013772;45126;46129;58679.41320586739;58905.01417513542;59318.77152898884;59345;60375.698121509115;8737.868931965899;8783.209511144029;8893.110688931314;9033.322500812597;9221;7355;8968.372213944962;10240.334378265388;12485.813847684456;13652.931735341259;11150.721231969266;11817.113716471471;11846.822297665369;11893.702657433287;12077.456514457046;11633.243839150427;11788.469518871843;11808.11439142085;11895.702607434883;11904.226225295328;11814.527418903148;12001.579944701894;12055.638330850088;12192.268463892195;12212.32832194238;7604.12552556441;7944.6424910877995;7999.800004999689;8324.084350721294;9151.954240228757;11573.421328933688;11673.357965311989;11771.587994420153;11851.407429628312;12024.376709144153 23:18:35 INFO - PROCESS | 17122 | |3;mootools.html;1345.3878485289194;1489.0246888288173;1524.4816762300818;1656.7763351947483;1813.3471950097971;8432.451890627113;8868.068852770455;8888.111188881117;9085.227755640779;9106.27149828012;8930.464172149676;8936.061713520085;9011;9016.954915225415;9019.819603607924;2066.86565373251;2266.841321107523;2408.5423769483828;2422.7819496245334;2423.4547226873988;2371.5019845832308;2387.2838148555365;2388.5700573896693;2388.59393903036;2388.665586817847;2341.637046257833;2361.4332560185603;2371.6323969784626;2372.6678265042906;2378.8691621960784;1935.9612807743906;2227.6769868368997;2295.1622657729977;2325.651152327156;2336.065573770497;2341.8711970841596;2351.0948284910432;2357.669926210332;2358.3160883343808;2359.5516851798275;9049.049849765746;9099.454032757989;9103.271738260923;9110.81778364429;9118.04260552639;5270.7101109439345;5338.679679219259;5382.434844341366;5396.541293990015;5399.379071406799;3337.4493208620547;3406.8807591734417;3536.381133301662;3538.3454060998542;3542.8051457169845;1055.7716491679575;1087.8422628718793;1092.6940456672144;1117.580907159815;1117.6255954255334;11918.165728399015;11924.761504769856;11929.224600401032;11929.28424294545;11944.223625464318;4740.834070807505;4750.881227969259;4817.710937343771;4828.251620998751;4950.603951683856;10387.532561034772;10421.843672344921;10433.634822781167;10434.68695939123;10435.16518678504;5425.810096646677;5452.727363631802;5458;5459.235707001023;5470.316210473691;4438.378626992224;4863.781129849165;4935.457099719028;5020.322256495399;5023.949760502422;3999.9800000999808;4030.0932290234464;4079.286124928126;4126.298529250034;4135.276326642826;1199.4902166579168;1282.2050328796206;1291.2446218962014;1299.818025476434;1309.4827543120605;864.9264812491009;910.6949172027313;947.1333729637443;950.9286803489628;964.8890377606525;5868.941310586925;5963.314218864886;6049.3950604939155;6079.452849243589;6225.252969643671;973.0755782007121;1019.2915923433143;1022.4938655365493;1023.4729114506035;1040.15747244732;4110.34234522475;4196.328587425997;4197.727147735387;4218.810153543098;4237.766922819274;11035;11520.539178432937;11572.76854462906;11588.6523404298;11593.88406115945;11013.229073964905;11440.656780296604;11442.54229830814;11451.312921224753;11462.312261264351;8244.670213191524;8326.833463330699;8401.117882622237;8411.411201215857;8412.705555305534;6040.546958978182;6283.71723272463;6306.148669929501;6444.967775161094;6518.869622607426;10521.684349469528;10595.576176952836;10609.787804244026;10658.573657053632;10703.518341674799;10201.076520510636;10464;10538.789224215627;10631;10641.574337026743;7514.8872766909635;7831.647575859214;8077.83844323122;8161.836763264582;8362.038365588036;8888.822223555622;8921.509316987369;8971.910280897107;8972.685955991508;8975.775605609912;7522.6614802335125;7529.661165247687;7606.277403646644;7672.961635191788;7954.284114429729;7436.516626419266;7585.506942048749;7606.505577137468;7670.8082297943;7753.806154846174;7042.084529011196;7044.260352662896;7065.788026359218;7085.787426377217;7098.219195888551;5096.566791822736;5118.334616499831;5192.948070519246;5266.920996184984;5302;5162.380514338303;5277.445868183939;5301.257823904733;5317.548008419328;5367.033933891937 23:18:35 INFO - PROCESS | 17122 | |4;prototype.html;319.78003915064045;323.4210762734705;331.0435327216153;332.90345799718074;334.85935906919093;5312.389075256347;5368.409474957752;5370.516653501184;5386.596005299609;5418.647787893789;5058.634168774438;5132.871678208037;5306.230596563506;5330.546903513206;5352;4192.769397683126;4214.157168566299;4223.3031549794405;4223.894402639928;4256.914861702772;4209.2856001278615;4210.452641156646;4221.725587836796;4224.577542245782;4227.4927008759;3975.5428125765475;4023.295923213441;4177.603127702863;4196.538380778112;4227.471566054243;4199.118185181115;4206.684498662582;4211.0946146578635;4217.177650358181;4225.450691410112;3736.1967177057063;3741.8129093545213;3745.250949810049;3759.812009399519;3766.098120449183;5121.615878809104;5196.596229469704;5199.792008319662;5231.869203269911;5246.580273578143;4714.57568818808;4774.9283760743265;4805.255185446226;4866.853994380174;4871.853844384674;5474.178873169017;5500.42245564218;5502.284702988586;5556.249906262643;5560.36055853578;3813.036286312551;4000.0599859033105;4012.558618551957;4018.3771515415156;4054.331035379159;5651.7739290428335;5716.542676585905;5750.281214848144;5758.596898217126;5778;4076.30702780528;4091.774952377648;4096.877093687194;4121.443605113332;4150.169966006811;4004.619561141687;4154.833806647761;4191.91616167675;4209.915801683949;4219.556946520633;4736.64475164364;4755.429348478136;4786.497417771154;4791.544803243686;4814.831480898151;5560.582956278215;5561.972190139023;5660.264165658438;5666.17840413146;5675;1378.3590630356932;1412.9222892740997;1434.8852091832837;1445.9372361314427;1450.6590951126477;906.6600024990629;906.8186362727481;912.2154946745793;920.7744102694883;922.7416323429451;4857.222844344888;4858.975705121452;4894.608431325485;4897.95102048975;4912.484189160086;5006.574441172468;5035.496450354935;5061.291419201315;5131.2559678846355;5185.585153187736;4772.284157376352;4784.856454306377;4788.11419887315;4833.4441539223435;5113.181890897438;3050.923726906845;3058.235441139715;3074.738647215012;3076.615423072116;3081.2604974805886;5891.7611307703655;5913.379095195029;5956.285245770535;5956.791512297049;5969.701514924236;5637.182608521824;5875.177475153482;5881.911771323434;5891.263592050994;5939.198208241918;5873.295204575478;5890.440408161217;5899.380565040695;5905.43898329658;5913.319968203626;5639.548836093185;5821.70891455417;5827.9708601458415;5866.560007999503;5878.147668588116;5736.827895163152;5753.769849205984;5753.971230143822;5795.217645617956;5824.126381042708;5962.761489540369;5989.371116032754;6001.159837622649;6023.969880150571;6026.367231440636;3887.2419878124624;3938.8227529760657;4046.10985583183;4150.501939767248;4158.979205103955;5801.94198058014;5802.970985145047;5803.419658034331;5806.854828629318;5808.883822323445;5652.802151924663;5673.34756502991;5681.545476361964;5684.175794509855;5687.061634830372;5448;5569.805056822992;5577.832665019893;5622.269105016322;5661.886762264814;5479.945200547943;5515.255440515479;5551.8889622208135;5554.416786237387;5574.9721251393485;5348.652337598043;5624.565735737525;5664.518515926193;5678.488935995615;5704.857378565569;5500.367457742411;5507.659244821015;5507.697076660822;5508.20131080983;5521.806736764194 23:18:35 INFO - PROCESS | 17122 | |5;yui.html;766.3754040457027;768.3891306411406;804.4811096842536;810.9067457242412;852.6163226548051;15114.92442537787;15848.524544263662;16330.591735206612;16337.673246535092;16353.83646163538;14868.182249976247;15088.773668394982;15102.320395582197;15119.773203401957;15159.62100947474;671.2381447057601;687.6802286936573;696.9756058537952;808.5148910653605;832.2135581875131;697.3897839390534;801.1867954026668;835.6573804740058;838.8490071787076;839.95380254086;873.2097451806126;873.4060838629729;876.583622779178;876.588003638289;877.6357811508217;700.1178515070997;868.0494858130369;871.2811930157582;871.3813192633238;871.9651213951435;615.0988801405962;631.207834168116;635.4789072960175;635.6281575278422;635.9554831161821;15000.849991500054;15012;15060.774088388684;15082.622934426727;15090;14263.215523146222;14639.341229644691;14698.853011469962;14701.926490367587;14795.40818367264;12674.302913339763;12956;13048.86951130486;13347.065705400626;13567.86432135676;4423.51341352451;4471.955280447219;4499.437570303713;4546.227141385973;4568.70303430276;78342.6082869582;78699;78827.21172788256;79097;79454.60272698599;11608.76782464346;11635.185537012498;11684.415779211005;11720.531178752753;12229.14395992272;21969.450763730714;21970.670439943413;22007;22015.449613759465;22564;7860.960695196487;7911.327537159291;8045.678172873137;8439.789005274795;8534.189252021048;4308.073764140725;4350.260455722535;4428.092241090569;4500.122476117192;4576.496585375606;3500.7024402925526;3623.710103191738;3630.5824830144343;3638.3450978823535;3642.3443780119833;635.3075148088514;637.9202599675041;654.554902666182;667.7162206062501;684.6337209592843;727.1347096954607;732.5897497401472;766.1419210484182;766.6818270417714;790.4980337485738;9379.812403752023;9844.409335439897;10282.742931426774;10389.896101038892;10395.94802025985;684.9075374824334;708.8794904866185;742.5223804628749;764.7629234937187;774.2992591704516;4287.957120428819;4534.659900507475;4662.696924699883;4745.667803253808;4774.880627984328;108021;111487;112496;114367;114392;105506.47246763717;105540.47229763956;106355.4682226584;107141;107541;91532.54233728789;92016.0798392021;92951.53524232336;93624;93888.53055734678;8843.60203790818;8849.69026084084;8875.112488751074;8903.19871211594;9059.184673379428;49702.25446618376;50225.49774502208;50878.74560627173;50966.49033509766;52578.73710631422;45149;48797;74135.62932185305;74373.25626743663;74480.62759686167;12246.448909798903;12340.074494413135;12435.962028663173;12469.189502682297;12801.807972880228;13822.308884555932;14013.789793152908;14321.28393580296;14672.019277397414;14714.705705886037;12459.93770031144;12552.309622970824;12651.746965060463;12685.175463595206;12995.935020324838;12459.377031148588;12680.619581412573;12891;13063.412146453242;13280;12048.277103373826;12459.501619935101;12796.680082998;12919.031072669775;12950.676233094247;10010.799784004425;10082.546285417027;10089.646862359783;10253.025962533547;10353.84469232947;9811.362261452983;9940.794309896057;10830.296030757976;10836.458177091272;10874.401907895142 23:18:35 INFO - PROCESS | 17122 | __end_tp_report 23:18:35 INFO - PROCESS | 17122 | __start_cc_report 23:18:35 INFO - PROCESS | 17122 | _x_x_mozilla_cycle_collect,926 23:18:35 INFO - PROCESS | 17122 | __end_cc_report 23:18:35 INFO - PROCESS | 17122 | __startTimestamp1468909115092__endTimestamp 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | ------- Summary: start ------- 23:18:35 INFO - PROCESS | 17122 | Number of tests: 6 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | [#0] dojo.html Cycles:165 Average:5408.56 Median:5660.86 stddev:626.35 (11.1%) stddev-sans-first:600.37 23:18:35 INFO - PROCESS | 17122 | Values: 3052.2 3064.4 3104.3 3111.7 3121.8 5367.4 5403.2 5439.0 5498.9 5535.9 5345.1 5352.4 5469.0 5484.9 5515.9 4816.2 4900.6 4911.0 4930.4 5094.5 5001.0 5006.4 5023.3 5040.4 5062.9 4843.1 4895.0 4901.1 4921.6 4928.0 4914.4 4926.7 4979.7 5003.5 5034.6 4850.1 4939.3 4942.7 4956.0 4968.7 5317.7 5388.8 5412.2 5467.5 5474.6 5783.0 5810.7 5828.0 5833.6 5837.2 5711.8 5754.1 5887.4 5929.1 5930.1 5388.6 5431.1 5471.6 5482.3 5484.9 6011.5 6012.5 6019.5 6023.1 6024.0 5462.9 5577.1 5583.9 5763.4 5774.6 5827.5 5832.7 5836.0 5837.1 5839.9 5691.2 5725.1 5733.4 5736.1 5753.4 5664.1 5722.9 5729.1 5763.5 5777.9 4397.0 4414.6 4420.7 4420.9 4464.9 3924.2 3939.9 3955.1 3979.2 4005.8 5359.0 5449.8 5462.6 5485.6 5518.4 5860.9 5861.6 5870.6 5871.7 5882.6 5499.5 5510.6 5511.0 5512.1 5513.0 4902.3 4993.1 5036.7 5044.0 5096.6 6078.1 6083.3 6086.5 6089.3 6124.2 5911.2 5919.5 5924.6 5929.6 5929.9 5832.0 5869.0 5897.6 5917.5 6073.8 5636.3 5660.9 5662.5 5663.9 5679.5 5758.2 5794.6 5800.4 5813.5 5817.1 5583.1 5721.3 5782.2 5786.0 5804.7 5775.1 5786.7 5788.9 5789.9 5793.3 5489.2 5713.3 5726.7 5731.1 5737.1 5702.0 5749.1 5775.6 5803.5 5860.2 5649.0 5673.1 5683.4 5691.9 5694.5 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | [#1] ext.html Cycles:180 Average:59324.76 Median:24124.69 stddev:111852.68 (463.6%) stddev-sans-first:112079.67 23:18:35 INFO - PROCESS | 17122 | Values: 640.0 646.3 659.0 660.8 668.7 42838.8 44947.3 45080.3 48383.3 48493.0 45659.3 46329.0 46331.5 46357.8 46364.3 9000.8 9129.8 9139.2 9141.5 9142.5 8911.6 8960.9 8978.7 8991.1 8995.3 8984.5 9012.0 9019.5 9020.3 9024.5 8975.2 8979.6 8986.2 9014.8 9018.1 8924.6 8961.7 8982.3 9017.5 9022.0 43619.0 43877.6 43968.8 43977.6 44158.8 3584.6 3598.9 4033.9 4255.9 4371.8 32623.3 33218.5 33519.8 33820.2 35018.1 4408.1 4485.9 4487.9 4580.0 4608.2 346188.0 348038.0 350384.0 350935.0 352561.0 58215.1 58275.7 58634.1 58940.0 58968.0 53593.7 59144.7 60278.7 60475.4 66593.7 17171.7 17303.9 17353.6 17359.7 17381.0 13247.0 13280.3 13328.6 13337.9 13340.7 15011.8 16684.8 16730.7 17016.6 17565.5 5893.1 5900.7 5941.5 6105.1 6117.3 1007.5 1014.6 1029.2 1031.5 1089.1 32653.8 33724.3 33854.3 34006.7 34051.5 1151.6 1153.1 1176.8 1240.3 1273.8 16951.2 17729.7 17747.5 17766.7 17777.0 520888.0 527483.0 527813.0 528245.0 528272.0 59460.0 60986.1 61102.7 61109.0 61122.4 1241.7 1336.8 1371.2 1388.5 1389.9 32451.7 34652.3 34942.8 34959.8 35361.3 51721.9 52131.5 52301.2 52329.0 52373.5 369774.0 370336.0 372226.0 372927.0 373183.0 31897.4 42572.0 43203.0 46141.3 47236.5 49509.0 50247.2 50818.2 51119.5 51335.5 42841.8 43098.8 43236.6 45046.0 45150.3 41893.4 42939.4 43537.3 43890.0 44374.0 41848.2 42439.0 42468.8 43221.8 43406.6 37288.4 38209.3 38483.4 38684.2 38700.5 28487.6 28713.3 28742.7 29228.7 29229.9 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | [#2] jquery.html Cycles:180 Average:31445.00 Median:31482.23 stddev:81932.05 (260.2%) stddev-sans-first:82128.73 23:18:35 INFO - PROCESS | 17122 | Values: 395.0 395.7 396.9 400.8 417.9 13411.7 18969.9 20518.0 20524.4 21676.9 15619.5 19714.7 19842.3 20221.8 20603.9 5779.7 5951.9 5956.0 5957.9 5984.0 5873.4 5894.7 5918.3 5930.4 5932.1 4242.2 6283.0 6662.9 6674.1 6731.3 6557.9 6579.0 6594.8 7218.4 7607.8 7170.3 7410.3 7418.3 7419.8 7480.3 27913.7 28246.6 28711.6 28942.3 29015.1 2632.8 2683.9 2722.9 2727.3 2802.5 16177.3 16366.5 16580.7 16916.6 17267.9 2107.1 2109.2 2131.6 2178.6 2194.1 96077.0 101234.5 101561.5 103047.0 105703.0 17883.8 18449.7 18576.9 18889.1 19200.0 33949.3 34142.0 34273.8 34290.7 34422.0 10070.5 10598.5 10651.5 10674.4 10773.8 8845.6 8863.6 8899.8 9053.2 9202.4 7431.3 8753.2 9003.6 9354.5 9458.4 3465.2 3519.4 3524.7 3631.5 3673.0 874.8 958.4 1009.6 1076.0 1096.1 19647.6 20115.4 20545.7 20669.2 21046.8 1023.6 1026.6 1054.8 1055.7 1076.8 9740.4 11398.1 11407.0 11457.9 11480.9 479942.8 490729.0 499648.0 501305.0 502429.0 95832.5 96995.0 97903.5 99316.0 100020.5 953.9 955.8 963.0 965.6 1002.7 18206.6 18226.4 18541.4 18561.9 18816.0 43242.0 43546.8 45059.3 45126.0 46129.0 58679.4 58905.0 59318.8 59345.0 60375.7 8737.9 8783.2 8893.1 9033.3 9221.0 7355.0 8968.4 10240.3 12485.8 13652.9 11150.7 11817.1 11846.8 11893.7 12077.5 11633.2 11788.5 11808.1 11895.7 11904.2 11814.5 12001.6 12055.6 12192.3 12212.3 7604.1 7944.6 7999.8 8324.1 9152.0 11573.4 11673.4 11771.6 11851.4 12024.4 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | [#3] mootools.html Cycles:180 Average:5929.44 Median:4745.86 stddev:3378.48 (71.2%) stddev-sans-first:3370.39 23:18:35 INFO - PROCESS | 17122 | Values: 1345.4 1489.0 1524.5 1656.8 1813.3 8432.5 8868.1 8888.1 9085.2 9106.3 8930.5 8936.1 9011.0 9017.0 9019.8 2066.9 2266.8 2408.5 2422.8 2423.5 2371.5 2387.3 2388.6 2388.6 2388.7 2341.6 2361.4 2371.6 2372.7 2378.9 1936.0 2227.7 2295.2 2325.7 2336.1 2341.9 2351.1 2357.7 2358.3 2359.6 9049.0 9099.5 9103.3 9110.8 9118.0 5270.7 5338.7 5382.4 5396.5 5399.4 3337.4 3406.9 3536.4 3538.3 3542.8 1055.8 1087.8 1092.7 1117.6 1117.6 11918.2 11924.8 11929.2 11929.3 11944.2 4740.8 4750.9 4817.7 4828.3 4950.6 10387.5 10421.8 10433.6 10434.7 10435.2 5425.8 5452.7 5458.0 5459.2 5470.3 4438.4 4863.8 4935.5 5020.3 5023.9 4000.0 4030.1 4079.3 4126.3 4135.3 1199.5 1282.2 1291.2 1299.8 1309.5 864.9 910.7 947.1 950.9 964.9 5868.9 5963.3 6049.4 6079.5 6225.3 973.1 1019.3 1022.5 1023.5 1040.2 4110.3 4196.3 4197.7 4218.8 4237.8 11035.0 11520.5 11572.8 11588.7 11593.9 11013.2 11440.7 11442.5 11451.3 11462.3 8244.7 8326.8 8401.1 8411.4 8412.7 6040.5 6283.7 6306.1 6445.0 6518.9 10521.7 10595.6 10609.8 10658.6 10703.5 10201.1 10464.0 10538.8 10631.0 10641.6 7514.9 7831.6 8077.8 8161.8 8362.0 8888.8 8921.5 8971.9 8972.7 8975.8 7522.7 7529.7 7606.3 7673.0 7954.3 7436.5 7585.5 7606.5 7670.8 7753.8 7042.1 7044.3 7065.8 7085.8 7098.2 5096.6 5118.3 5192.9 5266.9 5302.0 5162.4 5277.4 5301.3 5317.5 5367.0 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | [#4] prototype.html Cycles:180 Average:4683.15 Median:5239.22 stddev:1375.70 (26.3%) stddev-sans-first:1340.00 23:18:35 INFO - PROCESS | 17122 | Values: 319.8 323.4 331.0 332.9 334.9 5312.4 5368.4 5370.5 5386.6 5418.6 5058.6 5132.9 5306.2 5330.5 5352.0 4192.8 4214.2 4223.3 4223.9 4256.9 4209.3 4210.5 4221.7 4224.6 4227.5 3975.5 4023.3 4177.6 4196.5 4227.5 4199.1 4206.7 4211.1 4217.2 4225.5 3736.2 3741.8 3745.3 3759.8 3766.1 5121.6 5196.6 5199.8 5231.9 5246.6 4714.6 4774.9 4805.3 4866.9 4871.9 5474.2 5500.4 5502.3 5556.2 5560.4 3813.0 4000.1 4012.6 4018.4 4054.3 5651.8 5716.5 5750.3 5758.6 5778.0 4076.3 4091.8 4096.9 4121.4 4150.2 4004.6 4154.8 4191.9 4209.9 4219.6 4736.6 4755.4 4786.5 4791.5 4814.8 5560.6 5562.0 5660.3 5666.2 5675.0 1378.4 1412.9 1434.9 1445.9 1450.7 906.7 906.8 912.2 920.8 922.7 4857.2 4859.0 4894.6 4898.0 4912.5 5006.6 5035.5 5061.3 5131.3 5185.6 4772.3 4784.9 4788.1 4833.4 5113.2 3050.9 3058.2 3074.7 3076.6 3081.3 5891.8 5913.4 5956.3 5956.8 5969.7 5637.2 5875.2 5881.9 5891.3 5939.2 5873.3 5890.4 5899.4 5905.4 5913.3 5639.5 5821.7 5828.0 5866.6 5878.1 5736.8 5753.8 5754.0 5795.2 5824.1 5962.8 5989.4 6001.2 6024.0 6026.4 3887.2 3938.8 4046.1 4150.5 4159.0 5801.9 5803.0 5803.4 5806.9 5808.9 5652.8 5673.3 5681.5 5684.2 5687.1 5448.0 5569.8 5577.8 5622.3 5661.9 5479.9 5515.3 5551.9 5554.4 5575.0 5348.7 5624.6 5664.5 5678.5 5704.9 5500.4 5507.7 5507.7 5508.2 5521.8 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - PROCESS | 17122 | [#5] yui.html Cycles:180 Average:20803.03 Median:4450.02 stddev:30389.36 (682.9%) stddev-sans-first:30437.37 23:18:35 INFO - PROCESS | 17122 | Values: 766.4 768.4 804.5 810.9 852.6 15114.9 15848.5 16330.6 16337.7 16353.8 14868.2 15088.8 15102.3 15119.8 15159.6 671.2 687.7 697.0 808.5 832.2 697.4 801.2 835.7 838.8 840.0 873.2 873.4 876.6 876.6 877.6 700.1 868.0 871.3 871.4 872.0 615.1 631.2 635.5 635.6 636.0 15000.8 15012.0 15060.8 15082.6 15090.0 14263.2 14639.3 14698.9 14701.9 14795.4 12674.3 12956.0 13048.9 13347.1 13567.9 4423.5 4472.0 4499.4 4546.2 4568.7 78342.6 78699.0 78827.2 79097.0 79454.6 11608.8 11635.2 11684.4 11720.5 12229.1 21969.5 21970.7 22007.0 22015.4 22564.0 7861.0 7911.3 8045.7 8439.8 8534.2 4308.1 4350.3 4428.1 4500.1 4576.5 3500.7 3623.7 3630.6 3638.3 3642.3 635.3 637.9 654.6 667.7 684.6 727.1 732.6 766.1 766.7 790.5 9379.8 9844.4 10282.7 10389.9 10395.9 684.9 708.9 742.5 764.8 774.3 4288.0 4534.7 4662.7 4745.7 4774.9 108021.0 111487.0 112496.0 114367.0 114392.0 105506.5 105540.5 106355.5 107141.0 107541.0 91532.5 92016.1 92951.5 93624.0 93888.5 8843.6 8849.7 8875.1 8903.2 9059.2 49702.3 50225.5 50878.7 50966.5 52578.7 45149.0 48797.0 74135.6 74373.3 74480.6 12246.4 12340.1 12436.0 12469.2 12801.8 13822.3 14013.8 14321.3 14672.0 14714.7 12459.9 12552.3 12651.7 12685.2 12995.9 12459.4 12680.6 12891.0 13063.4 13280.0 12048.3 12459.5 12796.7 12919.0 12950.7 10010.8 10082.5 10089.6 10253.0 10353.8 9811.4 9940.8 10830.3 10836.5 10874.4 23:18:35 INFO - PROCESS | 17122 | -------- Summary: end -------- 23:18:35 INFO - PROCESS | 17122 | 23:18:35 INFO - TEST-INFO | 17122: exit 0 23:18:35 INFO - TEST-OK | dromaeo_css | took 1113678ms 23:18:35 INFO - TEST-START | kraken 23:18:35 INFO - operating with platform_type : linux_ 23:18:35 INFO - Initialising browser for kraken test... 23:18:35 INFO - TEST-INFO | started process 17317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphxIahd/profile http://localhost:34688/getInfo.html) 23:18:39 INFO - PROCESS | 17317 | __metrics Screen width/height:1600/1200 23:18:39 INFO - PROCESS | 17317 | colorDepth:24 23:18:39 INFO - PROCESS | 17317 | Browser inner width/height: 1024/697 23:18:39 INFO - PROCESS | 17317 | __metrics 23:18:40 INFO - PROCESS | 17317 | 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" 23:18:40 INFO - PROCESS | 17317 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:18:41 INFO - TEST-INFO | 17317: exit 0 23:18:41 INFO - Browser initialized. 23:18:41 INFO - Running cycle 1/1 for kraken test... 23:18:41 INFO - TEST-INFO | started process 17401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphxIahd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:53 INFO - PROCESS | 17401 | RSS: Main: 157970432 23:18:53 INFO - PROCESS | 17401 | 23:18:55 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34688/tests/kraken/driver.html?testName=audio-beat-detection) 23:18:56 INFO - PROCESS | 17401 | RSS: Main: 216981504 23:18:56 INFO - PROCESS | 17401 | 23:18:59 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34688/tests/kraken/driver.html?testName=audio-dft) 23:18:59 INFO - PROCESS | 17401 | RSS: Main: 204865536 23:18:59 INFO - PROCESS | 17401 | 23:19:03 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34688/tests/kraken/driver.html?testName=audio-fft) 23:19:03 INFO - PROCESS | 17401 | RSS: Main: 270135296 23:19:03 INFO - PROCESS | 17401 | 23:19:05 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34688/tests/kraken/driver.html?testName=audio-oscillator) 23:19:06 INFO - PROCESS | 17401 | RSS: Main: 180588544 23:19:06 INFO - PROCESS | 17401 | 23:19:08 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34688/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:19:09 INFO - PROCESS | 17401 | RSS: Main: 183791616 23:19:09 INFO - PROCESS | 17401 | 23:19:12 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34688/tests/kraken/driver.html?testName=imaging-darkroom) 23:19:12 INFO - PROCESS | 17401 | RSS: Main: 219111424 23:19:12 INFO - PROCESS | 17401 | 23:19:16 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34688/tests/kraken/driver.html?testName=imaging-desaturate) 23:19:16 INFO - PROCESS | 17401 | RSS: Main: 221065216 23:19:16 INFO - PROCESS | 17401 | 23:19:19 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34688/tests/kraken/driver.html?testName=json-parse-financial) 23:19:20 INFO - PROCESS | 17401 | RSS: Main: 221761536 23:19:20 INFO - PROCESS | 17401 | 23:19:21 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34688/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:19:22 INFO - PROCESS | 17401 | RSS: Main: 211058688 23:19:22 INFO - PROCESS | 17401 | 23:19:23 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:19:24 INFO - PROCESS | 17401 | RSS: Main: 213184512 23:19:24 INFO - PROCESS | 17401 | 23:19:26 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:19:27 INFO - PROCESS | 17401 | RSS: Main: 216465408 23:19:27 INFO - PROCESS | 17401 | 23:19:29 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:19:30 INFO - PROCESS | 17401 | RSS: Main: 223956992 23:19:30 INFO - PROCESS | 17401 | 23:19:33 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:19:33 INFO - PROCESS | 17401 | RSS: Main: 224493568 23:19:33 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | Cycle 1(1): loaded http://localhost:34688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34688/tests/kraken/driver.html?testName=ai-astar) 23:19:35 INFO - PROCESS | 17401 | RSS: Main: 266760192 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | __start_tp_report 23:19:35 INFO - PROCESS | 17401 | _x_x_mozilla_page_load 23:19:35 INFO - PROCESS | 17401 | _x_x_mozilla_page_load_details 23:19:35 INFO - PROCESS | 17401 | |i|pagename|runs| 23:19:35 INFO - PROCESS | 17401 | |0;ai-astar;101;111;109;109;111;111;111;109;109;110 23:19:35 INFO - PROCESS | 17401 | |1;audio-beat-detection;144;119;122;171;118;118;142;122;150;117 23:19:35 INFO - PROCESS | 17401 | |2;audio-dft;161;160;160;160;159;160;160;161;162;161 23:19:35 INFO - PROCESS | 17401 | |3;audio-fft;75;74;78;111;76;74;74;75;108;74 23:19:35 INFO - PROCESS | 17401 | |4;audio-oscillator;83;83;117;84;83;84;118;83;84;128 23:19:35 INFO - PROCESS | 17401 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;116 23:19:35 INFO - PROCESS | 17401 | |6;imaging-darkroom;146;145;145;145;146;147;146;145;145;145 23:19:35 INFO - PROCESS | 17401 | |7;imaging-desaturate;94;95;95;95;95;94;94;94;94;94 23:19:35 INFO - PROCESS | 17401 | |8;json-parse-financial;50;50;51;51;51;52;51;50;50;50 23:19:35 INFO - PROCESS | 17401 | |9;json-stringify-tinderbox;62;64;62;62;62;62;62;62;62;63 23:19:35 INFO - PROCESS | 17401 | |10;stanford-crypto-aes;83;74;83;83;80;75;78;80;75;75 23:19:35 INFO - PROCESS | 17401 | |11;stanford-crypto-ccm;127;116;117;116;132;116;125;119;165;119 23:19:35 INFO - PROCESS | 17401 | |12;stanford-crypto-pbkdf2;157;157;158;160;157;158;156;155;154;156 23:19:35 INFO - PROCESS | 17401 | |13;stanford-crypto-sha256-iterative;67;63;64;61;64;62;60;61;61;61 23:19:35 INFO - PROCESS | 17401 | __end_tp_report 23:19:35 INFO - PROCESS | 17401 | __start_cc_report 23:19:35 INFO - PROCESS | 17401 | _x_x_mozilla_cycle_collect,1663 23:19:35 INFO - PROCESS | 17401 | __end_cc_report 23:19:35 INFO - PROCESS | 17401 | __startTimestamp1468909175580__endTimestamp 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | ------- Summary: start ------- 23:19:35 INFO - PROCESS | 17401 | Number of tests: 14 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.50 stddev:3.00 (2.7%) stddev-sans-first:1.00 23:19:35 INFO - PROCESS | 17401 | Values: 101.0 111.0 109.0 109.0 111.0 111.0 111.0 109.0 109.0 110.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:132.00 stddev:18.48 (14.0%) stddev-sans-first:19.11 23:19:35 INFO - PROCESS | 17401 | Values: 144.0 119.0 122.0 171.0 118.0 118.0 142.0 122.0 150.0 117.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 23:19:35 INFO - PROCESS | 17401 | Values: 161.0 160.0 160.0 160.0 159.0 160.0 160.0 161.0 162.0 161.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#3] audio-fft Cycles:10 Average:81.90 Median:74.50 stddev:14.62 (19.6%) stddev-sans-first:15.29 23:19:35 INFO - PROCESS | 17401 | Values: 75.0 74.0 78.0 111.0 76.0 74.0 74.0 75.0 108.0 74.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.00 stddev:18.38 (22.1%) stddev-sans-first:19.00 23:19:35 INFO - PROCESS | 17401 | Values: 83.0 83.0 117.0 84.0 83.0 84.0 118.0 83.0 84.0 128.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:19:35 INFO - PROCESS | 17401 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 23:19:35 INFO - PROCESS | 17401 | Values: 146.0 145.0 145.0 145.0 146.0 147.0 146.0 145.0 145.0 145.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:19:35 INFO - PROCESS | 17401 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:0.70 (1.4%) stddev-sans-first:0.71 23:19:35 INFO - PROCESS | 17401 | Values: 50.0 50.0 51.0 51.0 51.0 52.0 51.0 50.0 50.0 50.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 23:19:35 INFO - PROCESS | 17401 | Values: 62.0 64.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 63.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#10] stanford-crypto-aes Cycles:10 Average:78.60 Median:80.00 stddev:3.69 (4.6%) stddev-sans-first:3.55 23:19:35 INFO - PROCESS | 17401 | Values: 83.0 74.0 83.0 83.0 80.0 75.0 78.0 80.0 75.0 75.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:122.00 stddev:15.02 (12.3%) stddev-sans-first:15.92 23:19:35 INFO - PROCESS | 17401 | Values: 127.0 116.0 117.0 116.0 132.0 116.0 125.0 119.0 165.0 119.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.80 Median:157.00 stddev:1.69 (1.1%) stddev-sans-first:1.79 23:19:35 INFO - PROCESS | 17401 | Values: 157.0 157.0 158.0 160.0 157.0 158.0 156.0 155.0 154.0 156.0 23:19:35 INFO - PROCESS | 17401 | 23:19:35 INFO - PROCESS | 17401 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.50 stddev:2.12 (3.4%) stddev-sans-first:1.45 23:19:35 INFO - PROCESS | 17401 | Values: 67.0 63.0 64.0 61.0 64.0 62.0 60.0 61.0 61.0 61.0 23:19:35 INFO - PROCESS | 17401 | -------- Summary: end -------- 23:19:35 INFO - PROCESS | 17401 | 23:19:36 INFO - TEST-INFO | 17401: exit 0 23:19:36 INFO - TEST-OK | kraken | took 60694ms 23:19:36 INFO - SUITE-END | took 1174s 23:19:36 INFO - Completed test suite (00:19:34) 23:19:36 INFO - javascript benchmark 23:19:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3052.236940764809, 3064.4330798802216, 3104.348086901752, 3111.6577176510586, 3121.843907804609, 5367.409584945663, 5403.189521571766, 5439.048166570854, 5498.862528436791, 5535.861603459915, 5345.091334473139, 5352.411234764173, 5468.9726551367285, 5484.890302193954, 5515.889682206353, 4816.157172494817, 4900.607951363901, 4910.975445122769, 4930.383702037246, 5094.541491265785, 5000.999800039988, 5006.374203224597, 5023.346964894558, 5040.36995375578, 5062.873428164307, 4843.055604157191, 4895.045466134123, 4901.142300097486, 4921.557059864629, 4927.95072049282, 4914.385701787277, 4926.704397736112, 4979.7012179268995, 5003.4746351633285, 5034.572061374787, 4850.126977144113, 4939.3084968104495, 4942.703437793743, 4956.033573453178, 4968.677035992685, 5317.680939143625, 5388.838334849961, 5412.161115027137, 5467.45325467454, 5474.589405794582, 5783.045797443417, 5810.709464526799, 5828.03837366834, 5833.591648584601, 5837.182794408786, 5711.828645140652, 5754.050581654022, 5887.411258874121, 5929.051351783737, 5930.140129681172, 5388.595855310868, 5431.076716958166, 5471.616986810965, 5482.342118945712, 5484.91772623411, 6011.5190784737115, 6012.518998480111, 6019.518438524908, 6023.0965355196195, 6024.0, 5462.863428414322, 5577.135543990688, 5583.860403489945, 5763.423657634287, 5774.595778295479, 5827.504662103684, 5832.708364581754, 5836.007878660638, 5837.124431335249, 5839.9124013139835, 5691.203231547586, 5725.112607545837, 5733.397993210653, 5736.110902809987, 5753.395893431213, 5664.122061080539, 5722.856928576819, 5729.054705973551, 5763.452472015152, 5777.855553611109, 4396.956030439718, 4414.558544145624, 4420.668449866273, 4420.911581768347, 4464.933026004613, 3924.234774219057, 3939.901502462404, 3955.0507878109047, 3979.1643754811234, 4005.7796821175116, 5359.035373632705, 5449.836504904859, 5462.617616766867, 5485.561155107582, 5518.44815518453, 5860.941390586039, 5861.589688721834, 5870.559708021916, 5871.677057761779, 5882.646891271934, 5499.532539734168, 5510.586705997066, 5511.0, 5512.062949298629, 5512.972435137799, 4902.28916807061, 4993.101241776515, 5036.748162591855, 5044.0, 5096.643234973591, 6078.149059131725, 6083.270007599028, 6086.543509236825, 6089.330173680892, 6124.234470691164, 5911.201987731602, 5919.496842768241, 5924.644521328734, 5929.584929054925, 5929.940700592939, 5831.970840145772, 5869.031609784338, 5897.557683173865, 5917.497012753792, 6073.787417440369, 5636.2954630671165, 5660.858478537904, 5662.546996240373, 5663.943360566342, 5679.517241034393, 5758.193852860519, 5794.62334948227, 5800.390958949419, 5813.476787089182, 5817.0983497557945, 5583.134614134816, 5721.313442386941, 5782.248307720138, 5786.0, 5804.709764511842, 5775.075987841925, 5786.739596718242, 5788.884222315614, 5789.8552536186935, 5793.3337666167245, 5489.176623506506, 5713.342965559014, 5726.74229659658, 5731.054376027909, 5737.053386191398, 5701.971490142523, 5749.108888122348, 5775.595708300379, 5803.506701930383, 5860.208871802422, 5649.0, 5673.149027645886, 5683.431656834284, 5691.943080569141, 5694.515966142924], "value": 5365.093225212628, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.9536757401648, 646.2955378637286, 659.0345144363506, 660.752217918281, 668.6823758714614, 42838.78580607096, 44947.32579011314, 45080.32379514306, 48383.27425088618, 48493.0, 45659.315110273375, 46329.0, 46331.536684633225, 46357.76821115898, 46364.304535432, 9000.77498062547, 9129.817403651923, 9139.223166030895, 9141.542922853863, 9142.497162656051, 8911.554422278892, 8960.8655870162, 8978.685745998891, 8991.145841145113, 8995.28037756978, 8984.472639651274, 9012.0, 9019.458832470073, 9020.2783777298, 9024.548772561378, 8975.192232699022, 8979.640814367418, 8986.236169925563, 9014.774630634221, 9018.098190180996, 8924.598393072327, 8961.731148065568, 8982.281417486585, 9017.504037278013, 9021.954890225572, 43619.0, 43877.612238775866, 43968.78015609901, 43977.56022439767, 44158.77920610408, 3584.6415358464205, 3598.8740394086344, 4033.8714469644565, 4255.893602659959, 4371.759553224571, 32623.347533049207, 33218.50172247418, 33519.83240083808, 33820.15449613755, 35018.124546886276, 4408.118376324748, 4485.887852803673, 4487.932681009787, 4579.954200457986, 4608.1705293047235, 346188.0, 348038.0, 350384.0, 350935.0, 352561.0, 58215.12677309844, 58275.70862145747, 58634.12048819271, 58940.0, 58968.0, 53593.732031340376, 59144.704276479206, 60278.69860650669, 60475.395246047854, 66593.66703166453, 17171.742423863652, 17303.91348043252, 17353.56616084583, 17359.65280694379, 17381.0, 13247.0, 13280.269585172715, 13328.600141995756, 13337.933310333387, 13340.73318533624, 15011.774823377658, 16684.833151668572, 16730.74903876443, 17016.57458563546, 17565.473035808947, 5893.114203454853, 5900.672348721503, 5941.465268125803, 6105.114758360101, 6117.327094019654, 1007.461948023666, 1014.5840205515851, 1029.2435060230725, 1031.4584842957506, 1089.096050278267, 32653.836730816194, 33724.32551349008, 33854.32291354159, 34006.65993340084, 34051.48922766161, 1151.6084531259412, 1153.0832987774738, 1176.817593273044, 1240.299230934537, 1273.8280332155225, 16951.237194326284, 17729.7340539892, 17747.46757597248, 17766.733498997524, 17777.0, 520888.0, 527483.0, 527813.0, 528245.0, 528272.0, 59460.0, 60986.0852087219, 61102.69448652817, 61109.0, 61122.38877611167, 1241.7268200995766, 1336.8395792504766, 1371.2047012732592, 1388.5209602687057, 1389.8818600419077, 32451.67548324581, 34652.30695386028, 34942.77646401481, 34959.825200873835, 35361.29277414489, 51721.93112275467, 52131.47868521266, 52301.215481768566, 52329.0, 52373.47626523686, 369774.0, 370336.0, 372226.0, 372927.0, 373183.0, 31897.36205275928, 42572.0, 43203.0, 46141.30788038249, 47236.52763472459, 49509.0, 50247.246291306394, 50818.237726434876, 51119.488805111476, 51335.48664513307, 42841.78579107209, 43098.78450607727, 43236.567634323255, 45046.0, 45150.32274515951, 41893.371599426646, 42939.35590966076, 43537.346939795294, 43890.0, 44374.0, 41848.1630367397, 42439.0, 42468.78765606152, 43221.7838910816, 43406.56593434025, 37288.44067338938, 38209.31217458481, 38483.42274865823, 38684.22631547297, 38700.47967210022, 28487.572686409305, 28713.28216794597, 28742.712572874003, 29228.707712922598, 29229.85385073061], "value": 20732.553847447554, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.99012524686896, 395.70717668924993, 396.9206158768247, 400.8436709683224, 417.9477565304337, 13411.73231598198, 18969.905150474246, 20517.97410129493, 20524.38426847195, 21676.89161554194, 15619.531414057568, 19714.70427943582, 19842.30551930683, 20221.797782022175, 20603.896980515077, 5779.682117483537, 5951.9107213391835, 5956.0, 5957.910631340534, 5983.970080149593, 5873.353931067579, 5894.705264736767, 5918.2898052233795, 5930.377310382412, 5932.11018347247, 4242.193983143208, 6283.0, 6662.933370666304, 6674.098996635465, 6731.259561448237, 6557.934420655804, 6579.04603832444, 6594.769183078593, 7218.386437152848, 7607.80980475487, 7170.318819712119, 7410.296021877913, 7418.332350088464, 7419.777406677755, 7480.251974802476, 27913.720862791317, 28246.576301355497, 28711.56932646012, 28942.276443088882, 29015.12954611365, 2632.7630513253707, 2683.9463210735676, 2722.9046983355684, 2727.3318037081044, 2802.495550800855, 16177.272022759003, 16366.509004729876, 16580.668386632322, 16916.577085572837, 17267.913660431743, 2107.115011695076, 2109.2301310021885, 2131.5949969505746, 2178.59695956247, 2194.122351059573, 96077.0, 101234.49382753113, 101561.4921925393, 103047.0, 105703.0, 17883.821161788215, 18449.7232541512, 18576.907115464335, 18889.149988250563, 19200.0, 33949.321013580084, 34142.0, 34273.82863085669, 34290.65709342925, 34422.0, 10070.546825392876, 10598.470076496144, 10651.467426628638, 10674.412907290018, 10773.83839242412, 8845.601947912359, 8863.556822158866, 8899.777505562413, 9053.206056486484, 9202.447853128833, 7431.340872374807, 8753.212210900921, 9003.594838232295, 9354.485503297385, 9458.43249405038, 3465.1856813648774, 3519.384107781128, 3524.718022558189, 3631.5460567429072, 3673.0413941179195, 874.7725591346168, 958.4057884111755, 1009.6213919780082, 1075.983860242097, 1096.1176253116319, 19647.607047858965, 20115.42862829002, 20545.69181462279, 20669.17323307051, 21046.78953210448, 1023.6161439460202, 1026.609888242463, 1054.8154073037188, 1055.720234137954, 1076.8115579773507, 9740.415575065377, 11398.1451391146, 11407.0, 11457.88542114585, 11480.94259528697, 479942.80228790455, 490729.0, 499648.0, 501305.0, 502429.0, 95832.52083739676, 96995.0, 97903.51048244713, 99316.0, 100020.49825764746, 953.8950715421298, 955.7515046088067, 962.9614815407306, 965.5510187762724, 1002.6891663584312, 18206.635867282846, 18226.36207732723, 18541.35105271309, 18561.90719046396, 18816.0, 43242.0, 43546.78226608847, 45059.32411013772, 45126.0, 46129.0, 58679.41320586739, 58905.01417513542, 59318.77152898884, 59345.0, 60375.698121509115, 8737.868931965899, 8783.209511144029, 8893.110688931314, 9033.322500812597, 9221.0, 7355.0, 8968.372213944962, 10240.334378265388, 12485.813847684456, 13652.931735341259, 11150.721231969266, 11817.113716471471, 11846.822297665369, 11893.702657433287, 12077.456514457046, 11633.243839150427, 11788.469518871843, 11808.11439142085, 11895.702607434883, 11904.226225295328, 11814.527418903148, 12001.579944701894, 12055.638330850088, 12192.268463892195, 12212.32832194238, 7604.12552556441, 7944.6424910877995, 7999.800004999689, 8324.084350721294, 9151.954240228757, 11573.421328933688, 11673.357965311989, 11771.587994420153, 11851.407429628312, 12024.376709144153], "value": 10560.246675413708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.3878485289194, 1489.0246888288173, 1524.4816762300818, 1656.7763351947483, 1813.3471950097971, 8432.451890627113, 8868.068852770455, 8888.111188881117, 9085.227755640779, 9106.27149828012, 8930.464172149676, 8936.061713520085, 9011.0, 9016.954915225415, 9019.819603607924, 2066.86565373251, 2266.841321107523, 2408.5423769483828, 2422.7819496245334, 2423.4547226873988, 2371.5019845832308, 2387.2838148555365, 2388.5700573896693, 2388.59393903036, 2388.665586817847, 2341.637046257833, 2361.4332560185603, 2371.6323969784626, 2372.6678265042906, 2378.8691621960784, 1935.9612807743906, 2227.6769868368997, 2295.1622657729977, 2325.651152327156, 2336.065573770497, 2341.8711970841596, 2351.0948284910432, 2357.669926210332, 2358.3160883343808, 2359.5516851798275, 9049.049849765746, 9099.454032757989, 9103.271738260923, 9110.81778364429, 9118.04260552639, 5270.7101109439345, 5338.679679219259, 5382.434844341366, 5396.541293990015, 5399.379071406799, 3337.4493208620547, 3406.8807591734417, 3536.381133301662, 3538.3454060998542, 3542.8051457169845, 1055.7716491679575, 1087.8422628718793, 1092.6940456672144, 1117.580907159815, 1117.6255954255334, 11918.165728399015, 11924.761504769856, 11929.224600401032, 11929.28424294545, 11944.223625464318, 4740.834070807505, 4750.881227969259, 4817.710937343771, 4828.251620998751, 4950.603951683856, 10387.532561034772, 10421.843672344921, 10433.634822781167, 10434.68695939123, 10435.16518678504, 5425.810096646677, 5452.727363631802, 5458.0, 5459.235707001023, 5470.316210473691, 4438.378626992224, 4863.781129849165, 4935.457099719028, 5020.322256495399, 5023.949760502422, 3999.9800000999808, 4030.0932290234464, 4079.286124928126, 4126.298529250034, 4135.276326642826, 1199.4902166579168, 1282.2050328796206, 1291.2446218962014, 1299.818025476434, 1309.4827543120605, 864.9264812491009, 910.6949172027313, 947.1333729637443, 950.9286803489628, 964.8890377606525, 5868.941310586925, 5963.314218864886, 6049.3950604939155, 6079.452849243589, 6225.252969643671, 973.0755782007121, 1019.2915923433143, 1022.4938655365493, 1023.4729114506035, 1040.15747244732, 4110.34234522475, 4196.328587425997, 4197.727147735387, 4218.810153543098, 4237.766922819274, 11035.0, 11520.539178432937, 11572.76854462906, 11588.6523404298, 11593.88406115945, 11013.229073964905, 11440.656780296604, 11442.54229830814, 11451.312921224753, 11462.312261264351, 8244.670213191524, 8326.833463330699, 8401.117882622237, 8411.411201215857, 8412.705555305534, 6040.546958978182, 6283.71723272463, 6306.148669929501, 6444.967775161094, 6518.869622607426, 10521.684349469528, 10595.576176952836, 10609.787804244026, 10658.573657053632, 10703.518341674799, 10201.076520510636, 10464.0, 10538.789224215627, 10631.0, 10641.574337026743, 7514.8872766909635, 7831.647575859214, 8077.83844323122, 8161.836763264582, 8362.038365588036, 8888.822223555622, 8921.509316987369, 8971.910280897107, 8972.685955991508, 8975.775605609912, 7522.6614802335125, 7529.661165247687, 7606.277403646644, 7672.961635191788, 7954.284114429729, 7436.516626419266, 7585.506942048749, 7606.505577137468, 7670.8082297943, 7753.806154846174, 7042.084529011196, 7044.260352662896, 7065.788026359218, 7085.787426377217, 7098.219195888551, 5096.566791822736, 5118.334616499831, 5192.948070519246, 5266.920996184984, 5302.0, 5162.380514338303, 5277.445868183939, 5301.257823904733, 5317.548008419328, 5367.033933891937], "value": 4727.436298799593, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.78003915064045, 323.4210762734705, 331.0435327216153, 332.90345799718074, 334.85935906919093, 5312.389075256347, 5368.409474957752, 5370.516653501184, 5386.596005299609, 5418.647787893789, 5058.634168774438, 5132.871678208037, 5306.230596563506, 5330.546903513206, 5352.0, 4192.769397683126, 4214.157168566299, 4223.3031549794405, 4223.894402639928, 4256.914861702772, 4209.2856001278615, 4210.452641156646, 4221.725587836796, 4224.577542245782, 4227.4927008759, 3975.5428125765475, 4023.295923213441, 4177.603127702863, 4196.538380778112, 4227.471566054243, 4199.118185181115, 4206.684498662582, 4211.0946146578635, 4217.177650358181, 4225.450691410112, 3736.1967177057063, 3741.8129093545213, 3745.250949810049, 3759.812009399519, 3766.098120449183, 5121.615878809104, 5196.596229469704, 5199.792008319662, 5231.869203269911, 5246.580273578143, 4714.57568818808, 4774.9283760743265, 4805.255185446226, 4866.853994380174, 4871.853844384674, 5474.178873169017, 5500.42245564218, 5502.284702988586, 5556.249906262643, 5560.36055853578, 3813.036286312551, 4000.0599859033105, 4012.558618551957, 4018.3771515415156, 4054.331035379159, 5651.7739290428335, 5716.542676585905, 5750.281214848144, 5758.596898217126, 5778.0, 4076.30702780528, 4091.774952377648, 4096.877093687194, 4121.443605113332, 4150.169966006811, 4004.619561141687, 4154.833806647761, 4191.91616167675, 4209.915801683949, 4219.556946520633, 4736.64475164364, 4755.429348478136, 4786.497417771154, 4791.544803243686, 4814.831480898151, 5560.582956278215, 5561.972190139023, 5660.264165658438, 5666.17840413146, 5675.0, 1378.3590630356932, 1412.9222892740997, 1434.8852091832837, 1445.9372361314427, 1450.6590951126477, 906.6600024990629, 906.8186362727481, 912.2154946745793, 920.7744102694883, 922.7416323429451, 4857.222844344888, 4858.975705121452, 4894.608431325485, 4897.95102048975, 4912.484189160086, 5006.574441172468, 5035.496450354935, 5061.291419201315, 5131.2559678846355, 5185.585153187736, 4772.284157376352, 4784.856454306377, 4788.11419887315, 4833.4441539223435, 5113.181890897438, 3050.923726906845, 3058.235441139715, 3074.738647215012, 3076.615423072116, 3081.2604974805886, 5891.7611307703655, 5913.379095195029, 5956.285245770535, 5956.791512297049, 5969.701514924236, 5637.182608521824, 5875.177475153482, 5881.911771323434, 5891.263592050994, 5939.198208241918, 5873.295204575478, 5890.440408161217, 5899.380565040695, 5905.43898329658, 5913.319968203626, 5639.548836093185, 5821.70891455417, 5827.9708601458415, 5866.560007999503, 5878.147668588116, 5736.827895163152, 5753.769849205984, 5753.971230143822, 5795.217645617956, 5824.126381042708, 5962.761489540369, 5989.371116032754, 6001.159837622649, 6023.969880150571, 6026.367231440636, 3887.2419878124624, 3938.8227529760657, 4046.10985583183, 4150.501939767248, 4158.979205103955, 5801.94198058014, 5802.970985145047, 5803.419658034331, 5806.854828629318, 5808.883822323445, 5652.802151924663, 5673.34756502991, 5681.545476361964, 5684.175794509855, 5687.061634830372, 5448.0, 5569.805056822992, 5577.832665019893, 5622.269105016322, 5661.886762264814, 5479.945200547943, 5515.255440515479, 5551.8889622208135, 5554.416786237387, 5574.9721251393485, 5348.652337598043, 5624.565735737525, 5664.518515926193, 5678.488935995615, 5704.857378565569, 5500.367457742411, 5507.659244821015, 5507.697076660822, 5508.20131080983, 5521.806736764194], "value": 4241.441415374884, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.3754040457027, 768.3891306411406, 804.4811096842536, 810.9067457242412, 852.6163226548051, 15114.92442537787, 15848.524544263662, 16330.591735206612, 16337.673246535092, 16353.83646163538, 14868.182249976247, 15088.773668394982, 15102.320395582197, 15119.773203401957, 15159.62100947474, 671.2381447057601, 687.6802286936573, 696.9756058537952, 808.5148910653605, 832.2135581875131, 697.3897839390534, 801.1867954026668, 835.6573804740058, 838.8490071787076, 839.95380254086, 873.2097451806126, 873.4060838629729, 876.583622779178, 876.588003638289, 877.6357811508217, 700.1178515070997, 868.0494858130369, 871.2811930157582, 871.3813192633238, 871.9651213951435, 615.0988801405962, 631.207834168116, 635.4789072960175, 635.6281575278422, 635.9554831161821, 15000.849991500054, 15012.0, 15060.774088388684, 15082.622934426727, 15090.0, 14263.215523146222, 14639.341229644691, 14698.853011469962, 14701.926490367587, 14795.40818367264, 12674.302913339763, 12956.0, 13048.86951130486, 13347.065705400626, 13567.86432135676, 4423.51341352451, 4471.955280447219, 4499.437570303713, 4546.227141385973, 4568.70303430276, 78342.6082869582, 78699.0, 78827.21172788256, 79097.0, 79454.60272698599, 11608.76782464346, 11635.185537012498, 11684.415779211005, 11720.531178752753, 12229.14395992272, 21969.450763730714, 21970.670439943413, 22007.0, 22015.449613759465, 22564.0, 7860.960695196487, 7911.327537159291, 8045.678172873137, 8439.789005274795, 8534.189252021048, 4308.073764140725, 4350.260455722535, 4428.092241090569, 4500.122476117192, 4576.496585375606, 3500.7024402925526, 3623.710103191738, 3630.5824830144343, 3638.3450978823535, 3642.3443780119833, 635.3075148088514, 637.9202599675041, 654.554902666182, 667.7162206062501, 684.6337209592843, 727.1347096954607, 732.5897497401472, 766.1419210484182, 766.6818270417714, 790.4980337485738, 9379.812403752023, 9844.409335439897, 10282.742931426774, 10389.896101038892, 10395.94802025985, 684.9075374824334, 708.8794904866185, 742.5223804628749, 764.7629234937187, 774.2992591704516, 4287.957120428819, 4534.659900507475, 4662.696924699883, 4745.667803253808, 4774.880627984328, 108021.0, 111487.0, 112496.0, 114367.0, 114392.0, 105506.47246763717, 105540.47229763956, 106355.4682226584, 107141.0, 107541.0, 91532.54233728789, 92016.0798392021, 92951.53524232336, 93624.0, 93888.53055734678, 8843.60203790818, 8849.69026084084, 8875.112488751074, 8903.19871211594, 9059.184673379428, 49702.25446618376, 50225.49774502208, 50878.74560627173, 50966.49033509766, 52578.73710631422, 45149.0, 48797.0, 74135.62932185305, 74373.25626743663, 74480.62759686167, 12246.448909798903, 12340.074494413135, 12435.962028663173, 12469.189502682297, 12801.807972880228, 13822.308884555932, 14013.789793152908, 14321.28393580296, 14672.019277397414, 14714.705705886037, 12459.93770031144, 12552.309622970824, 12651.746965060463, 12685.175463595206, 12995.935020324838, 12459.377031148588, 12680.619581412573, 12891.0, 13063.412146453242, 13280.0, 12048.277103373826, 12459.501619935101, 12796.680082998, 12919.031072669775, 12950.676233094247, 10010.799784004425, 10082.546285417027, 10089.646862359783, 10253.025962533547, 10353.84469232947, 9811.362261452983, 9940.794309896057, 10830.296030757976, 10836.458177091272, 10874.401907895142], "value": 7542.554840186794, "unit": "score"}], "name": "dromaeo_css", "value": 7497.8171884793555, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 109.0, 109.0, 111.0, 111.0, 111.0, 109.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 122.0, 171.0, 118.0, 118.0, 142.0, 122.0, 150.0, 117.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 159.0, 160.0, 160.0, 161.0, 162.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 78.0, 111.0, 76.0, 74.0, 74.0, 75.0, 108.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 117.0, 84.0, 83.0, 84.0, 118.0, 83.0, 84.0, 128.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 146.0, 147.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 51.0, 51.0, 52.0, 51.0, 50.0, 50.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 74.0, 83.0, 83.0, 80.0, 75.0, 78.0, 80.0, 75.0, 75.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 116.0, 117.0, 116.0, 132.0, 116.0, 125.0, 119.0, 165.0, 119.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 157.0, 158.0, 160.0, 157.0, 158.0, 156.0, 155.0, 154.0, 156.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 64.0, 61.0, 64.0, 62.0, 60.0, 61.0, 61.0, 61.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1471.0, "alertThreshold": 2.0}]} 23:19:36 INFO - Return code: 0 23:19:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:19:36 INFO - # TBPL SUCCESS # 23:19:36 INFO - Running post-action listener: _resource_record_post_action 23:19:36 INFO - ##### 23:19:36 INFO - ##### Finished run-tests step (success) 23:19:36 INFO - ##### 23:19:36 INFO - Running post-run listener: _resource_record_post_run 23:19:36 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 565202944; Read time: 8; Write time: 537660 23:19:36 INFO - TinderboxPrint: CPU usage
13.0% 23:19:36 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:19:36 INFO - TinderboxPrint: I/O write bytes / time
565,202,944 / 537,660 23:19:36 INFO - TinderboxPrint: CPU idle
8,274.0 (87.0%) 23:19:36 INFO - TinderboxPrint: CPU user
1,195.6 (12.6%) 23:19:36 INFO - TinderboxPrint: Swap in / out
0 / 0 23:19:36 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 269238272; Read time: 0; Write time: 306956 23:19:36 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 295964672; Read time: 0; Write time: 230704 23:19:37 INFO - Running post-run listener: _upload_blobber_files 23:19:37 INFO - Blob upload gear active. 23:19:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:19:37 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 23:19:37 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:19:37 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:19:37 INFO - (blobuploader) - INFO - Open directory for files ... 23:19:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:19:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:19:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:38 INFO - (blobuploader) - INFO - Done attempting. 23:19:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:19:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:19:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:46 INFO - (blobuploader) - INFO - Done attempting. 23:19:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:19:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:19:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:47 INFO - (blobuploader) - INFO - Done attempting. 23:19:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:19:47 INFO - Return code: 0 23:19:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:19:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:19:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27cbfa82feffa304399e801542f9bf5789025930afc7fcf6f41588c86fde2ac3a5c8564e582683e67c2ab1ace8ff10d7c5662b8ae12482d4907591746b0995b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac2d51a2e0573d5f15a4b0b0be3ad25b60e20fb0cb920ef7c7f82149f766da533d729ec8e6b1f51b3918d4737b804c70c82a5dee8ed987961e5312ec74d52145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4049c8de29104b8d0636fe0422c6037ea5521b9ab43568cee5c4cac0224f7bfbdd83829312ab79475915b5bd53783383256e32b75c0ac3905d148f73909e0819"} 23:19:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:19:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:19:47 INFO - Contents: 23:19:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27cbfa82feffa304399e801542f9bf5789025930afc7fcf6f41588c86fde2ac3a5c8564e582683e67c2ab1ace8ff10d7c5662b8ae12482d4907591746b0995b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac2d51a2e0573d5f15a4b0b0be3ad25b60e20fb0cb920ef7c7f82149f766da533d729ec8e6b1f51b3918d4737b804c70c82a5dee8ed987961e5312ec74d52145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4049c8de29104b8d0636fe0422c6037ea5521b9ab43568cee5c4cac0224f7bfbdd83829312ab79475915b5bd53783383256e32b75c0ac3905d148f73909e0819"} 23:19:47 INFO - Running post-run listener: copy_logs_to_upload_dir 23:19:47 INFO - Copying logs to upload dir... 23:19:47 INFO - mkdir: /builds/slave/test/build/upload/logs 23:19:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.365994 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-07-18 23:19:47.118445) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 23:19:47.121624) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27cbfa82feffa304399e801542f9bf5789025930afc7fcf6f41588c86fde2ac3a5c8564e582683e67c2ab1ace8ff10d7c5662b8ae12482d4907591746b0995b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac2d51a2e0573d5f15a4b0b0be3ad25b60e20fb0cb920ef7c7f82149f766da533d729ec8e6b1f51b3918d4737b804c70c82a5dee8ed987961e5312ec74d52145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4049c8de29104b8d0636fe0422c6037ea5521b9ab43568cee5c4cac0224f7bfbdd83829312ab79475915b5bd53783383256e32b75c0ac3905d148f73909e0819"} build_url:https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011479 build_url: 'https://queue.taskcluster.net/v1/task/dxFeDfqSTfq0CJd9MiiL4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27cbfa82feffa304399e801542f9bf5789025930afc7fcf6f41588c86fde2ac3a5c8564e582683e67c2ab1ace8ff10d7c5662b8ae12482d4907591746b0995b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ac2d51a2e0573d5f15a4b0b0be3ad25b60e20fb0cb920ef7c7f82149f766da533d729ec8e6b1f51b3918d4737b804c70c82a5dee8ed987961e5312ec74d52145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4049c8de29104b8d0636fe0422c6037ea5521b9ab43568cee5c4cac0224f7bfbdd83829312ab79475915b5bd53783383256e32b75c0ac3905d148f73909e0819"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 23:19:47.147289) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 23:19:47.147616) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468907950.560402-1729784841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 23:19:47.230663) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 23:19:47.231008) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 23:19:47.231339) ========= ========= Total master_lag: 0.17 =========