builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1468992618.48 results: success (0) buildid: 20160719193107 builduid: 7c27c0d302a44bf7970d8d202a339ab2 revision: b925c4294d4467954e53f7a5fb831029b104281d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.477052) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.477520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.477795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.492264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.492581) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 22:30:18-- 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% 220M=0s 2016-07-19 22:30:18 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113851 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.620317) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.620641) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019966 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.707248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:18.707611) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev b925c4294d4467954e53f7a5fb831029b104281d --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 b925c4294d4467954e53f7a5fb831029b104281d --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=5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 22:30:18,948 truncating revision to first 12 chars 2016-07-19 22:30:18,949 Setting DEBUG logging. 2016-07-19 22:30:18,949 attempt 1/10 2016-07-19 22:30:18,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/b925c4294d44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 22:30:19,250 unpacking tar archive at: mozilla-inbound-b925c4294d44/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632192 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:19.353464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:19.353829) ========= script_repo_revision: b925c4294d4467954e53f7a5fb831029b104281d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:19.354257) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:19.354546) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:30:19.364745) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-19 22:30:19.365129) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:30:19 INFO - MultiFileLogger online at 20160719 22:30:19 in /builds/slave/test 22:30:19 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:30:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:30:19 INFO - {'append_to_log': False, 22:30:19 INFO - 'base_work_dir': '/builds/slave/test', 22:30:19 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:30:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:30:19 INFO - 'branch': 'Mozilla-Inbound', 22:30:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:30:19 INFO - 'config_files': ('talos/linux_config.py',), 22:30:19 INFO - 'default_actions': ('clobber', 22:30:19 INFO - 'read-buildbot-config', 22:30:19 INFO - 'download-and-extract', 22:30:19 INFO - 'populate-webroot', 22:30:19 INFO - 'create-virtualenv', 22:30:19 INFO - 'install', 22:30:19 INFO - 'run-tests'), 22:30:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:30:19 INFO - 'download_minidump_stackwalk': True, 22:30:19 INFO - 'download_symbols': 'ondemand', 22:30:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:30:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:30:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:30:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:30:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:30:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:30:19 INFO - 'installer_path': 'installer.exe', 22:30:19 INFO - 'log_level': 'info', 22:30:19 INFO - 'log_name': 'talos', 22:30:19 INFO - 'log_to_console': True, 22:30:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:30:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:30:19 INFO - 'opt_config_files': (), 22:30:19 INFO - 'pip_index': False, 22:30:19 INFO - 'sps_profile': False, 22:30:19 INFO - 'sps_profile_interval': 0, 22:30:19 INFO - 'suite': 'dromaeojs', 22:30:19 INFO - 'system_bits': '32', 22:30:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:30:19 INFO - 'title': 'talos-linux64-ix-051', 22:30:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:30:19 INFO - 'use_talos_json': True, 22:30:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:30:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:30:19 INFO - 'work_dir': 'build'} 22:30:19 INFO - ##### 22:30:19 INFO - ##### Running clobber step. 22:30:19 INFO - ##### 22:30:19 INFO - Running pre-action listener: _resource_record_pre_action 22:30:19 INFO - Running main action method: clobber 22:30:19 INFO - rmtree: /builds/slave/test/build 22:30:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:30:20 INFO - Running post-action listener: _resource_record_post_action 22:30:20 INFO - ##### 22:30:20 INFO - ##### Finished clobber step (success) 22:30:20 INFO - ##### 22:30:20 INFO - ##### 22:30:20 INFO - ##### Running read-buildbot-config step. 22:30:20 INFO - ##### 22:30:20 INFO - Running pre-action listener: _resource_record_pre_action 22:30:20 INFO - Running main action method: read_buildbot_config 22:30:20 INFO - Using buildbot properties: 22:30:20 INFO - { 22:30:20 INFO - "project": "", 22:30:20 INFO - "product": "firefox", 22:30:20 INFO - "script_repo_revision": "production", 22:30:20 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:30:20 INFO - "repository": "", 22:30:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:30:20 INFO - "buildid": "20160719193107", 22:30:20 INFO - "pgo_build": "True", 22:30:20 INFO - "basedir": "/builds/slave/test", 22:30:20 INFO - "buildnumber": 2092, 22:30:20 INFO - "slavename": "talos-linux64-ix-051", 22:30:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:30:20 INFO - "platform": "ubuntu64_hw", 22:30:20 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:30:20 INFO - "branch": "mozilla-inbound", 22:30:20 INFO - "repo_path": "integration/mozilla-inbound", 22:30:20 INFO - "revision": "b925c4294d4467954e53f7a5fb831029b104281d", 22:30:20 INFO - "stage_platform": "linux64-pgo", 22:30:20 INFO - "builduid": "7c27c0d302a44bf7970d8d202a339ab2", 22:30:20 INFO - "slavebuilddir": "test" 22:30:20 INFO - } 22:30:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:30:20 INFO - Running post-action listener: _resource_record_post_action 22:30:20 INFO - ##### 22:30:20 INFO - ##### Finished read-buildbot-config step (success) 22:30:20 INFO - ##### 22:30:20 INFO - ##### 22:30:20 INFO - ##### Running download-and-extract step. 22:30:20 INFO - ##### 22:30:20 INFO - Running pre-action listener: _resource_record_pre_action 22:30:20 INFO - Running main action method: download_and_extract 22:30:20 INFO - mkdir: /builds/slave/test/build/tests 22:30: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:30:20 INFO - https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:30:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:30:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:30:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:20 INFO - Downloaded 1361 bytes. 22:30:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:30:20 INFO - Using the following test package requirements: 22:30:20 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:30:20 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:30:20 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:30:20 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'jsshell-linux-x86_64.zip'], 22:30:20 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:30:20 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:30:20 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:30:20 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:30:20 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:30:20 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:30:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:30: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:30:20 INFO - https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:30:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:30:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:21 INFO - Downloaded 24337877 bytes. 22:30:21 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:30:21 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:30:23 INFO - Return code: 0 22:30:23 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:30:23 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:30:23 INFO - https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:30:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:30:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:24 INFO - Downloaded 24337877 bytes. 22:30:24 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:30:24 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:30:25 INFO - Return code: 0 22:30: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:30:25 INFO - https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:30:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:30:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:30:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:26 INFO - Downloaded 14403317 bytes. 22:30:26 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:30:26 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:30:26 INFO - Return code: 0 22:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:26 INFO - https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:30:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:30:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:30:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CyuouZX4T5SRjagxIVWsTQ/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:30:28 INFO - Downloaded 60861671 bytes. 22:30:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:30:28 INFO - mkdir: /builds/slave/test/properties 22:30:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:30:28 INFO - Writing to file /builds/slave/test/properties/build_url 22:30:28 INFO - Contents: 22:30:28 INFO - build_url:https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:30:28 INFO - Running post-action listener: _resource_record_post_action 22:30:28 INFO - Running post-action listener: set_extra_try_arguments 22:30:28 INFO - ##### 22:30:28 INFO - ##### Finished download-and-extract step (success) 22:30:28 INFO - ##### 22:30:28 INFO - ##### 22:30:28 INFO - ##### Running populate-webroot step. 22:30:28 INFO - ##### 22:30:28 INFO - Running pre-action listener: _resource_record_pre_action 22:30:28 INFO - Running main action method: populate_webroot 22:30:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:30:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:30:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:30:28 INFO - '--tppagecycles', 22:30:28 INFO - '7'], 22:30:28 INFO - 'tests': ['tsvgm']}}, 22:30:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:30:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:30:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:30:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:30:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['damp', 'tps']}, 22:30:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'tests': ['damp', 'tps']}, 22:30:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['dromaeo_dom']}, 22:30:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:30:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['basic_compositor_video']}, 22:30:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:30:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['a11yr', 22:30:28 INFO - 'ts_paint', 22:30:28 INFO - 'tpaint', 22:30:28 INFO - 'sessionrestore', 22:30:28 INFO - 'sessionrestore_no_auto_restore', 22:30:28 INFO - 'tabpaint']}, 22:30:28 INFO - 'other-e10s': {'tests': ['a11yr', 22:30:28 INFO - 'ts_paint', 22:30:28 INFO - 'tpaint', 22:30:28 INFO - 'sessionrestore', 22:30:28 INFO - 'sessionrestore_no_auto_restore', 22:30:28 INFO - 'tabpaint']}, 22:30:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:30:28 INFO - 'ts_paint', 22:30:28 INFO - 'tpaint', 22:30:28 INFO - 'sessionrestore', 22:30:28 INFO - 'sessionrestore_no_auto_restore', 22:30:28 INFO - 'tabpaint']}, 22:30:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:30:28 INFO - 'ts_paint', 22:30:28 INFO - 'tpaint', 22:30:28 INFO - 'sessionrestore', 22:30:28 INFO - 'sessionrestore_no_auto_restore', 22:30:28 INFO - 'tabpaint']}, 22:30:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['a11yr', 22:30:28 INFO - 'ts_paint', 22:30:28 INFO - 'tpaint', 22:30:28 INFO - 'sessionrestore', 22:30:28 INFO - 'sessionrestore_no_auto_restore', 22:30:28 INFO - 'tabpaint']}, 22:30:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['a11yr', 22:30:28 INFO - 'ts_paint', 22:30:28 INFO - 'tpaint', 22:30:28 INFO - 'sessionrestore', 22:30:28 INFO - 'sessionrestore_no_auto_restore', 22:30:28 INFO - 'tabpaint']}, 22:30:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['tsvgx', 22:30:28 INFO - 'tsvgr_opacity', 22:30:28 INFO - 'tart', 22:30:28 INFO - 'tscrollx', 22:30:28 INFO - 'cart']}, 22:30:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:30:28 INFO - 'tsvgr_opacity', 22:30:28 INFO - 'tart', 22:30:28 INFO - 'tscrollx', 22:30:28 INFO - 'cart']}, 22:30:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'talos_options': ['--disable-e10s'], 22:30:28 INFO - 'tests': ['tp5o']}, 22:30:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'tests': ['tp5o']}, 22:30:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'talos_options': ['--disable-e10s', 22:30:28 INFO - '--xperf_path', 22:30:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:28 INFO - 'tests': ['tp5n']}, 22:30:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:28 INFO - 'talos_options': ['--xperf_path', 22:30:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:28 INFO - 'tests': ['tp5n']}}, 22:30:28 INFO - 'talos.zip': {'path': '', 22:30:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:30:28 INFO - Running post-action listener: _resource_record_post_action 22:30:28 INFO - ##### 22:30:28 INFO - ##### Finished populate-webroot step (success) 22:30:28 INFO - ##### 22:30:28 INFO - ##### 22:30:28 INFO - ##### Running create-virtualenv step. 22:30:28 INFO - ##### 22:30:28 INFO - Running pre-action listener: _resource_record_pre_action 22:30:28 INFO - Running main action method: create_virtualenv 22:30:28 INFO - Creating virtualenv /builds/slave/test/build/venv 22:30:28 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:30:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:30:29 INFO - Using real prefix '/usr' 22:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 22:30:31 INFO - Installing pip.................done. 22:30:31 INFO - Return code: 0 22:30:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:30:31 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:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:31 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:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:31 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:31 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:30:31 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:30:31 INFO - Using env: {'DISPLAY': ':0', 22:30:31 INFO - 'HOME': '/home/cltbld', 22:30:31 INFO - 'LANG': 'en_US.UTF-8', 22:30:31 INFO - 'LANGUAGE': 'en_US:en', 22:30:31 INFO - 'LOGNAME': 'cltbld', 22:30:31 INFO - 'MAIL': '/var/mail/cltbld', 22:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:31 INFO - 'MOZ_NO_REMOTE': '1', 22:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:31 INFO - 'NO_EM_RESTART': '1', 22:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:31 INFO - 'PWD': '/builds/slave/test', 22:30:31 INFO - 'SHELL': '/bin/bash', 22:30:31 INFO - 'SHLVL': '1', 22:30:31 INFO - 'TERM': 'linux', 22:30:31 INFO - 'TMOUT': '86400', 22:30:31 INFO - 'USER': 'cltbld', 22:30:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:31 INFO - '_': '/tools/buildbot/bin/python'} 22:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:31 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:30:31 INFO - Cleaning up... 22:30:31 INFO - Return code: 0 22:30:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:30:31 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:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:31 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:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:31 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:31 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:30:31 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:30:31 INFO - Using env: {'DISPLAY': ':0', 22:30:31 INFO - 'HOME': '/home/cltbld', 22:30:31 INFO - 'LANG': 'en_US.UTF-8', 22:30:31 INFO - 'LANGUAGE': 'en_US:en', 22:30:31 INFO - 'LOGNAME': 'cltbld', 22:30:31 INFO - 'MAIL': '/var/mail/cltbld', 22:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:31 INFO - 'MOZ_NO_REMOTE': '1', 22:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:31 INFO - 'NO_EM_RESTART': '1', 22:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:31 INFO - 'PWD': '/builds/slave/test', 22:30:31 INFO - 'SHELL': '/bin/bash', 22:30:31 INFO - 'SHLVL': '1', 22:30:31 INFO - 'TERM': 'linux', 22:30:31 INFO - 'TMOUT': '86400', 22:30:31 INFO - 'USER': 'cltbld', 22:30:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:31 INFO - '_': '/tools/buildbot/bin/python'} 22:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:31 INFO - Downloading/unpacking psutil>=3.1.1 22:30: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:30: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:30: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:30: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:30: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:30: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:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:30:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:33 INFO - Installing collected packages: psutil 22:30:33 INFO - Running setup.py install for psutil 22:30:34 INFO - building 'psutil._psutil_linux' extension 22:30:34 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:30:34 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:30:34 INFO - building 'psutil._psutil_posix' extension 22:30:34 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:30:34 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:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:34 INFO - Successfully installed psutil 22:30:34 INFO - Cleaning up... 22:30:34 INFO - Return code: 0 22:30:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:30:34 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:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:34 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:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:34 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:34 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:30:34 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:30:34 INFO - Using env: {'DISPLAY': ':0', 22:30:34 INFO - 'HOME': '/home/cltbld', 22:30:34 INFO - 'LANG': 'en_US.UTF-8', 22:30:34 INFO - 'LANGUAGE': 'en_US:en', 22:30:34 INFO - 'LOGNAME': 'cltbld', 22:30:34 INFO - 'MAIL': '/var/mail/cltbld', 22:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:34 INFO - 'MOZ_NO_REMOTE': '1', 22:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:34 INFO - 'NO_EM_RESTART': '1', 22:30:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:34 INFO - 'PWD': '/builds/slave/test', 22:30:34 INFO - 'SHELL': '/bin/bash', 22:30:34 INFO - 'SHLVL': '1', 22:30:34 INFO - 'TERM': 'linux', 22:30:34 INFO - 'TMOUT': '86400', 22:30:34 INFO - 'USER': 'cltbld', 22:30:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:34 INFO - '_': '/tools/buildbot/bin/python'} 22:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:30:34 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:30:34 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:30:34 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:30:34 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:30:34 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:30:34 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:30:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:30:36 INFO - Installing collected packages: mozsystemmonitor 22:30:36 INFO - Running setup.py install for mozsystemmonitor 22:30:36 INFO - Successfully installed mozsystemmonitor 22:30:36 INFO - Cleaning up... 22:30:36 INFO - Return code: 0 22:30:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:30: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:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:36 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:36 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:30:36 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:30:36 INFO - Using env: {'DISPLAY': ':0', 22:30:36 INFO - 'HOME': '/home/cltbld', 22:30:36 INFO - 'LANG': 'en_US.UTF-8', 22:30:36 INFO - 'LANGUAGE': 'en_US:en', 22:30:36 INFO - 'LOGNAME': 'cltbld', 22:30:36 INFO - 'MAIL': '/var/mail/cltbld', 22:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:36 INFO - 'MOZ_NO_REMOTE': '1', 22:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:36 INFO - 'NO_EM_RESTART': '1', 22:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:36 INFO - 'PWD': '/builds/slave/test', 22:30:36 INFO - 'SHELL': '/bin/bash', 22:30:36 INFO - 'SHLVL': '1', 22:30:36 INFO - 'TERM': 'linux', 22:30:36 INFO - 'TMOUT': '86400', 22:30:36 INFO - 'USER': 'cltbld', 22:30:36 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:36 INFO - '_': '/tools/buildbot/bin/python'} 22:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:37 INFO - Downloading/unpacking jsonschema==2.5.1 22:30:37 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:30:37 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:30:37 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:30:37 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:30:37 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:30:37 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:30:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:30:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:30:38 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:30:38 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:30:38 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:30:38 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:30:38 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:30:38 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:30:39 INFO - Downloading functools32-3.2.3-2.tar.gz 22:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:30:39 INFO - warning: no files found matching '*.txt' 22:30:39 INFO - no previously-included directories found matching 'build' 22:30:39 INFO - no previously-included directories found matching 'dist' 22:30:39 INFO - no previously-included directories found matching '.git*' 22:30:39 INFO - Installing collected packages: jsonschema, functools32 22:30:39 INFO - Running setup.py install for functools32 22:30:39 INFO - warning: no files found matching '*.txt' 22:30:39 INFO - no previously-included directories found matching 'build' 22:30:39 INFO - no previously-included directories found matching 'dist' 22:30:39 INFO - no previously-included directories found matching '.git*' 22:30:39 INFO - Successfully installed jsonschema functools32 22:30:39 INFO - Cleaning up... 22:30:39 INFO - Return code: 0 22:30:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:30:39 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:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:39 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:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:39 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:39 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:30:39 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:30:39 INFO - Using env: {'DISPLAY': ':0', 22:30:39 INFO - 'HOME': '/home/cltbld', 22:30:39 INFO - 'LANG': 'en_US.UTF-8', 22:30:39 INFO - 'LANGUAGE': 'en_US:en', 22:30:39 INFO - 'LOGNAME': 'cltbld', 22:30:39 INFO - 'MAIL': '/var/mail/cltbld', 22:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:39 INFO - 'MOZ_NO_REMOTE': '1', 22:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:39 INFO - 'NO_EM_RESTART': '1', 22:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:39 INFO - 'PWD': '/builds/slave/test', 22:30:39 INFO - 'SHELL': '/bin/bash', 22:30:39 INFO - 'SHLVL': '1', 22:30:39 INFO - 'TERM': 'linux', 22:30:39 INFO - 'TMOUT': '86400', 22:30:39 INFO - 'USER': 'cltbld', 22:30:39 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:39 INFO - '_': '/tools/buildbot/bin/python'} 22:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:39 INFO - Downloading/unpacking blobuploader==1.2.4 22:30:39 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:30:39 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:30:39 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:30:39 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:30:39 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:30:39 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:30:41 INFO - Downloading blobuploader-1.2.4.tar.gz 22:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:30:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:30: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:30: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:30: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:30: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:30: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:30: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:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:30:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:30: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:30: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:30: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:30: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:30: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:30: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:30:42 INFO - Downloading docopt-0.6.1.tar.gz 22:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:30:42 INFO - Installing collected packages: blobuploader, requests, docopt 22:30:42 INFO - Running setup.py install for blobuploader 22:30:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:30:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:30:42 INFO - Running setup.py install for requests 22:30:42 INFO - Running setup.py install for docopt 22:30:42 INFO - Successfully installed blobuploader requests docopt 22:30:42 INFO - Cleaning up... 22:30:42 INFO - Return code: 0 22:30:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:42 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:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:42 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:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:42 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:42 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:30:42 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:30:42 INFO - Using env: {'DISPLAY': ':0', 22:30:42 INFO - 'HOME': '/home/cltbld', 22:30:42 INFO - 'LANG': 'en_US.UTF-8', 22:30:42 INFO - 'LANGUAGE': 'en_US:en', 22:30:42 INFO - 'LOGNAME': 'cltbld', 22:30:42 INFO - 'MAIL': '/var/mail/cltbld', 22:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:42 INFO - 'MOZ_NO_REMOTE': '1', 22:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:42 INFO - 'NO_EM_RESTART': '1', 22:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:42 INFO - 'PWD': '/builds/slave/test', 22:30:42 INFO - 'SHELL': '/bin/bash', 22:30:42 INFO - 'SHLVL': '1', 22:30:42 INFO - 'TERM': 'linux', 22:30:42 INFO - 'TMOUT': '86400', 22:30:42 INFO - 'USER': 'cltbld', 22:30:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:42 INFO - '_': '/tools/buildbot/bin/python'} 22:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:43 INFO - Running setup.py (path:/tmp/pip-p3YcYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:43 INFO - Running setup.py (path:/tmp/pip-8iEGa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:43 INFO - Running setup.py (path:/tmp/pip-_7PMbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:43 INFO - Running setup.py (path:/tmp/pip-s7ZVgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:43 INFO - Running setup.py (path:/tmp/pip-vKRNxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:43 INFO - Running setup.py (path:/tmp/pip-J73DtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:43 INFO - Running setup.py (path:/tmp/pip-vXylmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:43 INFO - Running setup.py (path:/tmp/pip-If9jve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:43 INFO - Running setup.py (path:/tmp/pip-vaI8N9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:43 INFO - Running setup.py (path:/tmp/pip-CNigsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:43 INFO - Running setup.py (path:/tmp/pip-esMx4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:43 INFO - Running setup.py (path:/tmp/pip-kTFx_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:43 INFO - Running setup.py (path:/tmp/pip-CCrw9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:44 INFO - Running setup.py (path:/tmp/pip-3ijWVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:44 INFO - Running setup.py (path:/tmp/pip-0JTRmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:44 INFO - Running setup.py (path:/tmp/pip-DfOVpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:44 INFO - Running setup.py (path:/tmp/pip-tDDJ0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:30:44 INFO - Running setup.py install for manifestparser 22:30:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for mozcrash 22:30:44 INFO - Running setup.py install for mozdebug 22:30:44 INFO - Running setup.py install for mozdevice 22:30:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for mozfile 22:30:44 INFO - Running setup.py install for mozhttpd 22:30:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for mozinfo 22:30:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Running setup.py install for mozInstall 22:30:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Running setup.py install for mozleak 22:30:45 INFO - Running setup.py install for mozlog 22:30:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Running setup.py install for moznetwork 22:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Running setup.py install for mozprocess 22:30:45 INFO - Running setup.py install for mozprofile 22:30:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Running setup.py install for mozrunner 22:30:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:30:45 INFO - Running setup.py install for mozscreenshot 22:30:45 INFO - Running setup.py install for moztest 22:30:46 INFO - Running setup.py install for mozversion 22:30:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:30:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:30:46 INFO - Cleaning up... 22:30:46 INFO - Return code: 0 22:30:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:46 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:46 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:30:46 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:30:46 INFO - Using env: {'DISPLAY': ':0', 22:30:46 INFO - 'HOME': '/home/cltbld', 22:30:46 INFO - 'LANG': 'en_US.UTF-8', 22:30:46 INFO - 'LANGUAGE': 'en_US:en', 22:30:46 INFO - 'LOGNAME': 'cltbld', 22:30:46 INFO - 'MAIL': '/var/mail/cltbld', 22:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:46 INFO - 'MOZ_NO_REMOTE': '1', 22:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:46 INFO - 'NO_EM_RESTART': '1', 22:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:46 INFO - 'PWD': '/builds/slave/test', 22:30:46 INFO - 'SHELL': '/bin/bash', 22:30:46 INFO - 'SHLVL': '1', 22:30:46 INFO - 'TERM': 'linux', 22:30:46 INFO - 'TMOUT': '86400', 22:30:46 INFO - 'USER': 'cltbld', 22:30:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:46 INFO - '_': '/tools/buildbot/bin/python'} 22:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:46 INFO - Running setup.py (path:/tmp/pip-0qOWuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:46 INFO - Running setup.py (path:/tmp/pip-_5wmsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:46 INFO - Running setup.py (path:/tmp/pip-JhqJPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:46 INFO - Running setup.py (path:/tmp/pip-I4WqjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:46 INFO - Running setup.py (path:/tmp/pip-3eHnfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:46 INFO - Running setup.py (path:/tmp/pip-HpJI__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:46 INFO - Running setup.py (path:/tmp/pip-eKhRsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:46 INFO - Running setup.py (path:/tmp/pip-xx7hVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:46 INFO - Running setup.py (path:/tmp/pip-k0lwui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:47 INFO - Running setup.py (path:/tmp/pip-NAQ9BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:47 INFO - Running setup.py (path:/tmp/pip-NopuW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:47 INFO - Running setup.py (path:/tmp/pip-JR7Hxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:47 INFO - Running setup.py (path:/tmp/pip-SOFPZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:47 INFO - Running setup.py (path:/tmp/pip-tROBDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:47 INFO - Running setup.py (path:/tmp/pip-6aJzIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:47 INFO - Running setup.py (path:/tmp/pip-WIe_Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:47 INFO - Running setup.py (path:/tmp/pip-ANbvw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:49 INFO - Downloading blessings-1.6.tar.gz 22:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:30:49 INFO - Installing collected packages: blessings 22:30:49 INFO - Running setup.py install for blessings 22:30:49 INFO - Successfully installed blessings 22:30:49 INFO - Cleaning up... 22:30:49 INFO - Return code: 0 22:30:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:49 INFO - Reading from file tmpfile_stdout 22:30:49 INFO - Current package versions: 22:30:49 INFO - argparse == 1.2.1 22:30:49 INFO - blessings == 1.6 22:30:49 INFO - blobuploader == 1.2.4 22:30:49 INFO - docopt == 0.6.1 22:30:49 INFO - functools32 == 3.2.3-2 22:30:49 INFO - jsonschema == 2.5.1 22:30:49 INFO - manifestparser == 1.1 22:30:49 INFO - mozInstall == 1.12 22:30:49 INFO - mozcrash == 0.17 22:30:49 INFO - mozdebug == 0.1 22:30:49 INFO - mozdevice == 0.48 22:30:49 INFO - mozfile == 1.2 22:30:49 INFO - mozhttpd == 0.7 22:30:49 INFO - mozinfo == 0.9 22:30:49 INFO - mozleak == 0.1 22:30:49 INFO - mozlog == 3.2 22:30:49 INFO - moznetwork == 0.27 22:30:49 INFO - mozprocess == 0.23 22:30:49 INFO - mozprofile == 0.28 22:30:49 INFO - mozrunner == 6.12 22:30:49 INFO - mozscreenshot == 0.1 22:30:49 INFO - mozsystemmonitor == 0.3 22:30:49 INFO - moztest == 0.7 22:30:49 INFO - mozversion == 1.4 22:30:49 INFO - psutil == 3.1.1 22:30:49 INFO - requests == 1.2.3 22:30:49 INFO - wsgiref == 0.1.2 22:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:49 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 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:49 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:30:49 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:30:49 INFO - Using env: {'DISPLAY': ':0', 22:30:49 INFO - 'HOME': '/home/cltbld', 22:30:49 INFO - 'LANG': 'en_US.UTF-8', 22:30:49 INFO - 'LANGUAGE': 'en_US:en', 22:30:49 INFO - 'LOGNAME': 'cltbld', 22:30:49 INFO - 'MAIL': '/var/mail/cltbld', 22:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:49 INFO - 'MOZ_NO_REMOTE': '1', 22:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:49 INFO - 'NO_EM_RESTART': '1', 22:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:49 INFO - 'PWD': '/builds/slave/test', 22:30:49 INFO - 'SHELL': '/bin/bash', 22:30:49 INFO - 'SHLVL': '1', 22:30:49 INFO - 'TERM': 'linux', 22:30:49 INFO - 'TMOUT': '86400', 22:30:49 INFO - 'USER': 'cltbld', 22:30:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:49 INFO - '_': '/tools/buildbot/bin/python'} 22:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 INFO - Cleaning up... 22:30:50 INFO - Return code: 0 22:30:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', '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:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:30:50 INFO - Using env: {'DISPLAY': ':0', 22:30:50 INFO - 'HOME': '/home/cltbld', 22:30:50 INFO - 'LANG': 'en_US.UTF-8', 22:30:50 INFO - 'LANGUAGE': 'en_US:en', 22:30:50 INFO - 'LOGNAME': 'cltbld', 22:30:50 INFO - 'MAIL': '/var/mail/cltbld', 22:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:50 INFO - 'MOZ_NO_REMOTE': '1', 22:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:50 INFO - 'NO_EM_RESTART': '1', 22:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:50 INFO - 'PWD': '/builds/slave/test', 22:30:50 INFO - 'SHELL': '/bin/bash', 22:30:50 INFO - 'SHLVL': '1', 22:30:50 INFO - 'TERM': 'linux', 22:30:50 INFO - 'TMOUT': '86400', 22:30:50 INFO - 'USER': 'cltbld', 22:30:50 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:50 INFO - '_': '/tools/buildbot/bin/python'} 22:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:30:50 INFO - Cleaning up... 22:30:50 INFO - Return code: 0 22:30:50 INFO - Running post-action listener: _resource_record_post_action 22:30:50 INFO - Running post-action listener: _start_resource_monitoring 22:30:50 INFO - Starting resource monitoring. 22:30:50 INFO - ##### 22:30:50 INFO - ##### Finished create-virtualenv step (success) 22:30:50 INFO - ##### 22:30:50 INFO - ##### 22:30:50 INFO - ##### Running install step. 22:30:50 INFO - ##### 22:30:50 INFO - Running pre-action listener: _resource_record_pre_action 22:30:50 INFO - Running main action method: install 22:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:50 INFO - Reading from file tmpfile_stdout 22:30:50 INFO - Detecting whether we're running mozinstall >=1.0... 22:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:30:50 INFO - Reading from file tmpfile_stdout 22:30:50 INFO - Output received: 22:30:50 INFO - Usage: mozinstall [options] installer 22:30:50 INFO - Options: 22:30:50 INFO - -h, --help show this help message and exit 22:30:50 INFO - -d DEST, --destination=DEST 22:30:50 INFO - Directory to install application into. [default: 22:30:50 INFO - "/builds/slave/test"] 22:30:50 INFO - --app=APP Application being installed. [default: firefox] 22:30:50 INFO - mkdir: /builds/slave/test/build/application 22:30:50 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:30:50 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 22:31:07 INFO - Reading from file tmpfile_stdout 22:31:07 INFO - Output received: 22:31:07 INFO - /builds/slave/test/build/application/firefox/firefox 22:31:07 INFO - Running post-action listener: _resource_record_post_action 22:31:07 INFO - ##### 22:31:07 INFO - ##### Finished install step (success) 22:31:07 INFO - ##### 22:31:07 INFO - ##### 22:31:07 INFO - ##### Running run-tests step. 22:31:07 INFO - ##### 22:31:07 INFO - Running pre-action listener: _resource_record_pre_action 22:31:07 INFO - Running main action method: run_tests 22:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:31:07 INFO - Python 2.7.3 22:31:07 INFO - Return code: 0 22:31:07 INFO - grabbing minidump binary from tooltool 22:31:07 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:31:07 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 0x12ec3b0>, '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 0x12ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ed740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:31:07 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 22:31:07 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 22:31:07 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 22:31:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:31:08 INFO - Return code: 0 22:31:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:31:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:31:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:31:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:31:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:31:08 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/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 22:31:08 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/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 22:31:08 INFO - Using env: {'DISPLAY': ':0', 22:31:08 INFO - 'HOME': '/home/cltbld', 22:31:08 INFO - 'LANG': 'en_US.UTF-8', 22:31:08 INFO - 'LANGUAGE': 'en_US:en', 22:31:08 INFO - 'LOGNAME': 'cltbld', 22:31:08 INFO - 'MAIL': '/var/mail/cltbld', 22:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:08 INFO - 'MOZ_NO_REMOTE': '1', 22:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:08 INFO - 'NO_EM_RESTART': '1', 22:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:08 INFO - 'PWD': '/builds/slave/test', 22:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:31:08 INFO - 'SHELL': '/bin/bash', 22:31:08 INFO - 'SHLVL': '1', 22:31:08 INFO - 'TERM': 'linux', 22:31:08 INFO - 'TMOUT': '86400', 22:31:08 INFO - 'USER': 'cltbld', 22:31:08 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247', 22:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:08 INFO - '_': '/tools/buildbot/bin/python'} 22:31:08 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/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 22:31:08 INFO - mozversion application_buildid: 20160719193107 22:31:08 INFO - mozversion application_changeset: b925c4294d4467954e53f7a5fb831029b104281d 22:31:08 INFO - mozversion application_display_name: Nightly 22:31:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:31:08 INFO - mozversion application_name: Firefox 22:31:08 INFO - mozversion application_remotingname: firefox 22:31:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:31:08 INFO - mozversion application_vendor: Mozilla 22:31:08 INFO - mozversion application_version: 50.0a1 22:31:08 INFO - mozversion platform_buildid: 20160719193107 22:31:08 INFO - mozversion platform_changeset: b925c4294d4467954e53f7a5fb831029b104281d 22:31:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:31:08 INFO - mozversion platform_version: 50.0a1 22:31:08 INFO - using testdate: 1468992668 22:31:08 INFO - actual date: 1468992668 22:31:08 INFO - starting webserver on 'localhost:57259' 22:31:08 INFO - SUITE-START | Running 2 tests 22:31:08 INFO - TEST-START | dromaeo_css 22:31:08 INFO - operating with platform_type : linux_ 22:31:08 INFO - Initialising browser for dromaeo_css test... 22:31:08 INFO - TEST-INFO | started process 20075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppF1aau/profile http://localhost:57259/getInfo.html) 22:31:12 INFO - PROCESS | 20075 | __metrics Screen width/height:1600/1200 22:31:12 INFO - PROCESS | 20075 | colorDepth:24 22:31:12 INFO - PROCESS | 20075 | Browser inner width/height: 1024/697 22:31:12 INFO - PROCESS | 20075 | __metrics 22:31:12 INFO - PROCESS | 20075 | 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" 22:31:12 INFO - PROCESS | 20075 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:31:13 INFO - TEST-INFO | 20075: exit 0 22:31:13 INFO - Browser initialized. 22:31:13 INFO - Running cycle 1/1 for dromaeo_css test... 22:31:13 INFO - TEST-INFO | started process 20157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppF1aau/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:31:25 INFO - PROCESS | 20157 | RSS: Main: 156835840 22:31:25 INFO - PROCESS | 20157 | 22:32:17 INFO - PROCESS | 20157 | 1468992737238 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] 22:34:25 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:57259/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57259/tests/dromaeo/cssquery-ext.html) 22:34:26 INFO - PROCESS | 20157 | RSS: Main: 255037440 22:34:26 INFO - PROCESS | 20157 | 22:37:28 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:57259/tests/dromaeo/cssquery-ext.html (next: http://localhost:57259/tests/dromaeo/cssquery-jquery.html) 22:37:29 INFO - PROCESS | 20157 | RSS: Main: 244363264 22:37:29 INFO - PROCESS | 20157 | 22:40:31 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:57259/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57259/tests/dromaeo/cssquery-mootools.html) 22:40:31 INFO - PROCESS | 20157 | RSS: Main: 225734656 22:40:31 INFO - PROCESS | 20157 | 22:43:33 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:57259/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57259/tests/dromaeo/cssquery-prototype.html) 22:43:34 INFO - PROCESS | 20157 | RSS: Main: 211992576 22:43:34 INFO - PROCESS | 20157 | 22:46:37 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:57259/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57259/tests/dromaeo/cssquery-yui.html) 22:46:37 INFO - PROCESS | 20157 | RSS: Main: 183746560 22:46:37 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:57259/tests/dromaeo/cssquery-yui.html (next: http://localhost:57259/tests/dromaeo/cssquery-dojo.html) 22:49:40 INFO - PROCESS | 20157 | RSS: Main: 274268160 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | __start_tp_report 22:49:40 INFO - PROCESS | 20157 | _x_x_mozilla_page_load 22:49:40 INFO - PROCESS | 20157 | _x_x_mozilla_page_load_details 22:49:40 INFO - PROCESS | 20157 | |i|pagename|runs| 22:49:40 INFO - PROCESS | 20157 | |0;dojo.html;3037.9240518987035;3047.8476076196193;3088.197068762122;3088.891888783893;3103.5344698295253;5155.510226528483;5253.4221235664045;5328.227407025979;5452.482014208654;5464.207689884969;5316.627836051478;5324.227986941891;5388.353397592285;5407.405185429609;5472.699001554913;4846.369971903657;4934.629902757308;4943.382077240345;4949.579285760715;5083.974580127094;4999.275105109757;5020.497950204968;5041.445441001487;5056.974715126437;5061.924071138936;4814.085323788487;4850.611951043908;4920.507949205069;4928.654994150411;4946.554810067075;4843.806247750086;4862.246351815475;4863.416390033219;4868.561829435368;4973.055119527278;4886;4914.11545921734;4917.016596680678;4931.7040977541465;4966.776495057731;5190.221466779975;5229.189475631283;5261.815836445706;5327.760250788724;5460.563154947595;5733.512651424634;5775.971120144414;5787.305523337226;5789.797357092481;5792.768289268466;5782.3639399666;5817.272840894888;5826.329972053226;5834.766609335621;5836;5211.452797456288;5371.033214021474;5431.701256430894;5432.483914028161;5435.456454354572;6004.42957918997;6040.335563088057;6049.062395328731;6060.575759696779;6062.9996050652035;5777.91333129995;5783.2192653992015;5785.305763308345;5788.21859049023;5788.855278618068;5547.528460080938;5731.914021289684;5773.422657734193;5891.351951285441;5899.67551784656;5811.564132690065;5849.970750146307;5862.091375836752;5862.091375836752;5865.061590145641;5772.9711351442975;5778.451047150514;5828.883422331614;5834.358220595731;5842.532597392198;4333.696641235147;4429.9778501107285;4436.667249956202;4443.178012067778;4472.731636101845;3852.325842977468;3874.4575759393706;3880.7865567394065;3883.8446462141765;3944.881653550398;5436.3476382834315;5439.564834813205;5442.2380866678695;5443.156310771837;5445.891082178334;5754.913676294859;5758.049921762946;5763.337216220105;5765.048766953406;5804.65172089676;5268.683878967274;5406.648567843077;5445.727713614383;5458.863528411822;5532.75102620383;5000.574951129122;5011.373578302712;5013.874653133628;5014.598832093497;5056.039352523009;5943.108533719977;5989.760409583655;5992.0712289595185;5992.131140984533;6000.429959153874;5599.04816181242;5662.178984047207;5662.490375866192;5664.376918538875;5793.3337666167245;5896.115582662635;5900.881982360414;5904.822855314347;5905.645661260338;5905.852353691193;5587.050201465842;5607.102863541813;5624;5634.690092044977;5652.576056795675;5573.944260557342;5656.85857853557;5661.207430959586;5672.716364181857;5767.452092051249;5603.719814009365;5609.242752228397;5614.101743720985;5614.775408983758;5615.241942337895;5570.5265052470995;5610.0182468067105;5620.1288800236025;5623.128415095667;5633.887322253614;5356.892862142813;5562.388137304974;5610.046292130429;5624;5720.227769251098;5764.913526297193;5767.913481297868;5768.884622307614;5817.592768506164;5819.156222347651;5638.887222255614;5649.971750141223;5653.632513886749;5653.660780353192;5654.208410822406 22:49:40 INFO - PROCESS | 20157 | |1;ext.html;591.3022633292716;624.8968920128177;629.5058379172349;648.9383508566688;662.7017841971115;30158.84920575397;30647.161170329797;33217;33317.500237496424;42997.140057198914;39492.21015579686;43478.34782478266;43657.34513982293;43783.34324985128;43832.12335753283;8956.686515971925;9083.500407477586;9107.590158442887;9110.681126160585;9113.043130471304;9016.504092274923;9097.044810294923;9111.179993800557;9111.22554582858;9112.225460835836;9096.818063638722;9134.543272836365;9144.314176436761;9148.2681385489;9154.313426493007;9080.727578172633;9126.543672816399;9139;9141.725748227564;9141.817163656757;8909.866352004725;8999.730008099768;9005.054469280696;9018.458892466473;9023.954880225623;41188.1762364754;41458.79270603658;41468;41519.37720934188;41592.37611435831;3630.5824830144606;4197.706160568761;4220.725652832587;4236.131593023423;4345.391645169679;31525;31585.526217106762;32025;32349.191270218198;33085.66914330874;4404.4934832494355;4455.335263978608;4508.2561377372695;4515.390422293014;4559.430071241095;352292;352431;352940;353249;353900;56820.43179568234;57318;57406;57429;57758.8448231033;60324.09513857296;60584;60675;60794;60842.391576083675;17252.568685782706;17467.563310917074;17485.65028699419;17504.12479376026;18012.279508819764;13553.59339219825;13703.314834258446;13717.931410343084;13728.382222799997;13730.588082357544;19459.346165170442;19496.525959289178;19609.23516883487;19617.215311387383;19643.508912277306;7147.678354474061;7153.6780844862105;7365.674178647895;7414.221506741822;7452.776416707507;976.7216343342113;995.7361299255703;1005.4671024357102;1109.5118148014847;1127.9436028198556;31164.68835311618;33507.83246083754;33634.32731345359;33642.831785840914;33650.49524257138;1127.0870594818223;1143.2797337677375;1154.9480273387717;1155.5551112821624;1206.324458303353;17306.22122004513;17448.946434373935;17665.205065772072;17689.823101769074;17732.11339433023;574051;582692;582925;583794;584550;60662.090068649006;60965;61140;61292.38707612956;61368.69315653393;1258.7545428641326;1347.447546505928;1369.7876829091506;1380.9918759305563;1400.2018849255826;31678.841605791822;34031;34127.31745365029;34385.07520342311;34392.65607343894;51002.23496647627;51514.48485515097;51624.48375516197;51739.48260517346;51778;323588;323697;324401;324564;325997;25092.372690682878;27194;28298.85850570734;28318.716812831608;28673.42653146884;47380.763096184295;47997;48001.27998080102;48641;49051.26423103585;41361.172776543695;41771.37342939797;42316.576834231266;42791;43690.34464482972;41909;41912;42021.15957680768;43232.78383608188;43825.34261986137;41560.1687966245;41581.37627935644;43026.139477210905;43379.566204337556;45397.773011134734;33885;37855.242895142495;37921.24157516779;37956;38298.23403531858;29057.41885162327;29542;29611.851940740158;29818.850905745334;29858 22:49:40 INFO - PROCESS | 20157 | |2;jquery.html;389.6006593241927;390.50600989747966;393.88577312579315;396.8432469174676;417.4718980489681;14570.927145364285;18976.525586860334;19386.51533711658;19878.70181947272;19893.502662433428;18383.81616183838;19343.613127737437;19382.806171938275;19414.320498782545;19416.4175074748;5623.943760562393;6971.511994160411;7281.308275713825;7287.6356182190675;7313.597752123631;7088.255733148023;7130.8573828523395;7138.428925685958;7185.6766445510075;7383.9630801845915;7403.5557866527915;7420;7502.474826762129;7507.4369422293275;7512.211217822132;7194.640267986606;7351.632418379087;7374.262573742636;7381.667824947864;7388.852222955511;7000.824979375557;7078.115235595551;7149.106361704787;7248.71005159793;7262.745803896891;25800.225993220232;26029.349266268306;26138.86930565354;26363.868180659167;26585.46829063427;2401.519696060795;2414.2153800015067;2627.6846778386525;2630.408158164417;2998.925026874324;15059.096454212782;15371.923140384226;15821.762673559906;15941.202939853078;16470.541325877;1877.4649224970954;1990.263602467082;2093.5394213273057;2104.326615483046;2142.8500004999655;97377.5131124347;97872.51063744635;99336.50331748367;102928.48535757349;105096.47451762769;16094.517164485083;17585.20866561008;17757.7336339955;18174.909125454287;18391;25982.74017259803;28718.282042948675;31268.530972035438;31277.687223127934;31625.525617115763;10326.896731032593;10361.326513776581;10415;10553.630622928315;10603.416812075257;8740.694075707319;8772;8811.427257228259;9019.864702029476;9041.457512549268;8603.698870539502;8656.78358041054;8670.913290867136;8795.780105497413;8929.107089291148;3036.9392612148076;3225.6774322567558;3233.531137984979;3413.4026545354463;3505.4391297392294;911.462237280008;969.5588507229194;980.9944806573177;1005.3264312910368;1042.9582816687248;18045.458636240935;19251.32620358281;19358.4192474226;19511.609767804566;19515.902420487808;943.8253923024263;961.0677642686583;1019.5157300282307;1080.9243352965375;1089.8528698625585;10072.446015469219;10512.57949682004;10602.09882160025;10608.257421980386;10661.626843060456;492376;517711;551153;567540;572273;93394;94593;95994.52002739941;96087;98519.50740246396;955.8375076237056;963.1716723617683;982.2633025231077;984.522506584306;1021.443313394187;15778.842211577738;16699.833001670315;17360.305587776347;17538.947663140254;17574.560635984202;34704;36536.81731591431;40381.394279086424;44835.55164448444;45195.25449827721;51414;58736.4126358748;60908;61374;62667;7800.570968596781;7819.687212511663;8024.438289319692;8146.633401497065;8177;11783.587574434854;12316;12409.565665201675;12411.44148513301;12527.686807829878;10883.727906802393;11041.227114101936;11132.833007504732;11230.66308010761;11401.942990284995;10581.62964296246;10697;11006.449677516252;11058.39178845139;11104;10837.566497340018;11254.606088786853;11403.486843091914;11507.252028618113;11511.53953841837;7199.3520583147765;7499.475036747375;7561.886571701539;8243.216894395024;8285.260095380037;8746.956265218632;10604.522796474332;11287.548498059985;11367.602133925273;11694.122940779305 22:49:40 INFO - PROCESS | 20157 | |3;mootools.html;1513.477850141701;1547.3887814313346;1692.5091723400215;1738.843504084632;1813.2565648084287;8861.778455538606;9445.574949127289;9468;9474.526273686313;9478.099580539854;8024.91975080249;8239.093699693029;8240.752777416687;8401.579921003957;9282.953585232064;2254.425121593991;2333.4166458385403;2336.941576460585;2337.731160916491;2338.5790557699606;2281.942951426211;2306.2043594959714;2319.7912187903084;2331.184085570054;2331.895064722083;1996.520834999597;2283.5547068321684;2295.345826439465;2305.5734689082487;2306.988465057681;2290.5876942150408;2293.816494680422;2300.1719381022817;2311.3759284992957;2318.5015221727326;2235.4970131720397;2266.3994041578994;2266.9319920402413;2271.432141964509;2272.4546108933737;9300.953495232481;9415.858762118574;9423.90576094237;9437.811243775086;9477.147056764856;5082.542571168613;5177.559907407875;5223.477652234784;5233.005728911533;5233.633645644768;3142.3244002539336;3296.2748195396975;3301.4057469655454;3320.452125399306;3359.882404115844;1034.9559619750933;1064.084886997182;1079.5088234853124;1081.4971038467152;1093.7101668057971;12792.552260670926;12821.871781282161;12854.935725321408;12857.871421285854;12872.22766634005;4301.526832048472;4458.375827384169;4604.37840891482;4613.031263434649;4616.0767846430845;10697.62558310471;10732.141428685842;10754.838677419844;10758.0855627271;10837.187210959051;5181.326427564468;5205.869853253699;5211.530962213419;5217;5218.425973142951;4096.6481061249715;4727.57451829337;4792.233242681224;4796.016816552668;4797.352357431771;4163.2298024865495;4171.624553790173;4175.498940127143;4241.893952651209;4298.78506074695;1286.3889652415214;1320.518010926005;1321.1478596305521;1329.069651244133;1348.1506650809927;827.9319677615929;862.2369203255164;894.673444192865;897.9057198994011;953.7329547726648;5791.3339965903615;6391.872162556723;6489.70796314167;6509.218893732687;6661.800145995628;424.23637452585837;424.5096913065414;430.3243907065877;431.8445359670516;432.04302470029035;4285.935710964338;4307.18163548931;4339.5660433956355;4354.238008348526;4385.101054283864;11342.716432089099;11644.126690498246;11756.23584467007;11780.11649126319;11781.646550603497;11616.070714342832;11619.186656933933;11625.709357266136;11631.418429078512;11635.127365447624;7797.92202077972;8062.072861620989;8104.149064348158;8191.713290034821;8311.129995750907;6421.389967953037;6451.580647257913;6577.901331480128;6681.247565688963;6707.262201157966;10782.838257425989;10877.238593298393;10963;11042.061424778662;11072.335659860435;10838.458077096271;10897.346159230472;11094.833577496505;11218.66344009681;11228.101751859995;7407.962960185165;7695.653695583824;7832.960835195788;7893.486923349964;7908.762737117896;8909.509976951329;9121.270298376247;9174.128457796498;9359.251259899056;9364.016778238187;7671.846563068819;7683.270089341504;7784.143744188022;7800.297973182214;8035.919640803517;7378.557286562609;7652.617369131633;7696.499727517694;7717.498362606412;7801.570913599807;7479.289467500579;7554.584497852451;7586.203448637813;7610.695572177272;7613.9238607613215;5173.3533308336455;5269.394019687785;5269.631125821155;5314.840554783363;5502;5323.920141197963;5344.545713614386;5346.812861549983;5350.438203988525;5509.449055094458 22:49:40 INFO - PROCESS | 20157 | |4;prototype.html;310.8523451360604;316.9968300316997;319.98720051197955;332.86851693581036;334.06461906661343;5111.284420181169;5229.346331708536;5359.330083739533;5402.594805389596;5413.783448662049;5355.651882627626;5364.275822763926;5379.784808607651;5396.325459317585;5407.918881216785;4171.416001759756;4181.268278051344;4196.727212731178;4201.054762678388;4225.112726327475;4097.426360309544;4114.876553703393;4146.9792651036705;4180.157492904824;4221.472315960505;4093.2427500912427;4106.876793696194;4128.731632443896;4130.8554200602985;4132.524759652649;4123.154753275571;4123.8969025774295;4130.8554200602985;4134.3178375567995;4156.958430415687;3682.447632855093;3689.335919534483;3700.777953322809;3702.7037836972977;3709.3879509880835;5051.166557518005;5127.230915362726;5166.405863325728;5169.638125331228;5186.1442861927735;4746.881327966794;4750.7410536207935;4755.42934847817;4768.785404656799;4772.832950846739;5386.946130538684;5530.778768849241;5555.472230138131;5561.332640083176;5597.63615365;3971.126352202511;3987.4616926715103;4003.459532963042;4020.9396859047138;4026.2148880968225;5714.685692286923;5746.913796293059;5751;5753.942460575425;5757.222774925415;4112.280350938574;4113.1568028554075;4137.8758637240935;4139.965008747813;4163.854265100707;3856.614338566177;4031.274370613259;4039.4344791728595;4039.5758445362812;4188.8324467020975;4618.376519169936;4634.9536504634525;4646;4652.999605084923;4676.158291507493;5555.833325000256;5562.193481945095;5572.972135139298;5574.9721251393485;5608.579356548275;1328.1234385305652;1342.939567719455;1383.8514033352294;1407.429990853706;1415.344047464469;935.5266235284914;947.6446332625266;956.3687965942445;962.5427921737119;976.8437050072097;5334.253204551366;5347.304850369427;5349.598780091509;5359.330083739533;5372.704501252469;5593.133064375028;5601.187827765033;5640.125780504029;5645.745941432563;5649.06790379591;5168.7415629218385;5284.550813180846;5298.648844544617;5320.2817619620855;5328.973355133277;2658.2025392382207;2664.7601715845667;2677.410969586727;2691.380982374065;2702.2703869955003;5162.328897243335;5176.8964620707375;5191.299174611379;5208.557272631869;5241.633085684041;4818.538014010403;5033;5057.317262169634;5077.771500282496;5099.821506247264;5057.974710126499;5088.262201980692;5109.310243117132;5138.280640710229;5159.787449949259;4980;5016.347874776256;5026.572741317029;5043.672161309503;5084.339035925306;4979.3526841510375;5275.525202731773;5681.573881958787;5703.63112852904;5732.369439361583;5847.707614619167;5875.823725288248;5903.203067585821;5915.171875937286;5948.732307046107;3954.3475326570792;3955.268275369066;3970.146418520032;3983.521977362619;3993.781896521589;5461.890762184655;5464.508194262536;5667.716614169192;5703.686297253691;5761.567882408753;5472.507474327171;5574.136008918624;5578.219049333015;5592.86017849557;5620.4098569649595;5572.60991730575;5575.414581469049;5579.637323573955;5596.384397716167;5600.60795744294;5313.840584782308;5525.696086715269;5544.861378465571;5560.249366335493;5564.38791732901;5594.916076258778;5644.435556444323;5649.689267090185;5650.745716442688;5664.320281566238;5468.863278418072;5521.337439507285;5530.170474428868;5571.387147413862;5579.972100139474 22:49:40 INFO - PROCESS | 20157 | |5;yui.html;707.6956908529332;757.1103952855393;758.4842307231085;795.9641816118274;816.3061397811864;15789.28948197331;16362.509124726248;16540.42108526202;16615.833841661595;16991;14824.55526334209;15096.849031509682;15112.319945602447;15140.84859151408;15163.469278575252;673.5621845800245;794.0947320055142;814.8573999550084;825.9876101858476;826.0252901576138;819.639358682181;842.759813453166;844.2823599940064;846.1749793950879;846.8221673448584;896.5875697179305;897.824924139793;899.5232526760794;899.5457294066488;899.9595018224163;709.2163159708499;860.6428332242112;887.6316328723572;893.4594570284982;893.6068130022802;632.3581564711775;633.7148283272546;635.71710588788;635.8283263518824;637.2671427857954;14991;15013.474528391454;15016.399344026226;15031.924840375837;15049.398024079024;14221.573352799433;14525.418983240657;14715.92642036783;14732;14902.478413255592;13675.179489230679;13721.794173087412;13764.105333153313;13802.240876751875;13818.378172982138;4324.135172965388;4336.56634336567;4440.7335559866515;4471.195184866723;4521.886952826173;86795.56602216949;87550.562247189;88633;90716.54641726815;90948.54525727395;11437.542498299976;11441.599544015919;11451.942740286246;11499.137564682514;11534;22264;22314.442138946655;22322.776772232395;22341.218057367914;22350.329490115324;8522.403431759842;8598.613062412207;8710.25962793157;8732.301415886714;8766.298696104297;3945.427912952663;3955.6044395560216;3956.1692044670367;3970.4639873616693;4034.334334834778;3449.9482507762405;3494.790312581253;3512.7014203792965;3525.136341596325;3566.0728210665416;617.6633734614646;618.2827919613227;619.69634878909;626.0765371077715;640.7467468259352;703.5919166883195;718.2745427118648;725.5175308419841;734.4858598980735;747.0661672908864;9368.016358282419;10012.899871001342;10020.448875311928;10026.398416095057;10029.147522460527;588.5350573047244;695.4540685561809;722.757876111498;733.2813842434444;776.3245976000916;4596.908061838812;4602.539746025371;4834.468208497063;5082.771275292621;5164.7934082636275;72438.63780681063;77963;127103;127645;132594;101375;105774.47112764388;105853.47073264584;106561;109374;92666.53666731624;92735.53632231796;96312;96612;97494;9332.72001839946;9568.95215523918;9671.951640242036;9791.804163916824;9802.077267999446;54196;55421.999999998385;55813;56244;57598.712006441376;80548;82776.58611706903;84442.15557844343;87210;87598.56200718955;13228.66928326761;13485.932570337085;13720.382582783599;13762.00998038712;14205.360758766086;15482.303296351916;15555.45555905538;15605.921970390076;15644;16179;13701.45194192221;14267.785983210468;14346.641333966318;14775.92612036933;15263.847361526687;14904.552863414116;15209.543713688607;15406;15426.30581623852;15637.452689155825;13674.658133546343;14380.496682616058;14480.203588802717;14763.114213147246;14831.777523336943;11862.762744744885;12336.691582710146;12474.625761227178;12610.684732881751;12819.359032048174;10560.84158737635;11618.405749560243;11643;11725.530978760755;11751.823722643996 22:49:40 INFO - PROCESS | 20157 | __end_tp_report 22:49:40 INFO - PROCESS | 20157 | __start_cc_report 22:49:40 INFO - PROCESS | 20157 | _x_x_mozilla_cycle_collect,917 22:49:40 INFO - PROCESS | 20157 | __end_cc_report 22:49:40 INFO - PROCESS | 20157 | __startTimestamp1468993780798__endTimestamp 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | ------- Summary: start ------- 22:49:40 INFO - PROCESS | 20157 | Number of tests: 6 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | [#0] dojo.html Cycles:165 Average:5364.95 Median:5603.72 stddev:620.12 (11.1%) stddev-sans-first:594.54 22:49:40 INFO - PROCESS | 20157 | Values: 3037.9 3047.8 3088.2 3088.9 3103.5 5155.5 5253.4 5328.2 5452.5 5464.2 5316.6 5324.2 5388.4 5407.4 5472.7 4846.4 4934.6 4943.4 4949.6 5084.0 4999.3 5020.5 5041.4 5057.0 5061.9 4814.1 4850.6 4920.5 4928.7 4946.6 4843.8 4862.2 4863.4 4868.6 4973.1 4886.0 4914.1 4917.0 4931.7 4966.8 5190.2 5229.2 5261.8 5327.8 5460.6 5733.5 5776.0 5787.3 5789.8 5792.8 5782.4 5817.3 5826.3 5834.8 5836.0 5211.5 5371.0 5431.7 5432.5 5435.5 6004.4 6040.3 6049.1 6060.6 6063.0 5777.9 5783.2 5785.3 5788.2 5788.9 5547.5 5731.9 5773.4 5891.4 5899.7 5811.6 5850.0 5862.1 5862.1 5865.1 5773.0 5778.5 5828.9 5834.4 5842.5 4333.7 4430.0 4436.7 4443.2 4472.7 3852.3 3874.5 3880.8 3883.8 3944.9 5436.3 5439.6 5442.2 5443.2 5445.9 5754.9 5758.0 5763.3 5765.0 5804.7 5268.7 5406.6 5445.7 5458.9 5532.8 5000.6 5011.4 5013.9 5014.6 5056.0 5943.1 5989.8 5992.1 5992.1 6000.4 5599.0 5662.2 5662.5 5664.4 5793.3 5896.1 5900.9 5904.8 5905.6 5905.9 5587.1 5607.1 5624.0 5634.7 5652.6 5573.9 5656.9 5661.2 5672.7 5767.5 5603.7 5609.2 5614.1 5614.8 5615.2 5570.5 5610.0 5620.1 5623.1 5633.9 5356.9 5562.4 5610.0 5624.0 5720.2 5764.9 5767.9 5768.9 5817.6 5819.2 5638.9 5650.0 5653.6 5653.7 5654.2 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | [#1] ext.html Cycles:180 Average:58646.47 Median:23109.43 stddev:115570.96 (500.1%) stddev-sans-first:115812.97 22:49:40 INFO - PROCESS | 20157 | Values: 591.3 624.9 629.5 648.9 662.7 30158.8 30647.2 33217.0 33317.5 42997.1 39492.2 43478.3 43657.3 43783.3 43832.1 8956.7 9083.5 9107.6 9110.7 9113.0 9016.5 9097.0 9111.2 9111.2 9112.2 9096.8 9134.5 9144.3 9148.3 9154.3 9080.7 9126.5 9139.0 9141.7 9141.8 8909.9 8999.7 9005.1 9018.5 9024.0 41188.2 41458.8 41468.0 41519.4 41592.4 3630.6 4197.7 4220.7 4236.1 4345.4 31525.0 31585.5 32025.0 32349.2 33085.7 4404.5 4455.3 4508.3 4515.4 4559.4 352292.0 352431.0 352940.0 353249.0 353900.0 56820.4 57318.0 57406.0 57429.0 57758.8 60324.1 60584.0 60675.0 60794.0 60842.4 17252.6 17467.6 17485.7 17504.1 18012.3 13553.6 13703.3 13717.9 13728.4 13730.6 19459.3 19496.5 19609.2 19617.2 19643.5 7147.7 7153.7 7365.7 7414.2 7452.8 976.7 995.7 1005.5 1109.5 1127.9 31164.7 33507.8 33634.3 33642.8 33650.5 1127.1 1143.3 1154.9 1155.6 1206.3 17306.2 17448.9 17665.2 17689.8 17732.1 574051.0 582692.0 582925.0 583794.0 584550.0 60662.1 60965.0 61140.0 61292.4 61368.7 1258.8 1347.4 1369.8 1381.0 1400.2 31678.8 34031.0 34127.3 34385.1 34392.7 51002.2 51514.5 51624.5 51739.5 51778.0 323588.0 323697.0 324401.0 324564.0 325997.0 25092.4 27194.0 28298.9 28318.7 28673.4 47380.8 47997.0 48001.3 48641.0 49051.3 41361.2 41771.4 42316.6 42791.0 43690.3 41909.0 41912.0 42021.2 43232.8 43825.3 41560.2 41581.4 43026.1 43379.6 45397.8 33885.0 37855.2 37921.2 37956.0 38298.2 29057.4 29542.0 29611.9 29818.9 29858.0 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | [#2] jquery.html Cycles:180 Average:32138.01 Median:31273.11 stddev:89213.55 (285.3%) stddev-sans-first:89431.96 22:49:40 INFO - PROCESS | 20157 | Values: 389.6 390.5 393.9 396.8 417.5 14570.9 18976.5 19386.5 19878.7 19893.5 18383.8 19343.6 19382.8 19414.3 19416.4 5623.9 6971.5 7281.3 7287.6 7313.6 7088.3 7130.9 7138.4 7185.7 7384.0 7403.6 7420.0 7502.5 7507.4 7512.2 7194.6 7351.6 7374.3 7381.7 7388.9 7000.8 7078.1 7149.1 7248.7 7262.7 25800.2 26029.3 26138.9 26363.9 26585.5 2401.5 2414.2 2627.7 2630.4 2998.9 15059.1 15371.9 15821.8 15941.2 16470.5 1877.5 1990.3 2093.5 2104.3 2142.9 97377.5 97872.5 99336.5 102928.5 105096.5 16094.5 17585.2 17757.7 18174.9 18391.0 25982.7 28718.3 31268.5 31277.7 31625.5 10326.9 10361.3 10415.0 10553.6 10603.4 8740.7 8772.0 8811.4 9019.9 9041.5 8603.7 8656.8 8670.9 8795.8 8929.1 3036.9 3225.7 3233.5 3413.4 3505.4 911.5 969.6 981.0 1005.3 1043.0 18045.5 19251.3 19358.4 19511.6 19515.9 943.8 961.1 1019.5 1080.9 1089.9 10072.4 10512.6 10602.1 10608.3 10661.6 492376.0 517711.0 551153.0 567540.0 572273.0 93394.0 94593.0 95994.5 96087.0 98519.5 955.8 963.2 982.3 984.5 1021.4 15778.8 16699.8 17360.3 17538.9 17574.6 34704.0 36536.8 40381.4 44835.6 45195.3 51414.0 58736.4 60908.0 61374.0 62667.0 7800.6 7819.7 8024.4 8146.6 8177.0 11783.6 12316.0 12409.6 12411.4 12527.7 10883.7 11041.2 11132.8 11230.7 11401.9 10581.6 10697.0 11006.4 11058.4 11104.0 10837.6 11254.6 11403.5 11507.3 11511.5 7199.4 7499.5 7561.9 8243.2 8285.3 8747.0 10604.5 11287.5 11367.6 11694.1 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | [#3] mootools.html Cycles:180 Average:5998.06 Median:4369.67 stddev:3544.33 (81.1%) stddev-sans-first:3538.25 22:49:40 INFO - PROCESS | 20157 | Values: 1513.5 1547.4 1692.5 1738.8 1813.3 8861.8 9445.6 9468.0 9474.5 9478.1 8024.9 8239.1 8240.8 8401.6 9283.0 2254.4 2333.4 2336.9 2337.7 2338.6 2281.9 2306.2 2319.8 2331.2 2331.9 1996.5 2283.6 2295.3 2305.6 2307.0 2290.6 2293.8 2300.2 2311.4 2318.5 2235.5 2266.4 2266.9 2271.4 2272.5 9301.0 9415.9 9423.9 9437.8 9477.1 5082.5 5177.6 5223.5 5233.0 5233.6 3142.3 3296.3 3301.4 3320.5 3359.9 1035.0 1064.1 1079.5 1081.5 1093.7 12792.6 12821.9 12854.9 12857.9 12872.2 4301.5 4458.4 4604.4 4613.0 4616.1 10697.6 10732.1 10754.8 10758.1 10837.2 5181.3 5205.9 5211.5 5217.0 5218.4 4096.6 4727.6 4792.2 4796.0 4797.4 4163.2 4171.6 4175.5 4241.9 4298.8 1286.4 1320.5 1321.1 1329.1 1348.2 827.9 862.2 894.7 897.9 953.7 5791.3 6391.9 6489.7 6509.2 6661.8 424.2 424.5 430.3 431.8 432.0 4285.9 4307.2 4339.6 4354.2 4385.1 11342.7 11644.1 11756.2 11780.1 11781.6 11616.1 11619.2 11625.7 11631.4 11635.1 7797.9 8062.1 8104.1 8191.7 8311.1 6421.4 6451.6 6577.9 6681.2 6707.3 10782.8 10877.2 10963.0 11042.1 11072.3 10838.5 10897.3 11094.8 11218.7 11228.1 7408.0 7695.7 7833.0 7893.5 7908.8 8909.5 9121.3 9174.1 9359.3 9364.0 7671.8 7683.3 7784.1 7800.3 8035.9 7378.6 7652.6 7696.5 7717.5 7801.6 7479.3 7554.6 7586.2 7610.7 7613.9 5173.4 5269.4 5269.6 5314.8 5502.0 5323.9 5344.5 5346.8 5350.4 5509.4 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | [#4] prototype.html Cycles:180 Average:4590.82 Median:5167.57 stddev:1342.35 (26.0%) stddev-sans-first:1307.11 22:49:40 INFO - PROCESS | 20157 | Values: 310.9 317.0 320.0 332.9 334.1 5111.3 5229.3 5359.3 5402.6 5413.8 5355.7 5364.3 5379.8 5396.3 5407.9 4171.4 4181.3 4196.7 4201.1 4225.1 4097.4 4114.9 4147.0 4180.2 4221.5 4093.2 4106.9 4128.7 4130.9 4132.5 4123.2 4123.9 4130.9 4134.3 4157.0 3682.4 3689.3 3700.8 3702.7 3709.4 5051.2 5127.2 5166.4 5169.6 5186.1 4746.9 4750.7 4755.4 4768.8 4772.8 5386.9 5530.8 5555.5 5561.3 5597.6 3971.1 3987.5 4003.5 4020.9 4026.2 5714.7 5746.9 5751.0 5753.9 5757.2 4112.3 4113.2 4137.9 4140.0 4163.9 3856.6 4031.3 4039.4 4039.6 4188.8 4618.4 4635.0 4646.0 4653.0 4676.2 5555.8 5562.2 5573.0 5575.0 5608.6 1328.1 1342.9 1383.9 1407.4 1415.3 935.5 947.6 956.4 962.5 976.8 5334.3 5347.3 5349.6 5359.3 5372.7 5593.1 5601.2 5640.1 5645.7 5649.1 5168.7 5284.6 5298.6 5320.3 5329.0 2658.2 2664.8 2677.4 2691.4 2702.3 5162.3 5176.9 5191.3 5208.6 5241.6 4818.5 5033.0 5057.3 5077.8 5099.8 5058.0 5088.3 5109.3 5138.3 5159.8 4980.0 5016.3 5026.6 5043.7 5084.3 4979.4 5275.5 5681.6 5703.6 5732.4 5847.7 5875.8 5903.2 5915.2 5948.7 3954.3 3955.3 3970.1 3983.5 3993.8 5461.9 5464.5 5667.7 5703.7 5761.6 5472.5 5574.1 5578.2 5592.9 5620.4 5572.6 5575.4 5579.6 5596.4 5600.6 5313.8 5525.7 5544.9 5560.2 5564.4 5594.9 5644.4 5649.7 5650.7 5664.3 5468.9 5521.3 5530.2 5571.4 5580.0 22:49:40 INFO - PROCESS | 20157 | 22:49:40 INFO - PROCESS | 20157 | [#5] yui.html Cycles:180 Average:22104.47 Median:4002.40 stddev:31970.74 (798.8%) stddev-sans-first:32020.06 22:49:40 INFO - PROCESS | 20157 | Values: 707.7 757.1 758.5 796.0 816.3 15789.3 16362.5 16540.4 16615.8 16991.0 14824.6 15096.8 15112.3 15140.8 15163.5 673.6 794.1 814.9 826.0 826.0 819.6 842.8 844.3 846.2 846.8 896.6 897.8 899.5 899.5 900.0 709.2 860.6 887.6 893.5 893.6 632.4 633.7 635.7 635.8 637.3 14991.0 15013.5 15016.4 15031.9 15049.4 14221.6 14525.4 14715.9 14732.0 14902.5 13675.2 13721.8 13764.1 13802.2 13818.4 4324.1 4336.6 4440.7 4471.2 4521.9 86795.6 87550.6 88633.0 90716.5 90948.5 11437.5 11441.6 11451.9 11499.1 11534.0 22264.0 22314.4 22322.8 22341.2 22350.3 8522.4 8598.6 8710.3 8732.3 8766.3 3945.4 3955.6 3956.2 3970.5 4034.3 3449.9 3494.8 3512.7 3525.1 3566.1 617.7 618.3 619.7 626.1 640.7 703.6 718.3 725.5 734.5 747.1 9368.0 10012.9 10020.4 10026.4 10029.1 588.5 695.5 722.8 733.3 776.3 4596.9 4602.5 4834.5 5082.8 5164.8 72438.6 77963.0 127103.0 127645.0 132594.0 101375.0 105774.5 105853.5 106561.0 109374.0 92666.5 92735.5 96312.0 96612.0 97494.0 9332.7 9569.0 9672.0 9791.8 9802.1 54196.0 55422.0 55813.0 56244.0 57598.7 80548.0 82776.6 84442.2 87210.0 87598.6 13228.7 13485.9 13720.4 13762.0 14205.4 15482.3 15555.5 15605.9 15644.0 16179.0 13701.5 14267.8 14346.6 14775.9 15263.8 14904.6 15209.5 15406.0 15426.3 15637.5 13674.7 14380.5 14480.2 14763.1 14831.8 11862.8 12336.7 12474.6 12610.7 12819.4 10560.8 11618.4 11643.0 11725.5 11751.8 22:49:40 INFO - PROCESS | 20157 | -------- Summary: end -------- 22:49:40 INFO - PROCESS | 20157 | 22:49:41 INFO - TEST-INFO | 20157: exit 0 22:49:41 INFO - TEST-OK | dromaeo_css | took 1113358ms 22:49:41 INFO - TEST-START | kraken 22:49:41 INFO - operating with platform_type : linux_ 22:49:41 INFO - Initialising browser for kraken test... 22:49:41 INFO - TEST-INFO | started process 20343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcQ5GDR/profile http://localhost:57259/getInfo.html) 22:49:45 INFO - PROCESS | 20343 | __metrics Screen width/height:1600/1200 22:49:45 INFO - PROCESS | 20343 | colorDepth:24 22:49:45 INFO - PROCESS | 20343 | Browser inner width/height: 1024/697 22:49:45 INFO - PROCESS | 20343 | __metrics 22:49:46 INFO - PROCESS | 20343 | 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" 22:49:46 INFO - PROCESS | 20343 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:49:47 INFO - TEST-INFO | 20343: exit 0 22:49:47 INFO - Browser initialized. 22:49:47 INFO - Running cycle 1/1 for kraken test... 22:49:47 INFO - TEST-INFO | started process 20428 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcQ5GDR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:59 INFO - PROCESS | 20428 | RSS: Main: 157200384 22:49:59 INFO - PROCESS | 20428 | 22:50:01 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57259/tests/kraken/driver.html?testName=audio-beat-detection) 22:50:02 INFO - PROCESS | 20428 | RSS: Main: 213692416 22:50:02 INFO - PROCESS | 20428 | 22:50:05 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57259/tests/kraken/driver.html?testName=audio-dft) 22:50:05 INFO - PROCESS | 20428 | RSS: Main: 191475712 22:50:05 INFO - PROCESS | 20428 | 22:50:08 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57259/tests/kraken/driver.html?testName=audio-fft) 22:50:09 INFO - PROCESS | 20428 | RSS: Main: 269774848 22:50:09 INFO - PROCESS | 20428 | 22:50:11 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57259/tests/kraken/driver.html?testName=audio-oscillator) 22:50:12 INFO - PROCESS | 20428 | RSS: Main: 180436992 22:50:12 INFO - PROCESS | 20428 | 22:50:14 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57259/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:50:14 INFO - PROCESS | 20428 | RSS: Main: 184872960 22:50:14 INFO - PROCESS | 20428 | 22:50:17 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57259/tests/kraken/driver.html?testName=imaging-darkroom) 22:50:18 INFO - PROCESS | 20428 | RSS: Main: 219189248 22:50:18 INFO - PROCESS | 20428 | 22:50:21 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57259/tests/kraken/driver.html?testName=imaging-desaturate) 22:50:22 INFO - PROCESS | 20428 | RSS: Main: 219418624 22:50:22 INFO - PROCESS | 20428 | 22:50:25 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57259/tests/kraken/driver.html?testName=json-parse-financial) 22:50:25 INFO - PROCESS | 20428 | RSS: Main: 219942912 22:50:25 INFO - PROCESS | 20428 | 22:50:27 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57259/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:50:27 INFO - PROCESS | 20428 | RSS: Main: 185028608 22:50:27 INFO - PROCESS | 20428 | 22:50:29 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:50:30 INFO - PROCESS | 20428 | RSS: Main: 189521920 22:50:30 INFO - PROCESS | 20428 | 22:50:32 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:50:32 INFO - PROCESS | 20428 | RSS: Main: 194465792 22:50:32 INFO - PROCESS | 20428 | 22:50:35 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:50:36 INFO - PROCESS | 20428 | RSS: Main: 195559424 22:50:36 INFO - PROCESS | 20428 | 22:50:38 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:50:39 INFO - PROCESS | 20428 | RSS: Main: 193724416 22:50:39 INFO - PROCESS | 20428 | 22:50:40 INFO - PROCESS | 20428 | Cycle 1(1): loaded http://localhost:57259/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57259/tests/kraken/driver.html?testName=ai-astar) 22:50:41 INFO - PROCESS | 20428 | RSS: Main: 244695040 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | __start_tp_report 22:50:41 INFO - PROCESS | 20428 | _x_x_mozilla_page_load 22:50:41 INFO - PROCESS | 20428 | _x_x_mozilla_page_load_details 22:50:41 INFO - PROCESS | 20428 | |i|pagename|runs| 22:50:41 INFO - PROCESS | 20428 | |0;ai-astar;100;111;111;109;111;112;109;110;113;111 22:50:41 INFO - PROCESS | 20428 | |1;audio-beat-detection;146;116;118;186;116;122;141;116;166;115 22:50:41 INFO - PROCESS | 20428 | |2;audio-dft;161;159;158;160;160;161;162;162;161;160 22:50:41 INFO - PROCESS | 20428 | |3;audio-fft;75;74;75;112;76;76;76;75;108;75 22:50:41 INFO - PROCESS | 20428 | |4;audio-oscillator;84;85;110;84;84;85;121;83;83;123 22:50:41 INFO - PROCESS | 20428 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;117;117 22:50:41 INFO - PROCESS | 20428 | |6;imaging-darkroom;147;147;145;146;147;147;146;145;146;145 22:50:41 INFO - PROCESS | 20428 | |7;imaging-desaturate;95;96;95;95;95;94;95;95;95;94 22:50:41 INFO - PROCESS | 20428 | |8;json-parse-financial;52;54;51;51;51;53;55;51;51;51 22:50:41 INFO - PROCESS | 20428 | |9;json-stringify-tinderbox;61;62;61;62;61;60;62;64;61;62 22:50:41 INFO - PROCESS | 20428 | |10;stanford-crypto-aes;77;75;74;75;85;77;74;75;77;79 22:50:41 INFO - PROCESS | 20428 | |11;stanford-crypto-ccm;127;132;121;124;129;121;131;124;163;131 22:50:41 INFO - PROCESS | 20428 | |12;stanford-crypto-pbkdf2;156;154;152;154;154;155;153;158;152;153 22:50:41 INFO - PROCESS | 20428 | |13;stanford-crypto-sha256-iterative;64;64;72;62;62;65;63;67;61;61 22:50:41 INFO - PROCESS | 20428 | __end_tp_report 22:50:41 INFO - PROCESS | 20428 | __start_cc_report 22:50:41 INFO - PROCESS | 20428 | _x_x_mozilla_cycle_collect,1722 22:50:41 INFO - PROCESS | 20428 | __end_cc_report 22:50:41 INFO - PROCESS | 20428 | __startTimestamp1468993841247__endTimestamp 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | ------- Summary: start ------- 22:50:41 INFO - PROCESS | 20428 | Number of tests: 14 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.62 (3.3%) stddev-sans-first:1.30 22:50:41 INFO - PROCESS | 20428 | Values: 100.0 111.0 111.0 109.0 111.0 112.0 109.0 110.0 113.0 111.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:131.50 stddev:25.06 (19.1%) stddev-sans-first:26.22 22:50:41 INFO - PROCESS | 20428 | Values: 146.0 116.0 118.0 186.0 116.0 122.0 141.0 116.0 166.0 115.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:1.26 (0.8%) stddev-sans-first:1.32 22:50:41 INFO - PROCESS | 20428 | Values: 161.0 159.0 158.0 160.0 160.0 161.0 162.0 162.0 161.0 160.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:14.70 (19.6%) stddev-sans-first:15.35 22:50:41 INFO - PROCESS | 20428 | Values: 75.0 74.0 75.0 112.0 76.0 76.0 76.0 75.0 108.0 75.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:84.00 stddev:16.77 (20.0%) stddev-sans-first:17.37 22:50:41 INFO - PROCESS | 20428 | Values: 84.0 85.0 110.0 84.0 84.0 85.0 121.0 83.0 83.0 123.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:50:41 INFO - PROCESS | 20428 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 22:50:41 INFO - PROCESS | 20428 | Values: 147.0 147.0 145.0 146.0 147.0 147.0 146.0 145.0 146.0 145.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:50:41 INFO - PROCESS | 20428 | Values: 95.0 96.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:51.50 stddev:1.49 (2.9%) stddev-sans-first:1.58 22:50:41 INFO - PROCESS | 20428 | Values: 52.0 54.0 51.0 51.0 51.0 53.0 55.0 51.0 51.0 51.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.00 stddev:1.07 (1.7%) stddev-sans-first:1.12 22:50:41 INFO - PROCESS | 20428 | Values: 61.0 62.0 61.0 62.0 61.0 60.0 62.0 64.0 61.0 62.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:77.00 stddev:3.29 (4.3%) stddev-sans-first:3.49 22:50:41 INFO - PROCESS | 20428 | Values: 77.0 75.0 74.0 75.0 85.0 77.0 74.0 75.0 77.0 79.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:130.00 stddev:12.19 (9.4%) stddev-sans-first:12.87 22:50:41 INFO - PROCESS | 20428 | Values: 127.0 132.0 121.0 124.0 129.0 121.0 131.0 124.0 163.0 131.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:154.00 stddev:1.85 (1.2%) stddev-sans-first:1.83 22:50:41 INFO - PROCESS | 20428 | Values: 156.0 154.0 152.0 154.0 154.0 155.0 153.0 158.0 152.0 153.0 22:50:41 INFO - PROCESS | 20428 | 22:50:41 INFO - PROCESS | 20428 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:3.35 (5.2%) stddev-sans-first:3.55 22:50:41 INFO - PROCESS | 20428 | Values: 64.0 64.0 72.0 62.0 62.0 65.0 63.0 67.0 61.0 61.0 22:50:41 INFO - PROCESS | 20428 | -------- Summary: end -------- 22:50:41 INFO - PROCESS | 20428 | 22:50:42 INFO - TEST-INFO | 20428: exit 0 22:50:42 INFO - TEST-OK | kraken | took 60621ms 22:50:42 INFO - SUITE-END | took 1173s 22:50:42 INFO - Completed test suite (00:19:34) 22:50:42 INFO - javascript benchmark 22:50:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3037.9240518987035, 3047.8476076196193, 3088.197068762122, 3088.891888783893, 3103.5344698295253, 5155.510226528483, 5253.4221235664045, 5328.227407025979, 5452.482014208654, 5464.207689884969, 5316.627836051478, 5324.227986941891, 5388.353397592285, 5407.405185429609, 5472.699001554913, 4846.369971903657, 4934.629902757308, 4943.382077240345, 4949.579285760715, 5083.974580127094, 4999.275105109757, 5020.497950204968, 5041.445441001487, 5056.974715126437, 5061.924071138936, 4814.085323788487, 4850.611951043908, 4920.507949205069, 4928.654994150411, 4946.554810067075, 4843.806247750086, 4862.246351815475, 4863.416390033219, 4868.561829435368, 4973.055119527278, 4886.0, 4914.11545921734, 4917.016596680678, 4931.7040977541465, 4966.776495057731, 5190.221466779975, 5229.189475631283, 5261.815836445706, 5327.760250788724, 5460.563154947595, 5733.512651424634, 5775.971120144414, 5787.305523337226, 5789.797357092481, 5792.768289268466, 5782.3639399666, 5817.272840894888, 5826.329972053226, 5834.766609335621, 5836.0, 5211.452797456288, 5371.033214021474, 5431.701256430894, 5432.483914028161, 5435.456454354572, 6004.42957918997, 6040.335563088057, 6049.062395328731, 6060.575759696779, 6062.9996050652035, 5777.91333129995, 5783.2192653992015, 5785.305763308345, 5788.21859049023, 5788.855278618068, 5547.528460080938, 5731.914021289684, 5773.422657734193, 5891.351951285441, 5899.67551784656, 5811.564132690065, 5849.970750146307, 5862.091375836752, 5862.091375836752, 5865.061590145641, 5772.9711351442975, 5778.451047150514, 5828.883422331614, 5834.358220595731, 5842.532597392198, 4333.696641235147, 4429.9778501107285, 4436.667249956202, 4443.178012067778, 4472.731636101845, 3852.325842977468, 3874.4575759393706, 3880.7865567394065, 3883.8446462141765, 3944.881653550398, 5436.3476382834315, 5439.564834813205, 5442.2380866678695, 5443.156310771837, 5445.891082178334, 5754.913676294859, 5758.049921762946, 5763.337216220105, 5765.048766953406, 5804.65172089676, 5268.683878967274, 5406.648567843077, 5445.727713614383, 5458.863528411822, 5532.75102620383, 5000.574951129122, 5011.373578302712, 5013.874653133628, 5014.598832093497, 5056.039352523009, 5943.108533719977, 5989.760409583655, 5992.0712289595185, 5992.131140984533, 6000.429959153874, 5599.04816181242, 5662.178984047207, 5662.490375866192, 5664.376918538875, 5793.3337666167245, 5896.115582662635, 5900.881982360414, 5904.822855314347, 5905.645661260338, 5905.852353691193, 5587.050201465842, 5607.102863541813, 5624.0, 5634.690092044977, 5652.576056795675, 5573.944260557342, 5656.85857853557, 5661.207430959586, 5672.716364181857, 5767.452092051249, 5603.719814009365, 5609.242752228397, 5614.101743720985, 5614.775408983758, 5615.241942337895, 5570.5265052470995, 5610.0182468067105, 5620.1288800236025, 5623.128415095667, 5633.887322253614, 5356.892862142813, 5562.388137304974, 5610.046292130429, 5624.0, 5720.227769251098, 5764.913526297193, 5767.913481297868, 5768.884622307614, 5817.592768506164, 5819.156222347651, 5638.887222255614, 5649.971750141223, 5653.632513886749, 5653.660780353192, 5654.208410822406], "value": 5322.045588510189, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3022633292716, 624.8968920128177, 629.5058379172349, 648.9383508566688, 662.7017841971115, 30158.84920575397, 30647.161170329797, 33217.0, 33317.500237496424, 42997.140057198914, 39492.21015579686, 43478.34782478266, 43657.34513982293, 43783.34324985128, 43832.12335753283, 8956.686515971925, 9083.500407477586, 9107.590158442887, 9110.681126160585, 9113.043130471304, 9016.504092274923, 9097.044810294923, 9111.179993800557, 9111.22554582858, 9112.225460835836, 9096.818063638722, 9134.543272836365, 9144.314176436761, 9148.2681385489, 9154.313426493007, 9080.727578172633, 9126.543672816399, 9139.0, 9141.725748227564, 9141.817163656757, 8909.866352004725, 8999.730008099768, 9005.054469280696, 9018.458892466473, 9023.954880225623, 41188.1762364754, 41458.79270603658, 41468.0, 41519.37720934188, 41592.37611435831, 3630.5824830144606, 4197.706160568761, 4220.725652832587, 4236.131593023423, 4345.391645169679, 31525.0, 31585.526217106762, 32025.0, 32349.191270218198, 33085.66914330874, 4404.4934832494355, 4455.335263978608, 4508.2561377372695, 4515.390422293014, 4559.430071241095, 352292.0, 352431.0, 352940.0, 353249.0, 353900.0, 56820.43179568234, 57318.0, 57406.0, 57429.0, 57758.8448231033, 60324.09513857296, 60584.0, 60675.0, 60794.0, 60842.391576083675, 17252.568685782706, 17467.563310917074, 17485.65028699419, 17504.12479376026, 18012.279508819764, 13553.59339219825, 13703.314834258446, 13717.931410343084, 13728.382222799997, 13730.588082357544, 19459.346165170442, 19496.525959289178, 19609.23516883487, 19617.215311387383, 19643.508912277306, 7147.678354474061, 7153.6780844862105, 7365.674178647895, 7414.221506741822, 7452.776416707507, 976.7216343342113, 995.7361299255703, 1005.4671024357102, 1109.5118148014847, 1127.9436028198556, 31164.68835311618, 33507.83246083754, 33634.32731345359, 33642.831785840914, 33650.49524257138, 1127.0870594818223, 1143.2797337677375, 1154.9480273387717, 1155.5551112821624, 1206.324458303353, 17306.22122004513, 17448.946434373935, 17665.205065772072, 17689.823101769074, 17732.11339433023, 574051.0, 582692.0, 582925.0, 583794.0, 584550.0, 60662.090068649006, 60965.0, 61140.0, 61292.38707612956, 61368.69315653393, 1258.7545428641326, 1347.447546505928, 1369.7876829091506, 1380.9918759305563, 1400.2018849255826, 31678.841605791822, 34031.0, 34127.31745365029, 34385.07520342311, 34392.65607343894, 51002.23496647627, 51514.48485515097, 51624.48375516197, 51739.48260517346, 51778.0, 323588.0, 323697.0, 324401.0, 324564.0, 325997.0, 25092.372690682878, 27194.0, 28298.85850570734, 28318.716812831608, 28673.42653146884, 47380.763096184295, 47997.0, 48001.27998080102, 48641.0, 49051.26423103585, 41361.172776543695, 41771.37342939797, 42316.576834231266, 42791.0, 43690.34464482972, 41909.0, 41912.0, 42021.15957680768, 43232.78383608188, 43825.34261986137, 41560.1687966245, 41581.37627935644, 43026.139477210905, 43379.566204337556, 45397.773011134734, 33885.0, 37855.242895142495, 37921.24157516779, 37956.0, 38298.23403531858, 29057.41885162327, 29542.0, 29611.851940740158, 29818.850905745334, 29858.0], "value": 20348.478178618814, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.6006593241927, 390.50600989747966, 393.88577312579315, 396.8432469174676, 417.4718980489681, 14570.927145364285, 18976.525586860334, 19386.51533711658, 19878.70181947272, 19893.502662433428, 18383.81616183838, 19343.613127737437, 19382.806171938275, 19414.320498782545, 19416.4175074748, 5623.943760562393, 6971.511994160411, 7281.308275713825, 7287.6356182190675, 7313.597752123631, 7088.255733148023, 7130.8573828523395, 7138.428925685958, 7185.6766445510075, 7383.9630801845915, 7403.5557866527915, 7420.0, 7502.474826762129, 7507.4369422293275, 7512.211217822132, 7194.640267986606, 7351.632418379087, 7374.262573742636, 7381.667824947864, 7388.852222955511, 7000.824979375557, 7078.115235595551, 7149.106361704787, 7248.71005159793, 7262.745803896891, 25800.225993220232, 26029.349266268306, 26138.86930565354, 26363.868180659167, 26585.46829063427, 2401.519696060795, 2414.2153800015067, 2627.6846778386525, 2630.408158164417, 2998.925026874324, 15059.096454212782, 15371.923140384226, 15821.762673559906, 15941.202939853078, 16470.541325877, 1877.4649224970954, 1990.263602467082, 2093.5394213273057, 2104.326615483046, 2142.8500004999655, 97377.5131124347, 97872.51063744635, 99336.50331748367, 102928.48535757349, 105096.47451762769, 16094.517164485083, 17585.20866561008, 17757.7336339955, 18174.909125454287, 18391.0, 25982.74017259803, 28718.282042948675, 31268.530972035438, 31277.687223127934, 31625.525617115763, 10326.896731032593, 10361.326513776581, 10415.0, 10553.630622928315, 10603.416812075257, 8740.694075707319, 8772.0, 8811.427257228259, 9019.864702029476, 9041.457512549268, 8603.698870539502, 8656.78358041054, 8670.913290867136, 8795.780105497413, 8929.107089291148, 3036.9392612148076, 3225.6774322567558, 3233.531137984979, 3413.4026545354463, 3505.4391297392294, 911.462237280008, 969.5588507229194, 980.9944806573177, 1005.3264312910368, 1042.9582816687248, 18045.458636240935, 19251.32620358281, 19358.4192474226, 19511.609767804566, 19515.902420487808, 943.8253923024263, 961.0677642686583, 1019.5157300282307, 1080.9243352965375, 1089.8528698625585, 10072.446015469219, 10512.57949682004, 10602.09882160025, 10608.257421980386, 10661.626843060456, 492376.0, 517711.0, 551153.0, 567540.0, 572273.0, 93394.0, 94593.0, 95994.52002739941, 96087.0, 98519.50740246396, 955.8375076237056, 963.1716723617683, 982.2633025231077, 984.522506584306, 1021.443313394187, 15778.842211577738, 16699.833001670315, 17360.305587776347, 17538.947663140254, 17574.560635984202, 34704.0, 36536.81731591431, 40381.394279086424, 44835.55164448444, 45195.25449827721, 51414.0, 58736.4126358748, 60908.0, 61374.0, 62667.0, 7800.570968596781, 7819.687212511663, 8024.438289319692, 8146.633401497065, 8177.0, 11783.587574434854, 12316.0, 12409.565665201675, 12411.44148513301, 12527.686807829878, 10883.727906802393, 11041.227114101936, 11132.833007504732, 11230.66308010761, 11401.942990284995, 10581.62964296246, 10697.0, 11006.449677516252, 11058.39178845139, 11104.0, 10837.566497340018, 11254.606088786853, 11403.486843091914, 11507.252028618113, 11511.53953841837, 7199.3520583147765, 7499.475036747375, 7561.886571701539, 8243.216894395024, 8285.260095380037, 8746.956265218632, 10604.522796474332, 11287.548498059985, 11367.602133925273, 11694.122940779305], "value": 10388.007345960728, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.477850141701, 1547.3887814313346, 1692.5091723400215, 1738.843504084632, 1813.2565648084287, 8861.778455538606, 9445.574949127289, 9468.0, 9474.526273686313, 9478.099580539854, 8024.91975080249, 8239.093699693029, 8240.752777416687, 8401.579921003957, 9282.953585232064, 2254.425121593991, 2333.4166458385403, 2336.941576460585, 2337.731160916491, 2338.5790557699606, 2281.942951426211, 2306.2043594959714, 2319.7912187903084, 2331.184085570054, 2331.895064722083, 1996.520834999597, 2283.5547068321684, 2295.345826439465, 2305.5734689082487, 2306.988465057681, 2290.5876942150408, 2293.816494680422, 2300.1719381022817, 2311.3759284992957, 2318.5015221727326, 2235.4970131720397, 2266.3994041578994, 2266.9319920402413, 2271.432141964509, 2272.4546108933737, 9300.953495232481, 9415.858762118574, 9423.90576094237, 9437.811243775086, 9477.147056764856, 5082.542571168613, 5177.559907407875, 5223.477652234784, 5233.005728911533, 5233.633645644768, 3142.3244002539336, 3296.2748195396975, 3301.4057469655454, 3320.452125399306, 3359.882404115844, 1034.9559619750933, 1064.084886997182, 1079.5088234853124, 1081.4971038467152, 1093.7101668057971, 12792.552260670926, 12821.871781282161, 12854.935725321408, 12857.871421285854, 12872.22766634005, 4301.526832048472, 4458.375827384169, 4604.37840891482, 4613.031263434649, 4616.0767846430845, 10697.62558310471, 10732.141428685842, 10754.838677419844, 10758.0855627271, 10837.187210959051, 5181.326427564468, 5205.869853253699, 5211.530962213419, 5217.0, 5218.425973142951, 4096.6481061249715, 4727.57451829337, 4792.233242681224, 4796.016816552668, 4797.352357431771, 4163.2298024865495, 4171.624553790173, 4175.498940127143, 4241.893952651209, 4298.78506074695, 1286.3889652415214, 1320.518010926005, 1321.1478596305521, 1329.069651244133, 1348.1506650809927, 827.9319677615929, 862.2369203255164, 894.673444192865, 897.9057198994011, 953.7329547726648, 5791.3339965903615, 6391.872162556723, 6489.70796314167, 6509.218893732687, 6661.800145995628, 424.23637452585837, 424.5096913065414, 430.3243907065877, 431.8445359670516, 432.04302470029035, 4285.935710964338, 4307.18163548931, 4339.5660433956355, 4354.238008348526, 4385.101054283864, 11342.716432089099, 11644.126690498246, 11756.23584467007, 11780.11649126319, 11781.646550603497, 11616.070714342832, 11619.186656933933, 11625.709357266136, 11631.418429078512, 11635.127365447624, 7797.92202077972, 8062.072861620989, 8104.149064348158, 8191.713290034821, 8311.129995750907, 6421.389967953037, 6451.580647257913, 6577.901331480128, 6681.247565688963, 6707.262201157966, 10782.838257425989, 10877.238593298393, 10963.0, 11042.061424778662, 11072.335659860435, 10838.458077096271, 10897.346159230472, 11094.833577496505, 11218.66344009681, 11228.101751859995, 7407.962960185165, 7695.653695583824, 7832.960835195788, 7893.486923349964, 7908.762737117896, 8909.509976951329, 9121.270298376247, 9174.128457796498, 9359.251259899056, 9364.016778238187, 7671.846563068819, 7683.270089341504, 7784.143744188022, 7800.297973182214, 8035.919640803517, 7378.557286562609, 7652.617369131633, 7696.499727517694, 7717.498362606412, 7801.570913599807, 7479.289467500579, 7554.584497852451, 7586.203448637813, 7610.695572177272, 7613.9238607613215, 5173.3533308336455, 5269.394019687785, 5269.631125821155, 5314.840554783363, 5502.0, 5323.920141197963, 5344.545713614386, 5346.812861549983, 5350.438203988525, 5509.449055094458], "value": 4640.939554433779, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.8523451360604, 316.9968300316997, 319.98720051197955, 332.86851693581036, 334.06461906661343, 5111.284420181169, 5229.346331708536, 5359.330083739533, 5402.594805389596, 5413.783448662049, 5355.651882627626, 5364.275822763926, 5379.784808607651, 5396.325459317585, 5407.918881216785, 4171.416001759756, 4181.268278051344, 4196.727212731178, 4201.054762678388, 4225.112726327475, 4097.426360309544, 4114.876553703393, 4146.9792651036705, 4180.157492904824, 4221.472315960505, 4093.2427500912427, 4106.876793696194, 4128.731632443896, 4130.8554200602985, 4132.524759652649, 4123.154753275571, 4123.8969025774295, 4130.8554200602985, 4134.3178375567995, 4156.958430415687, 3682.447632855093, 3689.335919534483, 3700.777953322809, 3702.7037836972977, 3709.3879509880835, 5051.166557518005, 5127.230915362726, 5166.405863325728, 5169.638125331228, 5186.1442861927735, 4746.881327966794, 4750.7410536207935, 4755.42934847817, 4768.785404656799, 4772.832950846739, 5386.946130538684, 5530.778768849241, 5555.472230138131, 5561.332640083176, 5597.63615365, 3971.126352202511, 3987.4616926715103, 4003.459532963042, 4020.9396859047138, 4026.2148880968225, 5714.685692286923, 5746.913796293059, 5751.0, 5753.942460575425, 5757.222774925415, 4112.280350938574, 4113.1568028554075, 4137.8758637240935, 4139.965008747813, 4163.854265100707, 3856.614338566177, 4031.274370613259, 4039.4344791728595, 4039.5758445362812, 4188.8324467020975, 4618.376519169936, 4634.9536504634525, 4646.0, 4652.999605084923, 4676.158291507493, 5555.833325000256, 5562.193481945095, 5572.972135139298, 5574.9721251393485, 5608.579356548275, 1328.1234385305652, 1342.939567719455, 1383.8514033352294, 1407.429990853706, 1415.344047464469, 935.5266235284914, 947.6446332625266, 956.3687965942445, 962.5427921737119, 976.8437050072097, 5334.253204551366, 5347.304850369427, 5349.598780091509, 5359.330083739533, 5372.704501252469, 5593.133064375028, 5601.187827765033, 5640.125780504029, 5645.745941432563, 5649.06790379591, 5168.7415629218385, 5284.550813180846, 5298.648844544617, 5320.2817619620855, 5328.973355133277, 2658.2025392382207, 2664.7601715845667, 2677.410969586727, 2691.380982374065, 2702.2703869955003, 5162.328897243335, 5176.8964620707375, 5191.299174611379, 5208.557272631869, 5241.633085684041, 4818.538014010403, 5033.0, 5057.317262169634, 5077.771500282496, 5099.821506247264, 5057.974710126499, 5088.262201980692, 5109.310243117132, 5138.280640710229, 5159.787449949259, 4980.0, 5016.347874776256, 5026.572741317029, 5043.672161309503, 5084.339035925306, 4979.3526841510375, 5275.525202731773, 5681.573881958787, 5703.63112852904, 5732.369439361583, 5847.707614619167, 5875.823725288248, 5903.203067585821, 5915.171875937286, 5948.732307046107, 3954.3475326570792, 3955.268275369066, 3970.146418520032, 3983.521977362619, 3993.781896521589, 5461.890762184655, 5464.508194262536, 5667.716614169192, 5703.686297253691, 5761.567882408753, 5472.507474327171, 5574.136008918624, 5578.219049333015, 5592.86017849557, 5620.4098569649595, 5572.60991730575, 5575.414581469049, 5579.637323573955, 5596.384397716167, 5600.60795744294, 5313.840584782308, 5525.696086715269, 5544.861378465571, 5560.249366335493, 5564.38791732901, 5594.916076258778, 5644.435556444323, 5649.689267090185, 5650.745716442688, 5664.320281566238, 5468.863278418072, 5521.337439507285, 5530.170474428868, 5571.387147413862, 5579.972100139474], "value": 4161.3489984720545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.6956908529332, 757.1103952855393, 758.4842307231085, 795.9641816118274, 816.3061397811864, 15789.28948197331, 16362.509124726248, 16540.42108526202, 16615.833841661595, 16991.0, 14824.55526334209, 15096.849031509682, 15112.319945602447, 15140.84859151408, 15163.469278575252, 673.5621845800245, 794.0947320055142, 814.8573999550084, 825.9876101858476, 826.0252901576138, 819.639358682181, 842.759813453166, 844.2823599940064, 846.1749793950879, 846.8221673448584, 896.5875697179305, 897.824924139793, 899.5232526760794, 899.5457294066488, 899.9595018224163, 709.2163159708499, 860.6428332242112, 887.6316328723572, 893.4594570284982, 893.6068130022802, 632.3581564711775, 633.7148283272546, 635.71710588788, 635.8283263518824, 637.2671427857954, 14991.0, 15013.474528391454, 15016.399344026226, 15031.924840375837, 15049.398024079024, 14221.573352799433, 14525.418983240657, 14715.92642036783, 14732.0, 14902.478413255592, 13675.179489230679, 13721.794173087412, 13764.105333153313, 13802.240876751875, 13818.378172982138, 4324.135172965388, 4336.56634336567, 4440.7335559866515, 4471.195184866723, 4521.886952826173, 86795.56602216949, 87550.562247189, 88633.0, 90716.54641726815, 90948.54525727395, 11437.542498299976, 11441.599544015919, 11451.942740286246, 11499.137564682514, 11534.0, 22264.0, 22314.442138946655, 22322.776772232395, 22341.218057367914, 22350.329490115324, 8522.403431759842, 8598.613062412207, 8710.25962793157, 8732.301415886714, 8766.298696104297, 3945.427912952663, 3955.6044395560216, 3956.1692044670367, 3970.4639873616693, 4034.334334834778, 3449.9482507762405, 3494.790312581253, 3512.7014203792965, 3525.136341596325, 3566.0728210665416, 617.6633734614646, 618.2827919613227, 619.69634878909, 626.0765371077715, 640.7467468259352, 703.5919166883195, 718.2745427118648, 725.5175308419841, 734.4858598980735, 747.0661672908864, 9368.016358282419, 10012.899871001342, 10020.448875311928, 10026.398416095057, 10029.147522460527, 588.5350573047244, 695.4540685561809, 722.757876111498, 733.2813842434444, 776.3245976000916, 4596.908061838812, 4602.539746025371, 4834.468208497063, 5082.771275292621, 5164.7934082636275, 72438.63780681063, 77963.0, 127103.0, 127645.0, 132594.0, 101375.0, 105774.47112764388, 105853.47073264584, 106561.0, 109374.0, 92666.53666731624, 92735.53632231796, 96312.0, 96612.0, 97494.0, 9332.72001839946, 9568.95215523918, 9671.951640242036, 9791.804163916824, 9802.077267999446, 54196.0, 55421.999999998385, 55813.0, 56244.0, 57598.712006441376, 80548.0, 82776.58611706903, 84442.15557844343, 87210.0, 87598.56200718955, 13228.66928326761, 13485.932570337085, 13720.382582783599, 13762.00998038712, 14205.360758766086, 15482.303296351916, 15555.45555905538, 15605.921970390076, 15644.0, 16179.0, 13701.45194192221, 14267.785983210468, 14346.641333966318, 14775.92612036933, 15263.847361526687, 14904.552863414116, 15209.543713688607, 15406.0, 15426.30581623852, 15637.452689155825, 13674.658133546343, 14380.496682616058, 14480.203588802717, 14763.114213147246, 14831.777523336943, 11862.762744744885, 12336.691582710146, 12474.625761227178, 12610.684732881751, 12819.359032048174, 10560.84158737635, 11618.405749560243, 11643.0, 11725.530978760755, 11751.823722643996], "value": 7852.961452259536, "unit": "score"}], "name": "dromaeo_css", "value": 7447.503776894771, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 111.0, 109.0, 111.0, 112.0, 109.0, 110.0, 113.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 118.0, 186.0, 116.0, 122.0, 141.0, 116.0, 166.0, 115.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 158.0, 160.0, 160.0, 161.0, 162.0, 162.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 112.0, 76.0, 76.0, 76.0, 75.0, 108.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 110.0, 84.0, 84.0, 85.0, 121.0, 83.0, 83.0, 123.0], "value": 94.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 51.0, 51.0, 51.0, 53.0, 55.0, 51.0, 51.0, 51.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 61.0, 60.0, 62.0, 64.0, 61.0, 62.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 75.0, 85.0, 77.0, 74.0, 75.0, 77.0, 79.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 132.0, 121.0, 124.0, 129.0, 121.0, 131.0, 124.0, 163.0, 131.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 152.0, 154.0, 154.0, 155.0, 153.0, 158.0, 152.0, 153.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 72.0, 62.0, 62.0, 65.0, 63.0, 67.0, 61.0, 61.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1477.2999999999997, "alertThreshold": 2.0}]} 22:50:42 INFO - Return code: 0 22:50:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:50:42 INFO - # TBPL SUCCESS # 22:50:42 INFO - Running post-action listener: _resource_record_post_action 22:50:42 INFO - ##### 22:50:42 INFO - ##### Finished run-tests step (success) 22:50:42 INFO - ##### 22:50:42 INFO - Running post-run listener: _resource_record_post_run 22:50:42 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 567107584; Read time: 372; Write time: 426704 22:50:42 INFO - TinderboxPrint: CPU usage
13.0% 22:50:42 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 372 22:50:42 INFO - TinderboxPrint: I/O write bytes / time
567,107,584 / 426,704 22:50:42 INFO - TinderboxPrint: CPU idle
8,272.2 (86.9%) 22:50:42 INFO - TinderboxPrint: CPU user
1,194.7 (12.6%) 22:50:42 INFO - TinderboxPrint: Swap in / out
0 / 0 22:50:42 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 270995456; Read time: 0; Write time: 246452 22:50:42 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296112128; Read time: 356; Write time: 180252 22:50:43 INFO - Running post-run listener: _upload_blobber_files 22:50:43 INFO - Blob upload gear active. 22:50:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:50:43 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 22:50:43 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'] 22:50:43 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 22:50:43 INFO - (blobuploader) - INFO - Open directory for files ... 22:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:44 INFO - (blobuploader) - INFO - Done attempting. 22:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:50:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:45 INFO - (blobuploader) - INFO - Done attempting. 22:50:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:50:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:50:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:51 INFO - (blobuploader) - INFO - Done attempting. 22:50:51 INFO - (blobuploader) - INFO - Iteration through files over. 22:50:51 INFO - Return code: 0 22:50:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:50:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:50:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a5ca830420f2c44c20bc7c1f39a824a1deb2fa310fde15f16de98f4c6a13b5748295fa0d7490a8eaf255c52fb82e97885599245140c3d04423628ebe15be631e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/59701610064d450feb40ac74dc9a692cb9e847393563c55c65a918c3d8969560ebca71fc0d0265cc149fd6d6dea37d997e6b01898d942f7d79ac106b581d364a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7ec4f17d94e1d0e6e87f778fe5bef62316eb9ee99263651099ebcc7003ffa12a23f3593650c04c85ac06755444f5d8b6a9afd3c237e6e6c8f1bb95506211df16"} 22:50:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:50:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:50:51 INFO - Contents: 22:50:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a5ca830420f2c44c20bc7c1f39a824a1deb2fa310fde15f16de98f4c6a13b5748295fa0d7490a8eaf255c52fb82e97885599245140c3d04423628ebe15be631e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/59701610064d450feb40ac74dc9a692cb9e847393563c55c65a918c3d8969560ebca71fc0d0265cc149fd6d6dea37d997e6b01898d942f7d79ac106b581d364a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7ec4f17d94e1d0e6e87f778fe5bef62316eb9ee99263651099ebcc7003ffa12a23f3593650c04c85ac06755444f5d8b6a9afd3c237e6e6c8f1bb95506211df16"} 22:50:51 INFO - Running post-run listener: copy_logs_to_upload_dir 22:50:51 INFO - Copying logs to upload dir... 22:50:51 INFO - mkdir: /builds/slave/test/build/upload/logs 22:50:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.999298 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-19 22:50:51.372019) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 22:50:51.375838) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247 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/a5ca830420f2c44c20bc7c1f39a824a1deb2fa310fde15f16de98f4c6a13b5748295fa0d7490a8eaf255c52fb82e97885599245140c3d04423628ebe15be631e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/59701610064d450feb40ac74dc9a692cb9e847393563c55c65a918c3d8969560ebca71fc0d0265cc149fd6d6dea37d997e6b01898d942f7d79ac106b581d364a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7ec4f17d94e1d0e6e87f778fe5bef62316eb9ee99263651099ebcc7003ffa12a23f3593650c04c85ac06755444f5d8b6a9afd3c237e6e6c8f1bb95506211df16"} build_url:https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011354 build_url: 'https://queue.taskcluster.net/v1/task/CyuouZX4T5SRjagxIVWsTQ/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/a5ca830420f2c44c20bc7c1f39a824a1deb2fa310fde15f16de98f4c6a13b5748295fa0d7490a8eaf255c52fb82e97885599245140c3d04423628ebe15be631e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/59701610064d450feb40ac74dc9a692cb9e847393563c55c65a918c3d8969560ebca71fc0d0265cc149fd6d6dea37d997e6b01898d942f7d79ac106b581d364a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7ec4f17d94e1d0e6e87f778fe5bef62316eb9ee99263651099ebcc7003ffa12a23f3593650c04c85ac06755444f5d8b6a9afd3c237e6e6c8f1bb95506211df16"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 22:50:51.401726) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:50:51.402147) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1468992617.832893-640934247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:50:51.486250) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 22:50:51.486585) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 22:50:51.486914) ========= ========= Total master_lag: 0.20 =========