builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1467954301.33 results: success (0) buildid: 20160707193024 builduid: 69d10e523b2947be969ecc9648ae0e5d revision: 23dc78b7b57e9f91798ea44c242a04e112c37db0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.333899) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.334407) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.334761) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.396391) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.396991) ========= 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=af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-07 22:05:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-07-07 22:05:01 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.223152 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.639296) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.639657) ========= 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=af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018193 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:01.689745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 22:05:01.690141) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 23dc78b7b57e9f91798ea44c242a04e112c37db0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 23dc78b7b57e9f91798ea44c242a04e112c37db0 --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=af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-07 22:05:01,853 truncating revision to first 12 chars 2016-07-07 22:05:01,854 Setting DEBUG logging. 2016-07-07 22:05:01,854 attempt 1/10 2016-07-07 22:05:01,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/23dc78b7b57e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 22:05:03,324 unpacking tar archive at: mozilla-central-23dc78b7b57e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.802085 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 22:05:03.506242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:03.506611) ========= script_repo_revision: 23dc78b7b57e9f91798ea44c242a04e112c37db0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:03.507053) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:03.507351) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 22:05:03.517190) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-07 22:05:03.517645) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:05:03 INFO - MultiFileLogger online at 20160707 22:05:03 in /builds/slave/test 22:05:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:05:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:03 INFO - {'append_to_log': False, 22:05:03 INFO - 'base_work_dir': '/builds/slave/test', 22:05:03 INFO - 'blob_upload_branch': 'Firefox', 22:05:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:03 INFO - 'branch': 'Firefox', 22:05:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:03 INFO - 'config_files': ('talos/linux_config.py',), 22:05:03 INFO - 'default_actions': ('clobber', 22:05:03 INFO - 'read-buildbot-config', 22:05:03 INFO - 'download-and-extract', 22:05:03 INFO - 'populate-webroot', 22:05:03 INFO - 'create-virtualenv', 22:05:03 INFO - 'install', 22:05:03 INFO - 'run-tests'), 22:05:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:03 INFO - 'download_minidump_stackwalk': True, 22:05:03 INFO - 'download_symbols': 'ondemand', 22:05:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:03 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:03 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:03 INFO - 'installer_path': 'installer.exe', 22:05:03 INFO - 'log_level': 'info', 22:05:03 INFO - 'log_name': 'talos', 22:05:03 INFO - 'log_to_console': True, 22:05:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:05:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:05:03 INFO - 'opt_config_files': (), 22:05:03 INFO - 'pip_index': False, 22:05:03 INFO - 'sps_profile': False, 22:05:03 INFO - 'sps_profile_interval': 0, 22:05:03 INFO - 'suite': 'dromaeojs', 22:05:03 INFO - 'system_bits': '32', 22:05:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:03 INFO - 'title': 'talos-linux64-ix-020', 22:05:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:03 INFO - 'use_talos_json': True, 22:05:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:03 INFO - 'work_dir': 'build'} 22:05:03 INFO - ##### 22:05:03 INFO - ##### Running clobber step. 22:05:03 INFO - ##### 22:05:03 INFO - Running pre-action listener: _resource_record_pre_action 22:05:03 INFO - Running main action method: clobber 22:05:03 INFO - rmtree: /builds/slave/test/build 22:05:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:04 INFO - Running post-action listener: _resource_record_post_action 22:05:04 INFO - ##### 22:05:04 INFO - ##### Finished clobber step (success) 22:05:04 INFO - ##### 22:05:04 INFO - ##### 22:05:04 INFO - ##### Running read-buildbot-config step. 22:05:04 INFO - ##### 22:05:04 INFO - Running pre-action listener: _resource_record_pre_action 22:05:04 INFO - Running main action method: read_buildbot_config 22:05:04 INFO - Using buildbot properties: 22:05:04 INFO - { 22:05:04 INFO - "project": "", 22:05:04 INFO - "product": "firefox", 22:05:04 INFO - "script_repo_revision": "production", 22:05:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:05:04 INFO - "repository": "", 22:05:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:05:04 INFO - "buildid": "20160707193024", 22:05:04 INFO - "pgo_build": "True", 22:05:04 INFO - "basedir": "/builds/slave/test", 22:05:04 INFO - "buildnumber": 374, 22:05:04 INFO - "slavename": "talos-linux64-ix-020", 22:05:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:05:04 INFO - "platform": "ubuntu64_hw", 22:05:04 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:05:04 INFO - "branch": "mozilla-central", 22:05:04 INFO - "repo_path": "mozilla-central", 22:05:04 INFO - "revision": "23dc78b7b57e9f91798ea44c242a04e112c37db0", 22:05:04 INFO - "stage_platform": "linux64-pgo", 22:05:04 INFO - "builduid": "69d10e523b2947be969ecc9648ae0e5d", 22:05:04 INFO - "slavebuilddir": "test" 22:05:04 INFO - } 22:05:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:05:04 INFO - Running post-action listener: _resource_record_post_action 22:05:04 INFO - ##### 22:05:04 INFO - ##### Finished read-buildbot-config step (success) 22:05:04 INFO - ##### 22:05:04 INFO - ##### 22:05:04 INFO - ##### Running download-and-extract step. 22:05:04 INFO - ##### 22:05:04 INFO - Running pre-action listener: _resource_record_pre_action 22:05:04 INFO - Running main action method: download_and_extract 22:05:04 INFO - mkdir: /builds/slave/test/build/tests 22:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:04 INFO - https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:05:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:05:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:05:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:07 INFO - Downloaded 1361 bytes. 22:05:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:05:07 INFO - Using the following test package requirements: 22:05:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:05:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:05:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'jsshell-linux-x86_64.zip'], 22:05:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:05:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:05:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:05:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:05:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:05:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:05: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:05:07 INFO - https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:05:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:05:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:08 INFO - Downloaded 24304558 bytes. 22:05:08 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:05:08 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:05:09 INFO - Return code: 0 22:05:09 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:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:09 INFO - https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:05:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:05:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:13 INFO - Downloaded 24304558 bytes. 22:05:13 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:05:13 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:05:14 INFO - Return code: 0 22:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:14 INFO - https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:05:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:18 INFO - Downloaded 14403333 bytes. 22:05:18 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:05:18 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:05:18 INFO - Return code: 0 22:05:18 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:05:18 INFO - https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:05:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:05:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:05:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MiFNhldDQDSuDHT88nkyiA/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:05:20 INFO - Downloaded 60434480 bytes. 22:05:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:05:20 INFO - mkdir: /builds/slave/test/properties 22:05:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:05:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:05:20 INFO - Contents: 22:05:20 INFO - build_url:https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:05:21 INFO - Running post-action listener: _resource_record_post_action 22:05:21 INFO - Running post-action listener: set_extra_try_arguments 22:05:21 INFO - ##### 22:05:21 INFO - ##### Finished download-and-extract step (success) 22:05:21 INFO - ##### 22:05:21 INFO - ##### 22:05:21 INFO - ##### Running populate-webroot step. 22:05:21 INFO - ##### 22:05:21 INFO - Running pre-action listener: _resource_record_pre_action 22:05:21 INFO - Running main action method: populate_webroot 22:05:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:05:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:05:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:05:21 INFO - '--tppagecycles', 22:05:21 INFO - '7'], 22:05:21 INFO - 'tests': ['tsvgm']}}, 22:05:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:05:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:05:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:05:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:05:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['damp', 'tps']}, 22:05:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'tests': ['damp', 'tps']}, 22:05:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['dromaeo_dom']}, 22:05:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:05:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['basic_compositor_video']}, 22:05:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:05:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other-e10s': {'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tsvgx', 22:05:21 INFO - 'tsvgr_opacity', 22:05:21 INFO - 'tart', 22:05:21 INFO - 'tscrollx', 22:05:21 INFO - 'cart']}, 22:05:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:05:21 INFO - 'tsvgr_opacity', 22:05:21 INFO - 'tart', 22:05:21 INFO - 'tscrollx', 22:05:21 INFO - 'cart']}, 22:05:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tp5o']}, 22:05:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'tests': ['tp5o']}, 22:05:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s', 22:05:21 INFO - '--xperf_path', 22:05:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:21 INFO - 'tests': ['tp5n']}, 22:05:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--xperf_path', 22:05:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:21 INFO - 'tests': ['tp5n']}}, 22:05:21 INFO - 'talos.zip': {'path': '', 22:05:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:05:21 INFO - Running post-action listener: _resource_record_post_action 22:05:21 INFO - ##### 22:05:21 INFO - ##### Finished populate-webroot step (success) 22:05:21 INFO - ##### 22:05:21 INFO - ##### 22:05:21 INFO - ##### Running create-virtualenv step. 22:05:21 INFO - ##### 22:05:21 INFO - Running pre-action listener: _resource_record_pre_action 22:05:21 INFO - Running main action method: create_virtualenv 22:05:21 INFO - Creating virtualenv /builds/slave/test/build/venv 22:05:21 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:05:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:05:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:05:21 INFO - Using real prefix '/usr' 22:05:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:05:22 INFO - Installing distribute.............................................................................................................................................................................................done. 22:05:24 INFO - Installing pip.................done. 22:05:24 INFO - Return code: 0 22:05:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:05:24 INFO - Using env: {'DISPLAY': ':0', 22:05:24 INFO - 'HOME': '/home/cltbld', 22:05:24 INFO - 'LANG': 'en_US.UTF-8', 22:05:24 INFO - 'LANGUAGE': 'en_US:en', 22:05:24 INFO - 'LOGNAME': 'cltbld', 22:05:24 INFO - 'MAIL': '/var/mail/cltbld', 22:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:24 INFO - 'MOZ_NO_REMOTE': '1', 22:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:24 INFO - 'NO_EM_RESTART': '1', 22:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:24 INFO - 'PWD': '/builds/slave/test', 22:05:24 INFO - 'SHELL': '/bin/bash', 22:05:24 INFO - 'SHLVL': '1', 22:05:24 INFO - 'TERM': 'linux', 22:05:24 INFO - 'TMOUT': '86400', 22:05:24 INFO - 'USER': 'cltbld', 22:05:24 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:24 INFO - '_': '/tools/buildbot/bin/python'} 22:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:24 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:05:24 INFO - Cleaning up... 22:05:24 INFO - Return code: 0 22:05:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:05:24 INFO - Using env: {'DISPLAY': ':0', 22:05:24 INFO - 'HOME': '/home/cltbld', 22:05:24 INFO - 'LANG': 'en_US.UTF-8', 22:05:24 INFO - 'LANGUAGE': 'en_US:en', 22:05:24 INFO - 'LOGNAME': 'cltbld', 22:05:24 INFO - 'MAIL': '/var/mail/cltbld', 22:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:24 INFO - 'MOZ_NO_REMOTE': '1', 22:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:24 INFO - 'NO_EM_RESTART': '1', 22:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:24 INFO - 'PWD': '/builds/slave/test', 22:05:24 INFO - 'SHELL': '/bin/bash', 22:05:24 INFO - 'SHLVL': '1', 22:05:24 INFO - 'TERM': 'linux', 22:05:24 INFO - 'TMOUT': '86400', 22:05:24 INFO - 'USER': 'cltbld', 22:05:24 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:24 INFO - '_': '/tools/buildbot/bin/python'} 22:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:24 INFO - Downloading/unpacking psutil>=3.1.1 22:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:26 INFO - Installing collected packages: psutil 22:05:26 INFO - Running setup.py install for psutil 22:05:26 INFO - building 'psutil._psutil_linux' extension 22:05:26 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:05:26 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:05:26 INFO - building 'psutil._psutil_posix' extension 22:05:26 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:05:26 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:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:26 INFO - Successfully installed psutil 22:05:26 INFO - Cleaning up... 22:05:27 INFO - Return code: 0 22:05:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:05:27 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:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:27 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:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:27 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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:27 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:05:27 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:05:27 INFO - Using env: {'DISPLAY': ':0', 22:05:27 INFO - 'HOME': '/home/cltbld', 22:05:27 INFO - 'LANG': 'en_US.UTF-8', 22:05:27 INFO - 'LANGUAGE': 'en_US:en', 22:05:27 INFO - 'LOGNAME': 'cltbld', 22:05:27 INFO - 'MAIL': '/var/mail/cltbld', 22:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:27 INFO - 'MOZ_NO_REMOTE': '1', 22:05:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:27 INFO - 'NO_EM_RESTART': '1', 22:05:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:27 INFO - 'PWD': '/builds/slave/test', 22:05:27 INFO - 'SHELL': '/bin/bash', 22:05:27 INFO - 'SHLVL': '1', 22:05:27 INFO - 'TERM': 'linux', 22:05:27 INFO - 'TMOUT': '86400', 22:05:27 INFO - 'USER': 'cltbld', 22:05:27 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:27 INFO - '_': '/tools/buildbot/bin/python'} 22:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:05:27 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:05:27 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:05:27 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:05:27 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:05:27 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:05:27 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:05:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:05:29 INFO - Installing collected packages: mozsystemmonitor 22:05:29 INFO - Running setup.py install for mozsystemmonitor 22:05:29 INFO - Successfully installed mozsystemmonitor 22:05:29 INFO - Cleaning up... 22:05:29 INFO - Return code: 0 22:05:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:05:29 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:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:29 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:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:29 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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:29 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:05:29 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:05:29 INFO - Using env: {'DISPLAY': ':0', 22:05:29 INFO - 'HOME': '/home/cltbld', 22:05:29 INFO - 'LANG': 'en_US.UTF-8', 22:05:29 INFO - 'LANGUAGE': 'en_US:en', 22:05:29 INFO - 'LOGNAME': 'cltbld', 22:05:29 INFO - 'MAIL': '/var/mail/cltbld', 22:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:29 INFO - 'MOZ_NO_REMOTE': '1', 22:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:29 INFO - 'NO_EM_RESTART': '1', 22:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:29 INFO - 'PWD': '/builds/slave/test', 22:05:29 INFO - 'SHELL': '/bin/bash', 22:05:29 INFO - 'SHLVL': '1', 22:05:29 INFO - 'TERM': 'linux', 22:05:29 INFO - 'TMOUT': '86400', 22:05:29 INFO - 'USER': 'cltbld', 22:05:29 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:29 INFO - '_': '/tools/buildbot/bin/python'} 22:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:29 INFO - Downloading/unpacking jsonschema==2.5.1 22:05:29 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:05:29 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:05:29 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:05:29 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:05:29 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:05:29 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:05:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:05:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:05: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:05: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:05: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:05: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:05: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:05: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:05:31 INFO - Downloading functools32-3.2.3-2.tar.gz 22:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:05:31 INFO - warning: no files found matching '*.txt' 22:05:31 INFO - no previously-included directories found matching 'build' 22:05:31 INFO - no previously-included directories found matching 'dist' 22:05:31 INFO - no previously-included directories found matching '.git*' 22:05:31 INFO - Installing collected packages: jsonschema, functools32 22:05:31 INFO - Running setup.py install for functools32 22:05:31 INFO - warning: no files found matching '*.txt' 22:05:31 INFO - no previously-included directories found matching 'build' 22:05:31 INFO - no previously-included directories found matching 'dist' 22:05:31 INFO - no previously-included directories found matching '.git*' 22:05:31 INFO - Successfully installed jsonschema functools32 22:05:31 INFO - Cleaning up... 22:05:31 INFO - Return code: 0 22:05:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:05: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:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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', '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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:05: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 blobuploader==1.2.4 22:05:31 INFO - Using env: {'DISPLAY': ':0', 22:05:31 INFO - 'HOME': '/home/cltbld', 22:05:31 INFO - 'LANG': 'en_US.UTF-8', 22:05:31 INFO - 'LANGUAGE': 'en_US:en', 22:05:31 INFO - 'LOGNAME': 'cltbld', 22:05:31 INFO - 'MAIL': '/var/mail/cltbld', 22:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:31 INFO - 'MOZ_NO_REMOTE': '1', 22:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:31 INFO - 'NO_EM_RESTART': '1', 22:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:31 INFO - 'PWD': '/builds/slave/test', 22:05:31 INFO - 'SHELL': '/bin/bash', 22:05:31 INFO - 'SHLVL': '1', 22:05:31 INFO - 'TERM': 'linux', 22:05:31 INFO - 'TMOUT': '86400', 22:05:31 INFO - 'USER': 'cltbld', 22:05:31 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:31 INFO - '_': '/tools/buildbot/bin/python'} 22:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:32 INFO - Downloading/unpacking blobuploader==1.2.4 22:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:34 INFO - Downloading blobuploader-1.2.4.tar.gz 22:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:05:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:05: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:05: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:05: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:05: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:05: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:05: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:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:05:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:05: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:05: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:05: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:05: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:05: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:05: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:05:34 INFO - Downloading docopt-0.6.1.tar.gz 22:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:05:34 INFO - Installing collected packages: blobuploader, requests, docopt 22:05:34 INFO - Running setup.py install for blobuploader 22:05:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:05:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:05:35 INFO - Running setup.py install for requests 22:05:35 INFO - Running setup.py install for docopt 22:05:35 INFO - Successfully installed blobuploader requests docopt 22:05:35 INFO - Cleaning up... 22:05:35 INFO - Return code: 0 22:05:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:35 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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:35 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:05:35 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:05:35 INFO - Using env: {'DISPLAY': ':0', 22:05:35 INFO - 'HOME': '/home/cltbld', 22:05:35 INFO - 'LANG': 'en_US.UTF-8', 22:05:35 INFO - 'LANGUAGE': 'en_US:en', 22:05:35 INFO - 'LOGNAME': 'cltbld', 22:05:35 INFO - 'MAIL': '/var/mail/cltbld', 22:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:35 INFO - 'MOZ_NO_REMOTE': '1', 22:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:35 INFO - 'NO_EM_RESTART': '1', 22:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:35 INFO - 'PWD': '/builds/slave/test', 22:05:35 INFO - 'SHELL': '/bin/bash', 22:05:35 INFO - 'SHLVL': '1', 22:05:35 INFO - 'TERM': 'linux', 22:05:35 INFO - 'TMOUT': '86400', 22:05:35 INFO - 'USER': 'cltbld', 22:05:35 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:35 INFO - '_': '/tools/buildbot/bin/python'} 22:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:35 INFO - Running setup.py (path:/tmp/pip-JpHtS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:35 INFO - Running setup.py (path:/tmp/pip-jXOaOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:35 INFO - Running setup.py (path:/tmp/pip-P2ls6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:36 INFO - Running setup.py (path:/tmp/pip-3lppis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:36 INFO - Running setup.py (path:/tmp/pip-qQDpCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:36 INFO - Running setup.py (path:/tmp/pip-zQ8jJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:36 INFO - Running setup.py (path:/tmp/pip-Ek915m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:36 INFO - Running setup.py (path:/tmp/pip-A9wuXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:36 INFO - Running setup.py (path:/tmp/pip-_XQr0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:36 INFO - Running setup.py (path:/tmp/pip-0MKjoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:36 INFO - Running setup.py (path:/tmp/pip-E_ErYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:36 INFO - Running setup.py (path:/tmp/pip-12O8nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:36 INFO - Running setup.py (path:/tmp/pip-nJ0LMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:36 INFO - Running setup.py (path:/tmp/pip-KGp3i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:36 INFO - Running setup.py (path:/tmp/pip-nnC9Cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:36 INFO - Running setup.py (path:/tmp/pip-znWsCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:36 INFO - Running setup.py (path:/tmp/pip-yDkCEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:05:37 INFO - Running setup.py install for manifestparser 22:05:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Running setup.py install for mozcrash 22:05:37 INFO - Running setup.py install for mozdebug 22:05:37 INFO - Running setup.py install for mozdevice 22:05:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Running setup.py install for mozfile 22:05:37 INFO - Running setup.py install for mozhttpd 22:05:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Running setup.py install for mozinfo 22:05:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Running setup.py install for mozInstall 22:05:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:05:37 INFO - Running setup.py install for mozleak 22:05:37 INFO - Running setup.py install for mozlog 22:05:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Running setup.py install for moznetwork 22:05:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Running setup.py install for mozprocess 22:05:38 INFO - Running setup.py install for mozprofile 22:05:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Running setup.py install for mozrunner 22:05:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Running setup.py install for mozscreenshot 22:05:38 INFO - Running setup.py install for moztest 22:05:38 INFO - Running setup.py install for mozversion 22:05:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:05:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:05:38 INFO - Cleaning up... 22:05:38 INFO - Return code: 0 22:05:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:38 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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:38 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:05:38 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:05:38 INFO - Using env: {'DISPLAY': ':0', 22:05:38 INFO - 'HOME': '/home/cltbld', 22:05:38 INFO - 'LANG': 'en_US.UTF-8', 22:05:38 INFO - 'LANGUAGE': 'en_US:en', 22:05:38 INFO - 'LOGNAME': 'cltbld', 22:05:38 INFO - 'MAIL': '/var/mail/cltbld', 22:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:38 INFO - 'MOZ_NO_REMOTE': '1', 22:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:38 INFO - 'NO_EM_RESTART': '1', 22:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:38 INFO - 'PWD': '/builds/slave/test', 22:05:38 INFO - 'SHELL': '/bin/bash', 22:05:38 INFO - 'SHLVL': '1', 22:05:38 INFO - 'TERM': 'linux', 22:05:38 INFO - 'TMOUT': '86400', 22:05:38 INFO - 'USER': 'cltbld', 22:05:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:38 INFO - '_': '/tools/buildbot/bin/python'} 22:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:39 INFO - Running setup.py (path:/tmp/pip-WABmlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:39 INFO - Running setup.py (path:/tmp/pip-6PaVK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:39 INFO - Running setup.py (path:/tmp/pip-HzeiDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:39 INFO - Running setup.py (path:/tmp/pip-U0AtuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:39 INFO - Running setup.py (path:/tmp/pip-Z1arz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:39 INFO - Running setup.py (path:/tmp/pip-FI_cba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:39 INFO - Running setup.py (path:/tmp/pip-zv2yYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:39 INFO - Running setup.py (path:/tmp/pip-16XKN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:39 INFO - Running setup.py (path:/tmp/pip-_V12fK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:39 INFO - Running setup.py (path:/tmp/pip-pkCEOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:39 INFO - Running setup.py (path:/tmp/pip-WAlvJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:39 INFO - Running setup.py (path:/tmp/pip-km3Ns6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:39 INFO - Running setup.py (path:/tmp/pip-d6YT5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:39 INFO - Running setup.py (path:/tmp/pip-nef1dV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:40 INFO - Running setup.py (path:/tmp/pip-5fmnvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:40 INFO - Running setup.py (path:/tmp/pip-CGBEqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:40 INFO - Running setup.py (path:/tmp/pip-OAoOBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:40 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:05:40 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:05:40 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:05:40 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:05:40 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:05:40 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:05:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:05:40 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:05:40 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:05:40 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:05:40 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:05:40 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:05:40 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:05:42 INFO - Downloading blessings-1.6.tar.gz 22:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:05:42 INFO - Installing collected packages: blessings 22:05:42 INFO - Running setup.py install for blessings 22:05:42 INFO - Successfully installed blessings 22:05:42 INFO - Cleaning up... 22:05:42 INFO - Return code: 0 22:05:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:05:42 INFO - Reading from file tmpfile_stdout 22:05:42 INFO - Current package versions: 22:05:42 INFO - argparse == 1.2.1 22:05:42 INFO - blessings == 1.6 22:05:42 INFO - blobuploader == 1.2.4 22:05:42 INFO - docopt == 0.6.1 22:05:42 INFO - functools32 == 3.2.3-2 22:05:42 INFO - jsonschema == 2.5.1 22:05:42 INFO - manifestparser == 1.1 22:05:42 INFO - mozInstall == 1.12 22:05:42 INFO - mozcrash == 0.17 22:05:42 INFO - mozdebug == 0.1 22:05:42 INFO - mozdevice == 0.48 22:05:42 INFO - mozfile == 1.2 22:05:42 INFO - mozhttpd == 0.7 22:05:42 INFO - mozinfo == 0.9 22:05:42 INFO - mozleak == 0.1 22:05:42 INFO - mozlog == 3.2 22:05:42 INFO - moznetwork == 0.27 22:05:42 INFO - mozprocess == 0.23 22:05:42 INFO - mozprofile == 0.28 22:05:42 INFO - mozrunner == 6.12 22:05:42 INFO - mozscreenshot == 0.1 22:05:42 INFO - mozsystemmonitor == 0.3 22:05:42 INFO - moztest == 0.7 22:05:42 INFO - mozversion == 1.4 22:05:42 INFO - psutil == 3.1.1 22:05:42 INFO - requests == 1.2.3 22:05:42 INFO - wsgiref == 0.1.2 22:05:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05: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:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:42 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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:42 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:05:42 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:05:42 INFO - Using env: {'DISPLAY': ':0', 22:05:42 INFO - 'HOME': '/home/cltbld', 22:05:42 INFO - 'LANG': 'en_US.UTF-8', 22:05:42 INFO - 'LANGUAGE': 'en_US:en', 22:05:42 INFO - 'LOGNAME': 'cltbld', 22:05:42 INFO - 'MAIL': '/var/mail/cltbld', 22:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:42 INFO - 'MOZ_NO_REMOTE': '1', 22:05:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:42 INFO - 'NO_EM_RESTART': '1', 22:05:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:42 INFO - 'PWD': '/builds/slave/test', 22:05:42 INFO - 'SHELL': '/bin/bash', 22:05:42 INFO - 'SHLVL': '1', 22:05:42 INFO - 'TERM': 'linux', 22:05:42 INFO - 'TMOUT': '86400', 22:05:42 INFO - 'USER': 'cltbld', 22:05:42 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:42 INFO - '_': '/tools/buildbot/bin/python'} 22:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 INFO - Cleaning up... 22:05:42 INFO - Return code: 0 22:05:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:05: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:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:42 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 0x1d2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, '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': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', '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:05:42 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:05:42 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:05:42 INFO - Using env: {'DISPLAY': ':0', 22:05:42 INFO - 'HOME': '/home/cltbld', 22:05:42 INFO - 'LANG': 'en_US.UTF-8', 22:05:42 INFO - 'LANGUAGE': 'en_US:en', 22:05:42 INFO - 'LOGNAME': 'cltbld', 22:05:42 INFO - 'MAIL': '/var/mail/cltbld', 22:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:42 INFO - 'MOZ_NO_REMOTE': '1', 22:05:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:42 INFO - 'NO_EM_RESTART': '1', 22:05:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:42 INFO - 'PWD': '/builds/slave/test', 22:05:42 INFO - 'SHELL': '/bin/bash', 22:05:42 INFO - 'SHLVL': '1', 22:05:42 INFO - 'TERM': 'linux', 22:05:42 INFO - 'TMOUT': '86400', 22:05:42 INFO - 'USER': 'cltbld', 22:05:42 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:42 INFO - '_': '/tools/buildbot/bin/python'} 22:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:05:43 INFO - Cleaning up... 22:05:43 INFO - Return code: 0 22:05:43 INFO - Running post-action listener: _resource_record_post_action 22:05:43 INFO - Running post-action listener: _start_resource_monitoring 22:05:43 INFO - Starting resource monitoring. 22:05:43 INFO - ##### 22:05:43 INFO - ##### Finished create-virtualenv step (success) 22:05:43 INFO - ##### 22:05:43 INFO - ##### 22:05:43 INFO - ##### Running install step. 22:05:43 INFO - ##### 22:05:43 INFO - Running pre-action listener: _resource_record_pre_action 22:05:43 INFO - Running main action method: install 22:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:05:43 INFO - Reading from file tmpfile_stdout 22:05:43 INFO - Detecting whether we're running mozinstall >=1.0... 22:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:05:43 INFO - Reading from file tmpfile_stdout 22:05:43 INFO - Output received: 22:05:43 INFO - Usage: mozinstall [options] installer 22:05:43 INFO - Options: 22:05:43 INFO - -h, --help show this help message and exit 22:05:43 INFO - -d DEST, --destination=DEST 22:05:43 INFO - Directory to install application into. [default: 22:05:43 INFO - "/builds/slave/test"] 22:05:43 INFO - --app=APP Application being installed. [default: firefox] 22:05:43 INFO - mkdir: /builds/slave/test/build/application 22:05:43 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:05:43 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:06:00 INFO - Reading from file tmpfile_stdout 22:06:00 INFO - Output received: 22:06:00 INFO - /builds/slave/test/build/application/firefox/firefox 22:06:00 INFO - Running post-action listener: _resource_record_post_action 22:06:00 INFO - ##### 22:06:00 INFO - ##### Finished install step (success) 22:06:00 INFO - ##### 22:06:00 INFO - ##### 22:06:00 INFO - ##### Running run-tests step. 22:06:00 INFO - ##### 22:06:00 INFO - Running pre-action listener: _resource_record_pre_action 22:06:00 INFO - Running main action method: run_tests 22:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:06:00 INFO - Python 2.7.3 22:06:00 INFO - Return code: 0 22:06:00 INFO - grabbing minidump binary from tooltool 22:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:06:00 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 0x1feb3b0>, '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 0x1fec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fec740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:06:00 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:06:00 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:06:00 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:06:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:06:00 INFO - Return code: 0 22:06:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:06:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:06:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:06:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:06:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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:06:00 INFO - Using env: {'DISPLAY': ':0', 22:06:00 INFO - 'HOME': '/home/cltbld', 22:06:00 INFO - 'LANG': 'en_US.UTF-8', 22:06:00 INFO - 'LANGUAGE': 'en_US:en', 22:06:00 INFO - 'LOGNAME': 'cltbld', 22:06:00 INFO - 'MAIL': '/var/mail/cltbld', 22:06:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:06:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:00 INFO - 'MOZ_NO_REMOTE': '1', 22:06:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:00 INFO - 'NO_EM_RESTART': '1', 22:06:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:00 INFO - 'PWD': '/builds/slave/test', 22:06:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:06:00 INFO - 'SHELL': '/bin/bash', 22:06:00 INFO - 'SHLVL': '1', 22:06:00 INFO - 'TERM': 'linux', 22:06:00 INFO - 'TMOUT': '86400', 22:06:00 INFO - 'USER': 'cltbld', 22:06:00 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926', 22:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:00 INFO - '_': '/tools/buildbot/bin/python'} 22:06:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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:06:00 INFO - mozversion application_buildid: 20160707193024 22:06:00 INFO - mozversion application_changeset: 23dc78b7b57e9f91798ea44c242a04e112c37db0 22:06:00 INFO - mozversion application_display_name: Nightly 22:06:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:06:00 INFO - mozversion application_name: Firefox 22:06:00 INFO - mozversion application_remotingname: firefox 22:06:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:06:00 INFO - mozversion application_vendor: Mozilla 22:06:00 INFO - mozversion application_version: 50.0a1 22:06:00 INFO - mozversion platform_buildid: 20160707193024 22:06:00 INFO - mozversion platform_changeset: 23dc78b7b57e9f91798ea44c242a04e112c37db0 22:06:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:06:00 INFO - mozversion platform_version: 50.0a1 22:06:00 INFO - using testdate: 1467954360 22:06:00 INFO - actual date: 1467954360 22:06:00 INFO - starting webserver on 'localhost:37053' 22:06:00 INFO - SUITE-START | Running 2 tests 22:06:00 INFO - TEST-START | dromaeo_css 22:06:00 INFO - operating with platform_type : linux_ 22:06:00 INFO - Initialising browser for dromaeo_css test... 22:06:00 INFO - TEST-INFO | started process 677 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8g4SqO/profile http://localhost:37053/getInfo.html) 22:06:04 INFO - PROCESS | 677 | __metrics Screen width/height:1600/1200 22:06:04 INFO - PROCESS | 677 | colorDepth:24 22:06:04 INFO - PROCESS | 677 | Browser inner width/height: 1024/697 22:06:04 INFO - PROCESS | 677 | __metrics 22:06:06 INFO - PROCESS | 677 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:06:07 INFO - TEST-INFO | 677: exit 0 22:06:07 INFO - Browser initialized. 22:06:07 INFO - Running cycle 1/1 for dromaeo_css test... 22:06:07 INFO - TEST-INFO | started process 761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8g4SqO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:18 INFO - PROCESS | 761 | RSS: Main: 156672000 22:06:18 INFO - PROCESS | 761 | 22:07:10 INFO - PROCESS | 761 | 1467954430615 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:09:18 INFO - PROCESS | 761 | Cycle 1(1): loaded http://localhost:37053/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37053/tests/dromaeo/cssquery-ext.html) 22:09:19 INFO - PROCESS | 761 | RSS: Main: 246513664 22:09:19 INFO - PROCESS | 761 | 22:12:21 INFO - PROCESS | 761 | Cycle 1(1): loaded http://localhost:37053/tests/dromaeo/cssquery-ext.html (next: http://localhost:37053/tests/dromaeo/cssquery-jquery.html) 22:12:22 INFO - PROCESS | 761 | RSS: Main: 242827264 22:12:22 INFO - PROCESS | 761 | 22:15:24 INFO - PROCESS | 761 | Cycle 1(1): loaded http://localhost:37053/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37053/tests/dromaeo/cssquery-mootools.html) 22:15:24 INFO - PROCESS | 761 | RSS: Main: 240128000 22:15:24 INFO - PROCESS | 761 | 22:18:26 INFO - PROCESS | 761 | Cycle 1(1): loaded http://localhost:37053/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37053/tests/dromaeo/cssquery-prototype.html) 22:18:27 INFO - PROCESS | 761 | RSS: Main: 245268480 22:18:27 INFO - PROCESS | 761 | 22:21:30 INFO - PROCESS | 761 | Cycle 1(1): loaded http://localhost:37053/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37053/tests/dromaeo/cssquery-yui.html) 22:21:30 INFO - PROCESS | 761 | RSS: Main: 226902016 22:21:30 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | Cycle 1(1): loaded http://localhost:37053/tests/dromaeo/cssquery-yui.html (next: http://localhost:37053/tests/dromaeo/cssquery-dojo.html) 22:24:33 INFO - PROCESS | 761 | RSS: Main: 280543232 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | __start_tp_report 22:24:33 INFO - PROCESS | 761 | _x_x_mozilla_page_load 22:24:33 INFO - PROCESS | 761 | _x_x_mozilla_page_load_details 22:24:33 INFO - PROCESS | 761 | |i|pagename|runs| 22:24:33 INFO - PROCESS | 761 | |0;dojo.html;3079.2455848317163;3086.7376273016794;3116.8285744284053;3118.968810311896;3136.937261254775;5243.79024839007;5331.760070796814;5399.48704873037;5457.590680698949;5505.0366185917455;5457.181422786574;5458.836234912949;5461.836144915659;5516.420775818541;5537.695426751527;4892.608591312695;4908.9263661045115;4962.305277261186;4972.428170760372;5088.872778180538;4995;5013.924791128136;5041.117804384218;5069.923951140736;5074.391073071218;4747.216709242971;4783.019481006403;4868.269759536062;4937.23472861707;4939.431965323998;4890.24201248807;4892.559669629715;4896.4858689837765;4899.608031357483;4999.525045120708;4907.1657818171;4934.481879402683;4935.1363511385725;4939.456659767423;4957.380327459067;5293.841184764463;5371.060064488738;5402.081646120129;5452.454754524555;5454.563634909197;5826.82519524415;5829.358770535237;5897.764089436418;5898.262717160355;5903.822885313448;5875.529957603381;5910.438508341701;5939.020061689816;5939.049752039696;5939.67331796751;5207.661502002357;5344.118220493614;5376.650517716336;5408.756605952742;5428.131498960148;5914.378990206053;5928.555358348141;5974.492168165754;5976.103584462278;5985.132155837467;5454.427285135083;5736.4550367715;5740.56945729072;5740.827775166752;5742.425757424307;5633.4084921082695;5760.337561180518;5760.884782304331;5767.798127065533;5768.105943578752;5697.059985102412;5697.173909783141;5699.2590963174525;5701.344345400249;5749.568782341257;5549.361823390281;5644.9435505644415;5698.715064246854;5704.372519022904;5720.77116915327;4422.668299877458;4432.180046691373;4436.977815110904;4475.037866858641;4475.1049790042125;3935.0162459385156;3936.2127574484643;3943.743656662308;3955.604439556079;3981.3231750602467;5200.60995425337;5289.841304760864;5422.6475279106735;5483.890322193613;5552.666839989613;5817.185594016841;5847.181394604759;5863.3257175424515;5873.882522349614;5917.556183286185;5464.508194262536;5478.479544443271;5478.643888147338;5481.890362192734;5482.451754824486;4859.1010663027455;4938.4320803107385;4968.6025117990475;5016.598672106223;5078.492150784892;6012.39876012404;6018.6990650467305;6026.246719160105;6031.457168854824;6065.75736970525;5819.79630712923;5832.1543376209365;5832.562557808096;5834.27071616048;5852.063669812809;5790.305163380422;5985.431384018511;6013.488853447506;6015.187949626745;6023.216981792514;5755.798547050833;5761.193432919311;5778.971105144447;5779.7110144493445;5784.132380143013;5796.159556864147;5866.266716660418;5871.2073870027;5874.794382196603;5881.2942446906645;5431.646942948696;5613.46672066153;5701.857453563694;5711.400302968129;5748.425157484218;5689.402612725769;5697.4587414195585;5704.458076482728;5709.543236540983;5714.257146570919;5354.437784032621;5611.3266408031295;5628.521575666115;5637;5641.605087643826;5557.777688892399;5576.972115139399;5607.299087614048;5647.830565083054;5706.144078388109;5550.27846379968;5554.222408862844;5555.749991250323;5557.8610534735335;5562.3047119110115 22:24:33 INFO - PROCESS | 761 | |1;ext.html;610.8808782287455;627.5952010952933;627.9780207692731;631.3339426904615;641.2881701311546;31544.84227578862;34024.31951360974;44006.779966100126;44901.550984490146;45010.32484512727;45014.32478512817;45316.54683453165;45380;45417.31874021892;45432.545674543246;9007.279417646589;9130.04134565871;9143.725688229364;9150.313726470507;9151.633934642607;8892.421992570462;8983.281337492985;8984.371094023421;8991;8997.280217582611;9009.819803603925;9039.45763254207;9054;9056.909430905705;9065.18413342799;8993.055729148444;9022.593983270703;9023.503707296097;9033.367664263504;9037.728868133967;8867.379283450162;8922.107789221025;8927.732168034969;8930.776730581723;8934.195922366953;42610.57389426128;42906;43099;43153.78423107896;43443.565564344586;3440.3119376124873;3653.2876089162623;3662.6520480554304;4019.356902895552;4202.865226388858;32834;33052.190563801865;33953.49069763956;34322.313553729036;34866.30267394638;4528;4543.363929049936;4570.65720070996;4607.493175750665;4784.6889952153;366843;366862;369362;370595;371962;59632.40367596355;60047.39952600418;60173;60315.69842150761;60363.39636603578;56115;61788;63299.36700632935;63688;63773.36226637767;16888.83111168897;17121.828781712273;17244.741328880078;17304;17306.048167350917;13127.934360328138;13211.867881321255;13366.93316533411;13380.598582042554;13399.062065655311;15938.60153496172;16281;17665.646687066186;18163.273469061354;18230.817691823177;5713.342965558931;5905.320888097838;5980.8803823924145;6006.699665016732;6013.579049466583;940.7601061729215;971.8590804333332;1013.8428543575758;1063.4363787192801;1109.877913429522;32365.51451728226;33086.3382732344;33176.17059573482;33233.3353332932;33294.667053329154;1100.0814320042693;1117.474786850179;1135.6365962891796;1153.3541216918552;1209.5947857467847;17247.223874925658;17611.735823962652;17613.207405666777;17656.823431765773;17705.911470442566;153839.23080384752;154008;155754.22122889312;155898;155902;59448.10827837586;59716.70141649264;59908.70045649744;59914;60049;1324;1346.602752188107;1346.8249127613346;1352.7159296547836;1360.4626172661742;30743.846280768452;32013.199670008435;32701.34597307993;32716.182095446853;32791.50812737763;51740.22389664233;51772;52082.47917520928;52152;52220.47779522308;508200;511084;511881;513064;514835;24817.875910620332;27517;28276.575851362657;43315.13369732525;43825.34261986137;37020.25959480849;37968;40058.799706001286;48425.75787121183;48763.756181218865;41728.58271417247;42064.36903446512;42450;42848.57151428446;43404.13191736084;41351;41363.1727365445;41581.1683766329;42274.36588451237;42340.78829605832;41165.79417103015;41221;42567.148657027305;42610.36084458797;42733.35899961565;36191.63808361988;36587;36701;37275.23348989972;37314.253714925006;27091.59362609602;28001.579976300778;28217.71782282151;28432.57351139776;28938.855305723337 22:24:33 INFO - PROCESS | 761 | |2;jquery.html;385.6336480343674;394.37688452245453;397.1362287025718;397.1560434077585;410.57915636472615;18166.18252178652;18906.810931890675;19783;20210.292639757612;20224.89887550562;18444.44666659999;18929.148188331525;19560.31538896139;19594.118264678087;19658.115384807683;5162.303089082972;5266.631335806495;5353.063213937565;5599.160125981095;5799.21710569072;5650.858728531778;5731.455511726379;5739.454751798572;5764.53883689306;5768.278965129359;5514.228008078852;5619.466150715675;5696.51579615731;5784.82645520635;5826.82519524415;5884.735186916599;5905.084711869667;5908.61593996391;5917.674527900964;5920.31916329619;5493.917591236134;5605.523530499871;5617.747201375948;5627.521660658849;5630.493255606974;14212.715745685131;14527.418903243857;14529.200893950829;19896.005199739957;22804.429889252737;2340.239422187779;2376.7504412036833;2404.266698656909;2587.4825034992887;2717.483678101174;10475;11353.829692554618;12903.741925161537;14716.117032978056;15047.172405517693;1945.6886898096236;1992.1234656751128;2006.5284658105334;2027.4019164346405;2057.3930690446355;60023;74986.25013749902;77029.22970770333;80497.59751201207;84651;10244.692659220087;11034.779304413867;11174;11219.607313744144;11287.661370158909;14318.642033949234;14696.26518674062;14810.185439800915;14818.777718334233;15096.849031509764;8350.70772522971;8454.957725211458;8485.703000394957;8525.360597955178;8641.783955401166;8089.433739638286;8110.797230069177;8154.510729356258;8195.795105122419;8278.089410164877;4712.175369310357;4767.141914455362;4785.641076919245;4889.731064791471;4913.287573301851;1938.932137375185;1948.785633580305;1957.363856746563;1968.5430792785849;2009.829164521003;908.5956749246654;937.3672770879629;988.1995583577325;1002.9260317127979;1016.3139880580578;19163.425097247105;20254.493637659176;20338.694919576217;20391.18435262573;20455.4886127848;1006.6174853555602;1013.4628646817199;1021.070825548747;1023.155896385485;1077.6389909380446;10642;11191.944040279746;11205.43972801373;11219.495122719498;11224.32654040744;385031;388569;389071;532223;538310;100765.49617251867;103776.48111759544;107261;107854;108939.45530272298;1025.8050970315617;1037.6264544763878;1063.2769716592636;1065.3820783945293;1068.6633710381204;17979.19093640763;18181.18184681666;18374.26502939921;18647.72028419602;18837;28303.432293123788;38860.41709374418;40605.39091913683;44190.779046104566;46943.06113877635;56404.89773062521;59658.70170649119;60482.39517604768;61742;63216.36783632105;7807.570583617956;7998.600069996361;8116.472429292077;8747.912520874965;9077.09229077087;10378.896211038094;14120.082194657314;14216.502422415166;14335.211563364115;14469.871387742241;12168.330741808932;12515.186512876633;12631.557895473614;12676.492940282285;12924;11459.19785614999;11590.362530060927;11727;11800.114991375509;11815;12330.445129968994;12353.938230308791;12725;12725.109242353317;12828.294443805307;7406.222346653523;7638.312551870358;7736.535807851547;8121.8375632488205;8501.234888860032;10638.14894808398;11082.833757493805;11088.611898583511;11234.775304494027;11364.772704545698 22:24:33 INFO - PROCESS | 761 | |3;mootools.html;1446.746819306621;1476.586555764386;1582.778411022457;1693.7798086248786;1751.4482937874568;8504.064552899175;8980.550972451383;9048.547572621368;9054.45673259604;9058.95470522648;7393.408527317815;8176.91823081769;8648.437851539646;8678.47929124253;8860.601272942733;2293.6674182243605;2307.065638416444;2317.084751523147;2323.756005619411;2324.9418764530938;1937.331620590901;2292.1175347491253;2313.3869524576003;2314.9421264468356;2319.3273950554317;2249.280230326296;2304.193532263711;2313.2366319114735;2321.7097862767155;2347.730011048734;2329.4758679297106;2337.789598936104;2345.5074434368803;2350.4828937633692;2360.6577046328357;2265.2184996176456;2268.739095004079;2283.977160228393;2287.462446325112;2330.4406942333717;8617.65529378824;8654.826903461895;8657.437266577652;8660.740177794676;8673.869891951626;5172.482751724835;5316.574674026068;5316.9202461963105;5336.786528538853;5337.466253374631;3282.907662082515;3356.278400143981;3402.3535528249554;3422.144463884029;3447.517347571342;1171.619223752284;1174.6476057182897;1195.1335281920624;1205.0540325844258;1205.1142410328403;12237.816432753516;12416.81374779379;12450.3152326622;12463.937680311632;12504.31226282554;4450.2434586120435;4482.39487669167;4606.332081848113;4616.192166370872;4673.158831410379;10094.545745441474;10094.646687366054;10118.342307749972;10120.746981325527;10123.291369604205;5187.170052791535;5232.476752324813;5258.316418865599;5266.3943646480375;5271.973640131775;4720.150372932905;4743.881402964885;4823.421189457287;4827.806887724522;4832.395950506187;3801.163743976321;3834.8082595870096;3860.3244432224246;3912.8630497932436;3928.508936382952;1114.7603265297837;1211.4608998995363;1219.536576101076;1251.9374031298398;1263.0779530942464;938.8920274168421;968.801395713877;983.4640121133999;985.7141428985657;995.3878364805593;5722.62802917809;5727.398623144593;5753.395893431213;5757.481826635623;5768.278965129359;935.6771913689767;951.058452132384;969.5346233807812;971.8882328532168;1004.437514991607;4153.833846646161;4183.184279065553;4194.328907374806;4203.906984184073;4214.241436541452;10679.51942162589;11176.608818691308;11195.72010699739;11199.832002519968;11250.66248012561;9259.35184537076;11065.50205240766;11093.725786213969;11115.833262500908;11127.554897804159;7620.314171724572;7830.334421574116;7925;8033.317168040782;8040.678372865137;5961.195238642901;6168.568200225941;6202.689865506797;6217.937820621736;6342.49260059185;9486.905130948602;9740.464274464972;9841.950790246003;9849.458279794393;10027.849582256418;9992.500374981368;10079.596816127567;10121.240906932157;10121.392716437038;10126.44304563256;7658.310752032344;7673.271039251262;7701.191374905554;7759.6120193988945;7761.7671469855995;7935.484193527403;8076.515409075474;8114.06688230861;8121.309688676529;8138.226868447488;7058.047163632844;7213.098362704662;7306.890396644161;7359.1169059713175;7486.139094004041;7412.481126321106;7427.740029098955;7462.888056679263;7468.325408455318;7545.132309784445;6744.595324280559;6808.012838138541;6818.522703410714;6828.351306626061;6834.965825171042;4981.252812078217;5187.273781670494;5194.22086687;5250.212468129811;5280.630355875205;4976.054549635558;5003.274525193754;5095.92356114666;5255.080360936897;5298.337707786527 22:24:33 INFO - PROCESS | 761 | |4;prototype.html;324.07315078874416;326.7630967548527;333.2501870790721;335.2557322743509;336.6213010363341;5255.579553635709;5320.600954928386;5321.308229930113;5346.91979620306;5350.5184533391985;5214.582833373331;5253.4221235664045;5303.257543943851;5312.814051508198;5324.361076670795;3913.5890731473214;4011.7191796574252;4106.1992911382295;4111.36273877548;4170.77060761658;4079.77561234132;4120.855770048034;4129.896752581194;4170.812313445902;4171.436856024443;3910.2570511602703;4085.1829634073156;4108.239975604508;4108.609682080198;4128.772917489537;3995.8201880915285;4129.318662420697;4140.296149654566;4147.212029714345;4153.190127925056;3665.981670091659;3672.7245456590595;3674.2467794102413;3679.889603311905;3692.6861216796606;5091.61812864033;5113;5150.3047088642925;5152.819651312186;5163.922541161885;4359.80380882861;4621.4223222097235;4649.070185962787;4653.278741795028;4663.463701674317;5440.4287549807495;5467.6719396836315;5499.120140777456;5507.339119305709;5521.420250873641;3852.2295540891932;3854.807259637007;3862.478565393692;3863.497745293122;3874.2445223181207;5567.805126820542;5670.858228544278;5679.432056794329;5702.6863522506665;5704.600677952545;3996.800159991989;4012.35802271641;4014.8594799181888;4029.1135950090934;4030;4060.659982205865;4075.349483459204;4082.9183416331507;4100.91798164041;4104.56902025283;4594.563516465931;4653.325267836212;4657;4680.859574212779;4709.246520556694;5402.51377376038;5513.696746678891;5518.889622207534;5519.8896022080135;5559.6664200148125;1403.1440821099122;1418.680796820707;1418.7659036258901;1419.5386499387741;1422.7154569086017;895.9955200223957;909.9226565741853;923.2706162131975;925.1765928323797;937.8921424036187;5205.869853253699;5209.426963034063;5228.607854410859;5245.475452454724;5265.605079619044;5564.805231816867;5586.776528938878;5594.601349662585;5605.411431799684;5624.525208908068;5142.6400151989;5165.431802501722;5259.211118332204;5273.945210957824;5292.417834038252;2977.0622253990355;2983.13489088162;3001.5197568388953;3008.9318292006024;3020;5568.554515638739;5762.942370576325;5775.971120144372;5776.913346299809;5808.215890854765;5602.85992850182;5630.183623374588;5635.69003704792;5670.914936275959;5672.461116193955;5693.829185124453;5734.505490111815;5748.281464816898;5772.249607550992;5782.335031471434;5362.785488580569;5596.776128954796;5600.775968961196;5616.241807355955;5628.465295796893;5529.474699903502;5530.529904957963;5542.667439953616;5572.331320241435;5573.470520300565;5729.369769325288;5755.913661295168;5769.538436924952;5769.653820770767;5815.534757219497;3786.280606684831;4036.1120553478186;4041.1109555897656;4046.635802777764;4051.554329023746;5671.858203544944;5672.177534257592;5685.431456854281;5694.601377903507;5706.115552089433;5372.973135134299;5550;5565.888682226414;5569.3823902765635;5573.885222955506;5407.648502847303;5413.566914646897;5454.100073487989;5473.698946557977;5502.477264659851;5283.339582552181;5364.38309594386;5408.702521361363;5444.646098003617;5451.318585176853;5538.47384498472;5554.472325129106;5589.720513974366;5601.383847776823;5613.073842816053;5482.451754824486;5495.670259784426;5497.917531236954;5501.944980550144;5520.751566179451 22:24:33 INFO - PROCESS | 761 | |5;yui.html;719.7229066809278;781.8631739445599;820.9425340226187;827.5159031966299;850.2390360627243;14344.067635603678;14859.182744949023;14911.179885106314;14929.104253744783;14993.625159371035;14407.927960360183;14815.33331000105;15248.847511524882;15284.235788210573;15304.23478826057;651.501601275025;781.1173374087274;794.3129193247828;805.9274665280124;811.1482942909951;795.387551585279;807.2653884964683;807.8868958345836;807.9515229086273;808.5391326943644;848.3510114762212;848.7708318753932;850.2815121222588;850.2900078434515;850.9149085091503;676.4216594811439;794.8847417124542;818.758466252451;841.486693117198;841.9789505262356;599.3048064245498;599.7241269016258;600.2107228993902;600.342624825815;600.4475882188398;15264.771028434583;15306.540803775904;15318.080915145127;15336.15651139187;15336.923315383463;14535.78196327056;14765.77851332231;14915;15042.398304067825;15044.623884402868;13400.718127475067;13419.329033548382;13543.932280338535;13555;13735.587932362045;4465.084657645175;4479.776011199459;4516.81932722694;4523.705959112647;4576.244919588297;73720.26279737188;73863.26136738618;74165.6291718538;74254.62872685603;74288.34611041812;11575.942120289345;11612.12909031826;11614.361210133355;11774.411279435993;12003.63989080329;18753.90623046876;19641.607167856564;19664.213431462867;19679.31122410709;19733.309334173235;8341.124181960928;8446.324294056461;8629.784255393666;8735.781605459788;8972.641094356153;4864.075825593197;4912.606991440676;4921.926171107436;5089.083964886282;5220.086484865134;3383.4755612880044;3458.4812278157974;3485.4423292273113;3487.668671476206;3493.8951831445097;509.45233873586045;630.3538872655565;658.6333358281585;670.122139996606;701.8350687588413;630.3853742600927;684.8630273945131;706.8727629026722;725.7967769024682;786.1745167574024;9598.95200523993;9966.900330996743;10034.197264218845;10158.492075396201;10349.896501034893;629.8771739510752;688.4595592459895;697.9371856532937;768.1895600141904;780.5121798875703;4910.729909854992;4914.648471670248;4958.578520825698;4966.155753521838;4973.602111831045;73447.63276183691;104325.47837260917;126143.36928315299;127818;134604;108994;111508.61896572463;112713;113415.43292283651;116347;86383;86558;86749.56625216834;87045.56477217701;87801.56099219591;8392.440305706574;8800.559972001502;8882.978457477215;9174.954125229331;9413.388129771543;57541.71229143828;57601;58008.70995645164;58387.416125839896;59829;77160.61419692865;78694;79020.60489697514;87172;88076.5596172015;12426.440810163384;13103.279319637511;13117.475300987853;13388.866111339152;13450.798238026242;12884.355782210665;13142.737145257233;13224.404901779251;13916.304184790517;14069.788953165506;13101.082924195214;13120.212787232796;13477;13519.932400337935;13547.729045418839;12824.23054616726;13061.542846000344;13285;13641.727165456437;14159.867210622919;11481.311121332747;11604.88395116038;11919.3444360561;12307.876921230672;12383.442745076673;10495;11132.220744547803;11149.275297105662;11313.264637798517;11644.184907056424;10581.735456613646;10948.56205751761;11017.83473247886;11076.224664273423;11483.253588517055 22:24:33 INFO - PROCESS | 761 | __end_tp_report 22:24:33 INFO - PROCESS | 761 | __start_cc_report 22:24:33 INFO - PROCESS | 761 | _x_x_mozilla_cycle_collect,850 22:24:33 INFO - PROCESS | 761 | __end_cc_report 22:24:33 INFO - PROCESS | 761 | __startTimestamp1467955473889__endTimestamp 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | ------- Summary: start ------- 22:24:33 INFO - PROCESS | 761 | Number of tests: 6 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | [#0] dojo.html Cycles:165 Average:5382.93 Median:5557.78 stddev:617.82 (11.1%) stddev-sans-first:592.70 22:24:33 INFO - PROCESS | 761 | Values: 3079.2 3086.7 3116.8 3119.0 3136.9 5243.8 5331.8 5399.5 5457.6 5505.0 5457.2 5458.8 5461.8 5516.4 5537.7 4892.6 4908.9 4962.3 4972.4 5088.9 4995.0 5013.9 5041.1 5069.9 5074.4 4747.2 4783.0 4868.3 4937.2 4939.4 4890.2 4892.6 4896.5 4899.6 4999.5 4907.2 4934.5 4935.1 4939.5 4957.4 5293.8 5371.1 5402.1 5452.5 5454.6 5826.8 5829.4 5897.8 5898.3 5903.8 5875.5 5910.4 5939.0 5939.0 5939.7 5207.7 5344.1 5376.7 5408.8 5428.1 5914.4 5928.6 5974.5 5976.1 5985.1 5454.4 5736.5 5740.6 5740.8 5742.4 5633.4 5760.3 5760.9 5767.8 5768.1 5697.1 5697.2 5699.3 5701.3 5749.6 5549.4 5644.9 5698.7 5704.4 5720.8 4422.7 4432.2 4437.0 4475.0 4475.1 3935.0 3936.2 3943.7 3955.6 3981.3 5200.6 5289.8 5422.6 5483.9 5552.7 5817.2 5847.2 5863.3 5873.9 5917.6 5464.5 5478.5 5478.6 5481.9 5482.5 4859.1 4938.4 4968.6 5016.6 5078.5 6012.4 6018.7 6026.2 6031.5 6065.8 5819.8 5832.2 5832.6 5834.3 5852.1 5790.3 5985.4 6013.5 6015.2 6023.2 5755.8 5761.2 5779.0 5779.7 5784.1 5796.2 5866.3 5871.2 5874.8 5881.3 5431.6 5613.5 5701.9 5711.4 5748.4 5689.4 5697.5 5704.5 5709.5 5714.3 5354.4 5611.3 5628.5 5637.0 5641.6 5557.8 5577.0 5607.3 5647.8 5706.1 5550.3 5554.2 5555.7 5557.9 5562.3 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | [#1] ext.html Cycles:180 Average:52584.88 Median:42508.57 stddev:99900.44 (235.0%) stddev-sans-first:100104.47 22:24:33 INFO - PROCESS | 761 | Values: 610.9 627.6 628.0 631.3 641.3 31544.8 34024.3 44006.8 44901.6 45010.3 45014.3 45316.5 45380.0 45417.3 45432.5 9007.3 9130.0 9143.7 9150.3 9151.6 8892.4 8983.3 8984.4 8991.0 8997.3 9009.8 9039.5 9054.0 9056.9 9065.2 8993.1 9022.6 9023.5 9033.4 9037.7 8867.4 8922.1 8927.7 8930.8 8934.2 42610.6 42906.0 43099.0 43153.8 43443.6 3440.3 3653.3 3662.7 4019.4 4202.9 32834.0 33052.2 33953.5 34322.3 34866.3 4528.0 4543.4 4570.7 4607.5 4784.7 366843.0 366862.0 369362.0 370595.0 371962.0 59632.4 60047.4 60173.0 60315.7 60363.4 56115.0 61788.0 63299.4 63688.0 63773.4 16888.8 17121.8 17244.7 17304.0 17306.0 13127.9 13211.9 13366.9 13380.6 13399.1 15938.6 16281.0 17665.6 18163.3 18230.8 5713.3 5905.3 5980.9 6006.7 6013.6 940.8 971.9 1013.8 1063.4 1109.9 32365.5 33086.3 33176.2 33233.3 33294.7 1100.1 1117.5 1135.6 1153.4 1209.6 17247.2 17611.7 17613.2 17656.8 17705.9 153839.2 154008.0 155754.2 155898.0 155902.0 59448.1 59716.7 59908.7 59914.0 60049.0 1324.0 1346.6 1346.8 1352.7 1360.5 30743.8 32013.2 32701.3 32716.2 32791.5 51740.2 51772.0 52082.5 52152.0 52220.5 508200.0 511084.0 511881.0 513064.0 514835.0 24817.9 27517.0 28276.6 43315.1 43825.3 37020.3 37968.0 40058.8 48425.8 48763.8 41728.6 42064.4 42450.0 42848.6 43404.1 41351.0 41363.2 41581.2 42274.4 42340.8 41165.8 41221.0 42567.1 42610.4 42733.4 36191.6 36587.0 36701.0 37275.2 37314.3 27091.6 28001.6 28217.7 28432.6 28938.9 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | [#2] jquery.html Cycles:180 Average:28039.78 Median:11140.95 stddev:75172.72 (674.7%) stddev-sans-first:75354.93 22:24:33 INFO - PROCESS | 761 | Values: 385.6 394.4 397.1 397.2 410.6 18166.2 18906.8 19783.0 20210.3 20224.9 18444.4 18929.1 19560.3 19594.1 19658.1 5162.3 5266.6 5353.1 5599.2 5799.2 5650.9 5731.5 5739.5 5764.5 5768.3 5514.2 5619.5 5696.5 5784.8 5826.8 5884.7 5905.1 5908.6 5917.7 5920.3 5493.9 5605.5 5617.7 5627.5 5630.5 14212.7 14527.4 14529.2 19896.0 22804.4 2340.2 2376.8 2404.3 2587.5 2717.5 10475.0 11353.8 12903.7 14716.1 15047.2 1945.7 1992.1 2006.5 2027.4 2057.4 60023.0 74986.3 77029.2 80497.6 84651.0 10244.7 11034.8 11174.0 11219.6 11287.7 14318.6 14696.3 14810.2 14818.8 15096.8 8350.7 8455.0 8485.7 8525.4 8641.8 8089.4 8110.8 8154.5 8195.8 8278.1 4712.2 4767.1 4785.6 4889.7 4913.3 1938.9 1948.8 1957.4 1968.5 2009.8 908.6 937.4 988.2 1002.9 1016.3 19163.4 20254.5 20338.7 20391.2 20455.5 1006.6 1013.5 1021.1 1023.2 1077.6 10642.0 11191.9 11205.4 11219.5 11224.3 385031.0 388569.0 389071.0 532223.0 538310.0 100765.5 103776.5 107261.0 107854.0 108939.5 1025.8 1037.6 1063.3 1065.4 1068.7 17979.2 18181.2 18374.3 18647.7 18837.0 28303.4 38860.4 40605.4 44190.8 46943.1 56404.9 59658.7 60482.4 61742.0 63216.4 7807.6 7998.6 8116.5 8747.9 9077.1 10378.9 14120.1 14216.5 14335.2 14469.9 12168.3 12515.2 12631.6 12676.5 12924.0 11459.2 11590.4 11727.0 11800.1 11815.0 12330.4 12353.9 12725.0 12725.1 12828.3 7406.2 7638.3 7736.5 8121.8 8501.2 10638.1 11082.8 11088.6 11234.8 11364.8 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | [#3] mootools.html Cycles:180 Average:5732.96 Median:4978.65 stddev:3249.08 (65.3%) stddev-sans-first:3242.23 22:24:33 INFO - PROCESS | 761 | Values: 1446.7 1476.6 1582.8 1693.8 1751.4 8504.1 8980.6 9048.5 9054.5 9059.0 7393.4 8176.9 8648.4 8678.5 8860.6 2293.7 2307.1 2317.1 2323.8 2324.9 1937.3 2292.1 2313.4 2314.9 2319.3 2249.3 2304.2 2313.2 2321.7 2347.7 2329.5 2337.8 2345.5 2350.5 2360.7 2265.2 2268.7 2284.0 2287.5 2330.4 8617.7 8654.8 8657.4 8660.7 8673.9 5172.5 5316.6 5316.9 5336.8 5337.5 3282.9 3356.3 3402.4 3422.1 3447.5 1171.6 1174.6 1195.1 1205.1 1205.1 12237.8 12416.8 12450.3 12463.9 12504.3 4450.2 4482.4 4606.3 4616.2 4673.2 10094.5 10094.6 10118.3 10120.7 10123.3 5187.2 5232.5 5258.3 5266.4 5272.0 4720.2 4743.9 4823.4 4827.8 4832.4 3801.2 3834.8 3860.3 3912.9 3928.5 1114.8 1211.5 1219.5 1251.9 1263.1 938.9 968.8 983.5 985.7 995.4 5722.6 5727.4 5753.4 5757.5 5768.3 935.7 951.1 969.5 971.9 1004.4 4153.8 4183.2 4194.3 4203.9 4214.2 10679.5 11176.6 11195.7 11199.8 11250.7 9259.4 11065.5 11093.7 11115.8 11127.6 7620.3 7830.3 7925.0 8033.3 8040.7 5961.2 6168.6 6202.7 6217.9 6342.5 9486.9 9740.5 9842.0 9849.5 10027.8 9992.5 10079.6 10121.2 10121.4 10126.4 7658.3 7673.3 7701.2 7759.6 7761.8 7935.5 8076.5 8114.1 8121.3 8138.2 7058.0 7213.1 7306.9 7359.1 7486.1 7412.5 7427.7 7462.9 7468.3 7545.1 6744.6 6808.0 6818.5 6828.4 6835.0 4981.3 5187.3 5194.2 5250.2 5280.6 4976.1 5003.3 5095.9 5255.1 5298.3 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | [#4] prototype.html Cycles:180 Average:4625.15 Median:5316.71 stddev:1355.45 (25.5%) stddev-sans-first:1320.25 22:24:33 INFO - PROCESS | 761 | Values: 324.1 326.8 333.3 335.3 336.6 5255.6 5320.6 5321.3 5346.9 5350.5 5214.6 5253.4 5303.3 5312.8 5324.4 3913.6 4011.7 4106.2 4111.4 4170.8 4079.8 4120.9 4129.9 4170.8 4171.4 3910.3 4085.2 4108.2 4108.6 4128.8 3995.8 4129.3 4140.3 4147.2 4153.2 3666.0 3672.7 3674.2 3679.9 3692.7 5091.6 5113.0 5150.3 5152.8 5163.9 4359.8 4621.4 4649.1 4653.3 4663.5 5440.4 5467.7 5499.1 5507.3 5521.4 3852.2 3854.8 3862.5 3863.5 3874.2 5567.8 5670.9 5679.4 5702.7 5704.6 3996.8 4012.4 4014.9 4029.1 4030.0 4060.7 4075.3 4082.9 4100.9 4104.6 4594.6 4653.3 4657.0 4680.9 4709.2 5402.5 5513.7 5518.9 5519.9 5559.7 1403.1 1418.7 1418.8 1419.5 1422.7 896.0 909.9 923.3 925.2 937.9 5205.9 5209.4 5228.6 5245.5 5265.6 5564.8 5586.8 5594.6 5605.4 5624.5 5142.6 5165.4 5259.2 5273.9 5292.4 2977.1 2983.1 3001.5 3008.9 3020.0 5568.6 5762.9 5776.0 5776.9 5808.2 5602.9 5630.2 5635.7 5670.9 5672.5 5693.8 5734.5 5748.3 5772.2 5782.3 5362.8 5596.8 5600.8 5616.2 5628.5 5529.5 5530.5 5542.7 5572.3 5573.5 5729.4 5755.9 5769.5 5769.7 5815.5 3786.3 4036.1 4041.1 4046.6 4051.6 5671.9 5672.2 5685.4 5694.6 5706.1 5373.0 5550.0 5565.9 5569.4 5573.9 5407.6 5413.6 5454.1 5473.7 5502.5 5283.3 5364.4 5408.7 5444.6 5451.3 5538.5 5554.5 5589.7 5601.4 5613.1 5482.5 5495.7 5497.9 5501.9 5520.8 22:24:33 INFO - PROCESS | 761 | 22:24:33 INFO - PROCESS | 761 | [#5] yui.html Cycles:180 Average:21428.74 Median:4549.98 stddev:31581.89 (694.1%) stddev-sans-first:31632.21 22:24:33 INFO - PROCESS | 761 | Values: 719.7 781.9 820.9 827.5 850.2 14344.1 14859.2 14911.2 14929.1 14993.6 14407.9 14815.3 15248.8 15284.2 15304.2 651.5 781.1 794.3 805.9 811.1 795.4 807.3 807.9 808.0 808.5 848.4 848.8 850.3 850.3 850.9 676.4 794.9 818.8 841.5 842.0 599.3 599.7 600.2 600.3 600.4 15264.8 15306.5 15318.1 15336.2 15336.9 14535.8 14765.8 14915.0 15042.4 15044.6 13400.7 13419.3 13543.9 13555.0 13735.6 4465.1 4479.8 4516.8 4523.7 4576.2 73720.3 73863.3 74165.6 74254.6 74288.3 11575.9 11612.1 11614.4 11774.4 12003.6 18753.9 19641.6 19664.2 19679.3 19733.3 8341.1 8446.3 8629.8 8735.8 8972.6 4864.1 4912.6 4921.9 5089.1 5220.1 3383.5 3458.5 3485.4 3487.7 3493.9 509.5 630.4 658.6 670.1 701.8 630.4 684.9 706.9 725.8 786.2 9599.0 9966.9 10034.2 10158.5 10349.9 629.9 688.5 697.9 768.2 780.5 4910.7 4914.6 4958.6 4966.2 4973.6 73447.6 104325.5 126143.4 127818.0 134604.0 108994.0 111508.6 112713.0 113415.4 116347.0 86383.0 86558.0 86749.6 87045.6 87801.6 8392.4 8800.6 8883.0 9175.0 9413.4 57541.7 57601.0 58008.7 58387.4 59829.0 77160.6 78694.0 79020.6 87172.0 88076.6 12426.4 13103.3 13117.5 13388.9 13450.8 12884.4 13142.7 13224.4 13916.3 14069.8 13101.1 13120.2 13477.0 13519.9 13547.7 12824.2 13061.5 13285.0 13641.7 14159.9 11481.3 11604.9 11919.3 12307.9 12383.4 10495.0 11132.2 11149.3 11313.3 11644.2 10581.7 10948.6 11017.8 11076.2 11483.3 22:24:33 INFO - PROCESS | 761 | -------- Summary: end -------- 22:24:33 INFO - PROCESS | 761 | 22:24:34 INFO - TEST-INFO | 761: exit 0 22:24:34 INFO - TEST-OK | dromaeo_css | took 1113890ms 22:24:34 INFO - TEST-START | kraken 22:24:34 INFO - operating with platform_type : linux_ 22:24:34 INFO - Initialising browser for kraken test... 22:24:34 INFO - TEST-INFO | started process 963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptkLJNr/profile http://localhost:37053/getInfo.html) 22:24:38 INFO - PROCESS | 963 | __metrics Screen width/height:1600/1200 22:24:38 INFO - PROCESS | 963 | colorDepth:24 22:24:38 INFO - PROCESS | 963 | Browser inner width/height: 1024/697 22:24:38 INFO - PROCESS | 963 | __metrics 22:24:39 INFO - PROCESS | 963 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:24:40 INFO - TEST-INFO | 963: exit 0 22:24:40 INFO - Browser initialized. 22:24:40 INFO - Running cycle 1/1 for kraken test... 22:24:40 INFO - TEST-INFO | started process 1057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptkLJNr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:52 INFO - PROCESS | 1057 | RSS: Main: 157044736 22:24:52 INFO - PROCESS | 1057 | 22:24:55 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37053/tests/kraken/driver.html?testName=audio-beat-detection) 22:24:56 INFO - PROCESS | 1057 | RSS: Main: 215609344 22:24:56 INFO - PROCESS | 1057 | 22:24:58 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37053/tests/kraken/driver.html?testName=audio-dft) 22:24:59 INFO - PROCESS | 1057 | RSS: Main: 203669504 22:24:59 INFO - PROCESS | 1057 | 22:25:02 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37053/tests/kraken/driver.html?testName=audio-fft) 22:25:03 INFO - PROCESS | 1057 | RSS: Main: 268996608 22:25:03 INFO - PROCESS | 1057 | 22:25:05 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37053/tests/kraken/driver.html?testName=audio-oscillator) 22:25:05 INFO - PROCESS | 1057 | RSS: Main: 180785152 22:25:05 INFO - PROCESS | 1057 | 22:25:07 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37053/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:25:08 INFO - PROCESS | 1057 | RSS: Main: 184750080 22:25:08 INFO - PROCESS | 1057 | 22:25:11 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37053/tests/kraken/driver.html?testName=imaging-darkroom) 22:25:12 INFO - PROCESS | 1057 | RSS: Main: 218615808 22:25:12 INFO - PROCESS | 1057 | 22:25:15 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37053/tests/kraken/driver.html?testName=imaging-desaturate) 22:25:16 INFO - PROCESS | 1057 | RSS: Main: 221536256 22:25:16 INFO - PROCESS | 1057 | 22:25:18 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37053/tests/kraken/driver.html?testName=json-parse-financial) 22:25:19 INFO - PROCESS | 1057 | RSS: Main: 221130752 22:25:19 INFO - PROCESS | 1057 | 22:25:20 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37053/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:25:21 INFO - PROCESS | 1057 | RSS: Main: 206864384 22:25:21 INFO - PROCESS | 1057 | 22:25:23 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:25:23 INFO - PROCESS | 1057 | RSS: Main: 212910080 22:25:23 INFO - PROCESS | 1057 | 22:25:25 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:25:26 INFO - PROCESS | 1057 | RSS: Main: 210481152 22:25:26 INFO - PROCESS | 1057 | 22:25:29 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:25:29 INFO - PROCESS | 1057 | RSS: Main: 227078144 22:25:29 INFO - PROCESS | 1057 | 22:25:32 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:25:32 INFO - PROCESS | 1057 | RSS: Main: 225992704 22:25:32 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | Cycle 1(1): loaded http://localhost:37053/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37053/tests/kraken/driver.html?testName=ai-astar) 22:25:34 INFO - PROCESS | 1057 | RSS: Main: 269463552 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | __start_tp_report 22:25:34 INFO - PROCESS | 1057 | _x_x_mozilla_page_load 22:25:34 INFO - PROCESS | 1057 | _x_x_mozilla_page_load_details 22:25:34 INFO - PROCESS | 1057 | |i|pagename|runs| 22:25:34 INFO - PROCESS | 1057 | |0;ai-astar;101;110;108;109;112;109;108;109;110;110 22:25:34 INFO - PROCESS | 1057 | |1;audio-beat-detection;148;122;128;174;120;119;141;123;167;118 22:25:34 INFO - PROCESS | 1057 | |2;audio-dft;159;163;161;162;160;161;161;161;160;162 22:25:34 INFO - PROCESS | 1057 | |3;audio-fft;74;75;76;129;75;75;74;76;117;76 22:25:34 INFO - PROCESS | 1057 | |4;audio-oscillator;83;83;120;83;83;83;113;84;83;126 22:25:34 INFO - PROCESS | 1057 | |5;imaging-gaussian-blur;118;117;116;117;117;117;116;116;118;117 22:25:34 INFO - PROCESS | 1057 | |6;imaging-darkroom;146;145;145;145;146;147;146;145;147;146 22:25:34 INFO - PROCESS | 1057 | |7;imaging-desaturate;95;95;96;96;96;96;95;95;95;95 22:25:34 INFO - PROCESS | 1057 | |8;json-parse-financial;50;51;51;52;53;51;52;51;52;51 22:25:34 INFO - PROCESS | 1057 | |9;json-stringify-tinderbox;61;62;62;62;61;61;61;61;61;61 22:25:34 INFO - PROCESS | 1057 | |10;stanford-crypto-aes;76;83;75;76;79;74;78;73;73;76 22:25:34 INFO - PROCESS | 1057 | |11;stanford-crypto-ccm;118;115;116;117;133;131;118;135;216;131 22:25:34 INFO - PROCESS | 1057 | |12;stanford-crypto-pbkdf2;155;157;158;158;153;155;153;157;157;156 22:25:34 INFO - PROCESS | 1057 | |13;stanford-crypto-sha256-iterative;64;62;62;61;62;66;62;61;60;65 22:25:34 INFO - PROCESS | 1057 | __end_tp_report 22:25:34 INFO - PROCESS | 1057 | __start_cc_report 22:25:34 INFO - PROCESS | 1057 | _x_x_mozilla_cycle_collect,1635 22:25:34 INFO - PROCESS | 1057 | __end_cc_report 22:25:34 INFO - PROCESS | 1057 | __startTimestamp1467955534902__endTimestamp 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | ------- Summary: start ------- 22:25:34 INFO - PROCESS | 1057 | Number of tests: 14 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.50 stddev:2.91 (2.7%) stddev-sans-first:1.24 22:25:34 INFO - PROCESS | 1057 | Values: 101.0 110.0 108.0 109.0 112.0 109.0 108.0 109.0 110.0 110.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:134.50 stddev:20.74 (15.4%) stddev-sans-first:21.54 22:25:34 INFO - PROCESS | 1057 | Values: 148.0 122.0 128.0 174.0 120.0 119.0 141.0 123.0 167.0 118.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:0.97 22:25:34 INFO - PROCESS | 1057 | Values: 159.0 163.0 161.0 162.0 160.0 161.0 161.0 161.0 160.0 162.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#3] audio-fft Cycles:10 Average:84.70 Median:75.00 stddev:20.40 (27.2%) stddev-sans-first:21.26 22:25:34 INFO - PROCESS | 1057 | Values: 74.0 75.0 76.0 129.0 75.0 75.0 74.0 76.0 117.0 76.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.00 stddev:17.91 (21.6%) stddev-sans-first:18.54 22:25:34 INFO - PROCESS | 1057 | Values: 83.0 83.0 120.0 83.0 83.0 83.0 113.0 84.0 83.0 126.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 22:25:34 INFO - PROCESS | 1057 | Values: 118.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 118.0 117.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 22:25:34 INFO - PROCESS | 1057 | Values: 146.0 145.0 145.0 145.0 146.0 147.0 146.0 145.0 147.0 146.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:25:34 INFO - PROCESS | 1057 | Values: 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:0.84 (1.6%) stddev-sans-first:0.73 22:25:34 INFO - PROCESS | 1057 | Values: 50.0 51.0 51.0 52.0 53.0 51.0 52.0 51.0 52.0 51.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 22:25:34 INFO - PROCESS | 1057 | Values: 61.0 62.0 62.0 62.0 61.0 61.0 61.0 61.0 61.0 61.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.00 stddev:3.06 (4.0%) stddev-sans-first:3.24 22:25:34 INFO - PROCESS | 1057 | Values: 76.0 83.0 75.0 76.0 79.0 74.0 78.0 73.0 73.0 76.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:131.00 stddev:30.22 (23.1%) stddev-sans-first:31.56 22:25:34 INFO - PROCESS | 1057 | Values: 118.0 115.0 116.0 117.0 133.0 131.0 118.0 135.0 216.0 131.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.90 Median:157.00 stddev:1.85 (1.2%) stddev-sans-first:1.94 22:25:34 INFO - PROCESS | 1057 | Values: 155.0 157.0 158.0 158.0 153.0 155.0 153.0 157.0 157.0 156.0 22:25:34 INFO - PROCESS | 1057 | 22:25:34 INFO - PROCESS | 1057 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:1.90 (3.1%) stddev-sans-first:1.94 22:25:34 INFO - PROCESS | 1057 | Values: 64.0 62.0 62.0 61.0 62.0 66.0 62.0 61.0 60.0 65.0 22:25:34 INFO - PROCESS | 1057 | -------- Summary: end -------- 22:25:34 INFO - PROCESS | 1057 | 22:25:35 INFO - TEST-INFO | 1057: exit 0 22:25:35 INFO - TEST-OK | kraken | took 61274ms 22:25:35 INFO - SUITE-END | took 1175s 22:25:36 INFO - Completed test suite (00:19:35) 22:25:36 INFO - javascript benchmark 22:25:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.2455848317163, 3086.7376273016794, 3116.8285744284053, 3118.968810311896, 3136.937261254775, 5243.79024839007, 5331.760070796814, 5399.48704873037, 5457.590680698949, 5505.0366185917455, 5457.181422786574, 5458.836234912949, 5461.836144915659, 5516.420775818541, 5537.695426751527, 4892.608591312695, 4908.9263661045115, 4962.305277261186, 4972.428170760372, 5088.872778180538, 4995.0, 5013.924791128136, 5041.117804384218, 5069.923951140736, 5074.391073071218, 4747.216709242971, 4783.019481006403, 4868.269759536062, 4937.23472861707, 4939.431965323998, 4890.24201248807, 4892.559669629715, 4896.4858689837765, 4899.608031357483, 4999.525045120708, 4907.1657818171, 4934.481879402683, 4935.1363511385725, 4939.456659767423, 4957.380327459067, 5293.841184764463, 5371.060064488738, 5402.081646120129, 5452.454754524555, 5454.563634909197, 5826.82519524415, 5829.358770535237, 5897.764089436418, 5898.262717160355, 5903.822885313448, 5875.529957603381, 5910.438508341701, 5939.020061689816, 5939.049752039696, 5939.67331796751, 5207.661502002357, 5344.118220493614, 5376.650517716336, 5408.756605952742, 5428.131498960148, 5914.378990206053, 5928.555358348141, 5974.492168165754, 5976.103584462278, 5985.132155837467, 5454.427285135083, 5736.4550367715, 5740.56945729072, 5740.827775166752, 5742.425757424307, 5633.4084921082695, 5760.337561180518, 5760.884782304331, 5767.798127065533, 5768.105943578752, 5697.059985102412, 5697.173909783141, 5699.2590963174525, 5701.344345400249, 5749.568782341257, 5549.361823390281, 5644.9435505644415, 5698.715064246854, 5704.372519022904, 5720.77116915327, 4422.668299877458, 4432.180046691373, 4436.977815110904, 4475.037866858641, 4475.1049790042125, 3935.0162459385156, 3936.2127574484643, 3943.743656662308, 3955.604439556079, 3981.3231750602467, 5200.60995425337, 5289.841304760864, 5422.6475279106735, 5483.890322193613, 5552.666839989613, 5817.185594016841, 5847.181394604759, 5863.3257175424515, 5873.882522349614, 5917.556183286185, 5464.508194262536, 5478.479544443271, 5478.643888147338, 5481.890362192734, 5482.451754824486, 4859.1010663027455, 4938.4320803107385, 4968.6025117990475, 5016.598672106223, 5078.492150784892, 6012.39876012404, 6018.6990650467305, 6026.246719160105, 6031.457168854824, 6065.75736970525, 5819.79630712923, 5832.1543376209365, 5832.562557808096, 5834.27071616048, 5852.063669812809, 5790.305163380422, 5985.431384018511, 6013.488853447506, 6015.187949626745, 6023.216981792514, 5755.798547050833, 5761.193432919311, 5778.971105144447, 5779.7110144493445, 5784.132380143013, 5796.159556864147, 5866.266716660418, 5871.2073870027, 5874.794382196603, 5881.2942446906645, 5431.646942948696, 5613.46672066153, 5701.857453563694, 5711.400302968129, 5748.425157484218, 5689.402612725769, 5697.4587414195585, 5704.458076482728, 5709.543236540983, 5714.257146570919, 5354.437784032621, 5611.3266408031295, 5628.521575666115, 5637.0, 5641.605087643826, 5557.777688892399, 5576.972115139399, 5607.299087614048, 5647.830565083054, 5706.144078388109, 5550.27846379968, 5554.222408862844, 5555.749991250323, 5557.8610534735335, 5562.3047119110115], "value": 5340.792423135763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.8808782287455, 627.5952010952933, 627.9780207692731, 631.3339426904615, 641.2881701311546, 31544.84227578862, 34024.31951360974, 44006.779966100126, 44901.550984490146, 45010.32484512727, 45014.32478512817, 45316.54683453165, 45380.0, 45417.31874021892, 45432.545674543246, 9007.279417646589, 9130.04134565871, 9143.725688229364, 9150.313726470507, 9151.633934642607, 8892.421992570462, 8983.281337492985, 8984.371094023421, 8991.0, 8997.280217582611, 9009.819803603925, 9039.45763254207, 9054.0, 9056.909430905705, 9065.18413342799, 8993.055729148444, 9022.593983270703, 9023.503707296097, 9033.367664263504, 9037.728868133967, 8867.379283450162, 8922.107789221025, 8927.732168034969, 8930.776730581723, 8934.195922366953, 42610.57389426128, 42906.0, 43099.0, 43153.78423107896, 43443.565564344586, 3440.3119376124873, 3653.2876089162623, 3662.6520480554304, 4019.356902895552, 4202.865226388858, 32834.0, 33052.190563801865, 33953.49069763956, 34322.313553729036, 34866.30267394638, 4528.0, 4543.363929049936, 4570.65720070996, 4607.493175750665, 4784.6889952153, 366843.0, 366862.0, 369362.0, 370595.0, 371962.0, 59632.40367596355, 60047.39952600418, 60173.0, 60315.69842150761, 60363.39636603578, 56115.0, 61788.0, 63299.36700632935, 63688.0, 63773.36226637767, 16888.83111168897, 17121.828781712273, 17244.741328880078, 17304.0, 17306.048167350917, 13127.934360328138, 13211.867881321255, 13366.93316533411, 13380.598582042554, 13399.062065655311, 15938.60153496172, 16281.0, 17665.646687066186, 18163.273469061354, 18230.817691823177, 5713.342965558931, 5905.320888097838, 5980.8803823924145, 6006.699665016732, 6013.579049466583, 940.7601061729215, 971.8590804333332, 1013.8428543575758, 1063.4363787192801, 1109.877913429522, 32365.51451728226, 33086.3382732344, 33176.17059573482, 33233.3353332932, 33294.667053329154, 1100.0814320042693, 1117.474786850179, 1135.6365962891796, 1153.3541216918552, 1209.5947857467847, 17247.223874925658, 17611.735823962652, 17613.207405666777, 17656.823431765773, 17705.911470442566, 153839.23080384752, 154008.0, 155754.22122889312, 155898.0, 155902.0, 59448.10827837586, 59716.70141649264, 59908.70045649744, 59914.0, 60049.0, 1324.0, 1346.602752188107, 1346.8249127613346, 1352.7159296547836, 1360.4626172661742, 30743.846280768452, 32013.199670008435, 32701.34597307993, 32716.182095446853, 32791.50812737763, 51740.22389664233, 51772.0, 52082.47917520928, 52152.0, 52220.47779522308, 508200.0, 511084.0, 511881.0, 513064.0, 514835.0, 24817.875910620332, 27517.0, 28276.575851362657, 43315.13369732525, 43825.34261986137, 37020.25959480849, 37968.0, 40058.799706001286, 48425.75787121183, 48763.756181218865, 41728.58271417247, 42064.36903446512, 42450.0, 42848.57151428446, 43404.13191736084, 41351.0, 41363.1727365445, 41581.1683766329, 42274.36588451237, 42340.78829605832, 41165.79417103015, 41221.0, 42567.148657027305, 42610.36084458797, 42733.35899961565, 36191.63808361988, 36587.0, 36701.0, 37275.23348989972, 37314.253714925006, 27091.59362609602, 28001.579976300778, 28217.71782282151, 28432.57351139776, 28938.855305723337], "value": 19753.675673310318, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.6336480343674, 394.37688452245453, 397.1362287025718, 397.1560434077585, 410.57915636472615, 18166.18252178652, 18906.810931890675, 19783.0, 20210.292639757612, 20224.89887550562, 18444.44666659999, 18929.148188331525, 19560.31538896139, 19594.118264678087, 19658.115384807683, 5162.303089082972, 5266.631335806495, 5353.063213937565, 5599.160125981095, 5799.21710569072, 5650.858728531778, 5731.455511726379, 5739.454751798572, 5764.53883689306, 5768.278965129359, 5514.228008078852, 5619.466150715675, 5696.51579615731, 5784.82645520635, 5826.82519524415, 5884.735186916599, 5905.084711869667, 5908.61593996391, 5917.674527900964, 5920.31916329619, 5493.917591236134, 5605.523530499871, 5617.747201375948, 5627.521660658849, 5630.493255606974, 14212.715745685131, 14527.418903243857, 14529.200893950829, 19896.005199739957, 22804.429889252737, 2340.239422187779, 2376.7504412036833, 2404.266698656909, 2587.4825034992887, 2717.483678101174, 10475.0, 11353.829692554618, 12903.741925161537, 14716.117032978056, 15047.172405517693, 1945.6886898096236, 1992.1234656751128, 2006.5284658105334, 2027.4019164346405, 2057.3930690446355, 60023.0, 74986.25013749902, 77029.22970770333, 80497.59751201207, 84651.0, 10244.692659220087, 11034.779304413867, 11174.0, 11219.607313744144, 11287.661370158909, 14318.642033949234, 14696.26518674062, 14810.185439800915, 14818.777718334233, 15096.849031509764, 8350.70772522971, 8454.957725211458, 8485.703000394957, 8525.360597955178, 8641.783955401166, 8089.433739638286, 8110.797230069177, 8154.510729356258, 8195.795105122419, 8278.089410164877, 4712.175369310357, 4767.141914455362, 4785.641076919245, 4889.731064791471, 4913.287573301851, 1938.932137375185, 1948.785633580305, 1957.363856746563, 1968.5430792785849, 2009.829164521003, 908.5956749246654, 937.3672770879629, 988.1995583577325, 1002.9260317127979, 1016.3139880580578, 19163.425097247105, 20254.493637659176, 20338.694919576217, 20391.18435262573, 20455.4886127848, 1006.6174853555602, 1013.4628646817199, 1021.070825548747, 1023.155896385485, 1077.6389909380446, 10642.0, 11191.944040279746, 11205.43972801373, 11219.495122719498, 11224.32654040744, 385031.0, 388569.0, 389071.0, 532223.0, 538310.0, 100765.49617251867, 103776.48111759544, 107261.0, 107854.0, 108939.45530272298, 1025.8050970315617, 1037.6264544763878, 1063.2769716592636, 1065.3820783945293, 1068.6633710381204, 17979.19093640763, 18181.18184681666, 18374.26502939921, 18647.72028419602, 18837.0, 28303.432293123788, 38860.41709374418, 40605.39091913683, 44190.779046104566, 46943.06113877635, 56404.89773062521, 59658.70170649119, 60482.39517604768, 61742.0, 63216.36783632105, 7807.570583617956, 7998.600069996361, 8116.472429292077, 8747.912520874965, 9077.09229077087, 10378.896211038094, 14120.082194657314, 14216.502422415166, 14335.211563364115, 14469.871387742241, 12168.330741808932, 12515.186512876633, 12631.557895473614, 12676.492940282285, 12924.0, 11459.19785614999, 11590.362530060927, 11727.0, 11800.114991375509, 11815.0, 12330.445129968994, 12353.938230308791, 12725.0, 12725.109242353317, 12828.294443805307, 7406.222346653523, 7638.312551870358, 7736.535807851547, 8121.8375632488205, 8501.234888860032, 10638.14894808398, 11082.833757493805, 11088.611898583511, 11234.775304494027, 11364.772704545698], "value": 9284.714412078478, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.746819306621, 1476.586555764386, 1582.778411022457, 1693.7798086248786, 1751.4482937874568, 8504.064552899175, 8980.550972451383, 9048.547572621368, 9054.45673259604, 9058.95470522648, 7393.408527317815, 8176.91823081769, 8648.437851539646, 8678.47929124253, 8860.601272942733, 2293.6674182243605, 2307.065638416444, 2317.084751523147, 2323.756005619411, 2324.9418764530938, 1937.331620590901, 2292.1175347491253, 2313.3869524576003, 2314.9421264468356, 2319.3273950554317, 2249.280230326296, 2304.193532263711, 2313.2366319114735, 2321.7097862767155, 2347.730011048734, 2329.4758679297106, 2337.789598936104, 2345.5074434368803, 2350.4828937633692, 2360.6577046328357, 2265.2184996176456, 2268.739095004079, 2283.977160228393, 2287.462446325112, 2330.4406942333717, 8617.65529378824, 8654.826903461895, 8657.437266577652, 8660.740177794676, 8673.869891951626, 5172.482751724835, 5316.574674026068, 5316.9202461963105, 5336.786528538853, 5337.466253374631, 3282.907662082515, 3356.278400143981, 3402.3535528249554, 3422.144463884029, 3447.517347571342, 1171.619223752284, 1174.6476057182897, 1195.1335281920624, 1205.0540325844258, 1205.1142410328403, 12237.816432753516, 12416.81374779379, 12450.3152326622, 12463.937680311632, 12504.31226282554, 4450.2434586120435, 4482.39487669167, 4606.332081848113, 4616.192166370872, 4673.158831410379, 10094.545745441474, 10094.646687366054, 10118.342307749972, 10120.746981325527, 10123.291369604205, 5187.170052791535, 5232.476752324813, 5258.316418865599, 5266.3943646480375, 5271.973640131775, 4720.150372932905, 4743.881402964885, 4823.421189457287, 4827.806887724522, 4832.395950506187, 3801.163743976321, 3834.8082595870096, 3860.3244432224246, 3912.8630497932436, 3928.508936382952, 1114.7603265297837, 1211.4608998995363, 1219.536576101076, 1251.9374031298398, 1263.0779530942464, 938.8920274168421, 968.801395713877, 983.4640121133999, 985.7141428985657, 995.3878364805593, 5722.62802917809, 5727.398623144593, 5753.395893431213, 5757.481826635623, 5768.278965129359, 935.6771913689767, 951.058452132384, 969.5346233807812, 971.8882328532168, 1004.437514991607, 4153.833846646161, 4183.184279065553, 4194.328907374806, 4203.906984184073, 4214.241436541452, 10679.51942162589, 11176.608818691308, 11195.72010699739, 11199.832002519968, 11250.66248012561, 9259.35184537076, 11065.50205240766, 11093.725786213969, 11115.833262500908, 11127.554897804159, 7620.314171724572, 7830.334421574116, 7925.0, 8033.317168040782, 8040.678372865137, 5961.195238642901, 6168.568200225941, 6202.689865506797, 6217.937820621736, 6342.49260059185, 9486.905130948602, 9740.464274464972, 9841.950790246003, 9849.458279794393, 10027.849582256418, 9992.500374981368, 10079.596816127567, 10121.240906932157, 10121.392716437038, 10126.44304563256, 7658.310752032344, 7673.271039251262, 7701.191374905554, 7759.6120193988945, 7761.7671469855995, 7935.484193527403, 8076.515409075474, 8114.06688230861, 8121.309688676529, 8138.226868447488, 7058.047163632844, 7213.098362704662, 7306.890396644161, 7359.1169059713175, 7486.139094004041, 7412.481126321106, 7427.740029098955, 7462.888056679263, 7468.325408455318, 7545.132309784445, 6744.595324280559, 6808.012838138541, 6818.522703410714, 6828.351306626061, 6834.965825171042, 4981.252812078217, 5187.273781670494, 5194.22086687, 5250.212468129811, 5280.630355875205, 4976.054549635558, 5003.274525193754, 5095.92356114666, 5255.080360936897, 5298.337707786527], "value": 4605.252139313166, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.07315078874416, 326.7630967548527, 333.2501870790721, 335.2557322743509, 336.6213010363341, 5255.579553635709, 5320.600954928386, 5321.308229930113, 5346.91979620306, 5350.5184533391985, 5214.582833373331, 5253.4221235664045, 5303.257543943851, 5312.814051508198, 5324.361076670795, 3913.5890731473214, 4011.7191796574252, 4106.1992911382295, 4111.36273877548, 4170.77060761658, 4079.77561234132, 4120.855770048034, 4129.896752581194, 4170.812313445902, 4171.436856024443, 3910.2570511602703, 4085.1829634073156, 4108.239975604508, 4108.609682080198, 4128.772917489537, 3995.8201880915285, 4129.318662420697, 4140.296149654566, 4147.212029714345, 4153.190127925056, 3665.981670091659, 3672.7245456590595, 3674.2467794102413, 3679.889603311905, 3692.6861216796606, 5091.61812864033, 5113.0, 5150.3047088642925, 5152.819651312186, 5163.922541161885, 4359.80380882861, 4621.4223222097235, 4649.070185962787, 4653.278741795028, 4663.463701674317, 5440.4287549807495, 5467.6719396836315, 5499.120140777456, 5507.339119305709, 5521.420250873641, 3852.2295540891932, 3854.807259637007, 3862.478565393692, 3863.497745293122, 3874.2445223181207, 5567.805126820542, 5670.858228544278, 5679.432056794329, 5702.6863522506665, 5704.600677952545, 3996.800159991989, 4012.35802271641, 4014.8594799181888, 4029.1135950090934, 4030.0, 4060.659982205865, 4075.349483459204, 4082.9183416331507, 4100.91798164041, 4104.56902025283, 4594.563516465931, 4653.325267836212, 4657.0, 4680.859574212779, 4709.246520556694, 5402.51377376038, 5513.696746678891, 5518.889622207534, 5519.8896022080135, 5559.6664200148125, 1403.1440821099122, 1418.680796820707, 1418.7659036258901, 1419.5386499387741, 1422.7154569086017, 895.9955200223957, 909.9226565741853, 923.2706162131975, 925.1765928323797, 937.8921424036187, 5205.869853253699, 5209.426963034063, 5228.607854410859, 5245.475452454724, 5265.605079619044, 5564.805231816867, 5586.776528938878, 5594.601349662585, 5605.411431799684, 5624.525208908068, 5142.6400151989, 5165.431802501722, 5259.211118332204, 5273.945210957824, 5292.417834038252, 2977.0622253990355, 2983.13489088162, 3001.5197568388953, 3008.9318292006024, 3020.0, 5568.554515638739, 5762.942370576325, 5775.971120144372, 5776.913346299809, 5808.215890854765, 5602.85992850182, 5630.183623374588, 5635.69003704792, 5670.914936275959, 5672.461116193955, 5693.829185124453, 5734.505490111815, 5748.281464816898, 5772.249607550992, 5782.335031471434, 5362.785488580569, 5596.776128954796, 5600.775968961196, 5616.241807355955, 5628.465295796893, 5529.474699903502, 5530.529904957963, 5542.667439953616, 5572.331320241435, 5573.470520300565, 5729.369769325288, 5755.913661295168, 5769.538436924952, 5769.653820770767, 5815.534757219497, 3786.280606684831, 4036.1120553478186, 4041.1109555897656, 4046.635802777764, 4051.554329023746, 5671.858203544944, 5672.177534257592, 5685.431456854281, 5694.601377903507, 5706.115552089433, 5372.973135134299, 5550.0, 5565.888682226414, 5569.3823902765635, 5573.885222955506, 5407.648502847303, 5413.566914646897, 5454.100073487989, 5473.698946557977, 5502.477264659851, 5283.339582552181, 5364.38309594386, 5408.702521361363, 5444.646098003617, 5451.318585176853, 5538.47384498472, 5554.472325129106, 5589.720513974366, 5601.383847776823, 5613.073842816053, 5482.451754824486, 5495.670259784426, 5497.917531236954, 5501.944980550144, 5520.751566179451], "value": 4192.533084046879, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.7229066809278, 781.8631739445599, 820.9425340226187, 827.5159031966299, 850.2390360627243, 14344.067635603678, 14859.182744949023, 14911.179885106314, 14929.104253744783, 14993.625159371035, 14407.927960360183, 14815.33331000105, 15248.847511524882, 15284.235788210573, 15304.23478826057, 651.501601275025, 781.1173374087274, 794.3129193247828, 805.9274665280124, 811.1482942909951, 795.387551585279, 807.2653884964683, 807.8868958345836, 807.9515229086273, 808.5391326943644, 848.3510114762212, 848.7708318753932, 850.2815121222588, 850.2900078434515, 850.9149085091503, 676.4216594811439, 794.8847417124542, 818.758466252451, 841.486693117198, 841.9789505262356, 599.3048064245498, 599.7241269016258, 600.2107228993902, 600.342624825815, 600.4475882188398, 15264.771028434583, 15306.540803775904, 15318.080915145127, 15336.15651139187, 15336.923315383463, 14535.78196327056, 14765.77851332231, 14915.0, 15042.398304067825, 15044.623884402868, 13400.718127475067, 13419.329033548382, 13543.932280338535, 13555.0, 13735.587932362045, 4465.084657645175, 4479.776011199459, 4516.81932722694, 4523.705959112647, 4576.244919588297, 73720.26279737188, 73863.26136738618, 74165.6291718538, 74254.62872685603, 74288.34611041812, 11575.942120289345, 11612.12909031826, 11614.361210133355, 11774.411279435993, 12003.63989080329, 18753.90623046876, 19641.607167856564, 19664.213431462867, 19679.31122410709, 19733.309334173235, 8341.124181960928, 8446.324294056461, 8629.784255393666, 8735.781605459788, 8972.641094356153, 4864.075825593197, 4912.606991440676, 4921.926171107436, 5089.083964886282, 5220.086484865134, 3383.4755612880044, 3458.4812278157974, 3485.4423292273113, 3487.668671476206, 3493.8951831445097, 509.45233873586045, 630.3538872655565, 658.6333358281585, 670.122139996606, 701.8350687588413, 630.3853742600927, 684.8630273945131, 706.8727629026722, 725.7967769024682, 786.1745167574024, 9598.95200523993, 9966.900330996743, 10034.197264218845, 10158.492075396201, 10349.896501034893, 629.8771739510752, 688.4595592459895, 697.9371856532937, 768.1895600141904, 780.5121798875703, 4910.729909854992, 4914.648471670248, 4958.578520825698, 4966.155753521838, 4973.602111831045, 73447.63276183691, 104325.47837260917, 126143.36928315299, 127818.0, 134604.0, 108994.0, 111508.61896572463, 112713.0, 113415.43292283651, 116347.0, 86383.0, 86558.0, 86749.56625216834, 87045.56477217701, 87801.56099219591, 8392.440305706574, 8800.559972001502, 8882.978457477215, 9174.954125229331, 9413.388129771543, 57541.71229143828, 57601.0, 58008.70995645164, 58387.416125839896, 59829.0, 77160.61419692865, 78694.0, 79020.60489697514, 87172.0, 88076.5596172015, 12426.440810163384, 13103.279319637511, 13117.475300987853, 13388.866111339152, 13450.798238026242, 12884.355782210665, 13142.737145257233, 13224.404901779251, 13916.304184790517, 14069.788953165506, 13101.082924195214, 13120.212787232796, 13477.0, 13519.932400337935, 13547.729045418839, 12824.23054616726, 13061.542846000344, 13285.0, 13641.727165456437, 14159.867210622919, 11481.311121332747, 11604.88395116038, 11919.3444360561, 12307.876921230672, 12383.442745076673, 10495.0, 11132.220744547803, 11149.275297105662, 11313.264637798517, 11644.184907056424, 10581.735456613646, 10948.56205751761, 11017.83473247886, 11076.224664273423, 11483.253588517055], "value": 7614.208452383951, "unit": "score"}], "name": "dromaeo_css", "value": 7239.973672690155, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 108.0, 109.0, 112.0, 109.0, 108.0, 109.0, 110.0, 110.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 128.0, 174.0, 120.0, 119.0, 141.0, 123.0, 167.0, 118.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 163.0, 161.0, 162.0, 160.0, 161.0, 161.0, 161.0, 160.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 129.0, 75.0, 75.0, 74.0, 76.0, 117.0, 76.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 120.0, 83.0, 83.0, 83.0, 113.0, 84.0, 83.0, 126.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 118.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 146.0, 147.0, 146.0, 145.0, 147.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 52.0, 53.0, 51.0, 52.0, 51.0, 52.0, 51.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 75.0, 76.0, 79.0, 74.0, 78.0, 73.0, 73.0, 76.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 116.0, 117.0, 133.0, 131.0, 118.0, 135.0, 216.0, 131.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 158.0, 158.0, 153.0, 155.0, 153.0, 157.0, 157.0, 156.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 61.0, 62.0, 66.0, 62.0, 61.0, 60.0, 65.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1482.8999999999999, "alertThreshold": 2.0}]} 22:25:36 INFO - Return code: 0 22:25:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:25:36 INFO - # TBPL SUCCESS # 22:25:36 INFO - Running post-action listener: _resource_record_post_action 22:25:36 INFO - ##### 22:25:36 INFO - ##### Finished run-tests step (success) 22:25:36 INFO - ##### 22:25:36 INFO - Running post-run listener: _resource_record_post_run 22:25:36 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 551145472; Read time: 8; Write time: 764172 22:25:36 INFO - TinderboxPrint: CPU usage
12.9% 22:25:36 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:25:36 INFO - TinderboxPrint: I/O write bytes / time
551,145,472 / 764,172 22:25:36 INFO - TinderboxPrint: CPU idle
8,289.5 (87.1%) 22:25:36 INFO - TinderboxPrint: CPU user
1,188.4 (12.5%) 22:25:36 INFO - TinderboxPrint: Swap in / out
0 / 0 22:25:36 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256688128; Read time: 0; Write time: 472352 22:25:36 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 294457344; Read time: 0; Write time: 291820 22:25:36 INFO - Running post-run listener: _upload_blobber_files 22:25:36 INFO - Blob upload gear active. 22:25:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:25:36 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:25:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:25:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:25:37 INFO - (blobuploader) - INFO - Open directory for files ... 22:25:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:40 INFO - (blobuploader) - INFO - Done attempting. 22:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:41 INFO - (blobuploader) - INFO - Done attempting. 22:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:42 INFO - (blobuploader) - INFO - Done attempting. 22:25:42 INFO - (blobuploader) - INFO - Iteration through files over. 22:25:42 INFO - Return code: 0 22:25:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:25:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:25:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8853865bffe88c6990841a95ca875748e1be4be2547d671b81e257676744817312731f7041944ed0f7664e995f6747a35e2157f69bbf3f56d5841247807ed14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96db88c25fa0fb8c0676855dc6439b13bfca289ca28aceb4ae9a68ddab464350dd1989b4476f2e9fe4777f0cb5825c5661ea3ab6210d75c545516a30a361f269", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f761a66d07ae334ef070135fbc1334c123edc0e91daf2671ba06e0f83cf0fd818660d50847b2d1d4850b2f0e8265eb38973fb7108f9ee84a438c27827e6531b"} 22:25:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:25:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:25:42 INFO - Contents: 22:25:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8853865bffe88c6990841a95ca875748e1be4be2547d671b81e257676744817312731f7041944ed0f7664e995f6747a35e2157f69bbf3f56d5841247807ed14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96db88c25fa0fb8c0676855dc6439b13bfca289ca28aceb4ae9a68ddab464350dd1989b4476f2e9fe4777f0cb5825c5661ea3ab6210d75c545516a30a361f269", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f761a66d07ae334ef070135fbc1334c123edc0e91daf2671ba06e0f83cf0fd818660d50847b2d1d4850b2f0e8265eb38973fb7108f9ee84a438c27827e6531b"} 22:25:42 INFO - Running post-run listener: copy_logs_to_upload_dir 22:25:42 INFO - Copying logs to upload dir... 22:25:42 INFO - mkdir: /builds/slave/test/build/upload/logs 22:25:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.555630 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-07 22:25:42.086113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 22:25:42.089965) ========= 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=af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8853865bffe88c6990841a95ca875748e1be4be2547d671b81e257676744817312731f7041944ed0f7664e995f6747a35e2157f69bbf3f56d5841247807ed14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96db88c25fa0fb8c0676855dc6439b13bfca289ca28aceb4ae9a68ddab464350dd1989b4476f2e9fe4777f0cb5825c5661ea3ab6210d75c545516a30a361f269", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f761a66d07ae334ef070135fbc1334c123edc0e91daf2671ba06e0f83cf0fd818660d50847b2d1d4850b2f0e8265eb38973fb7108f9ee84a438c27827e6531b"} build_url:https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/MiFNhldDQDSuDHT88nkyiA/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/Firefox/sha512/8853865bffe88c6990841a95ca875748e1be4be2547d671b81e257676744817312731f7041944ed0f7664e995f6747a35e2157f69bbf3f56d5841247807ed14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96db88c25fa0fb8c0676855dc6439b13bfca289ca28aceb4ae9a68ddab464350dd1989b4476f2e9fe4777f0cb5825c5661ea3ab6210d75c545516a30a361f269", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f761a66d07ae334ef070135fbc1334c123edc0e91daf2671ba06e0f83cf0fd818660d50847b2d1d4850b2f0e8265eb38973fb7108f9ee84a438c27827e6531b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 22:25:42.115093) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 22:25:42.115439) ========= 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=af724c981e2a0f424dbabf99000001e7-1467953733.16950-1383251926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 22:25:42.199552) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 22:25:42.199912) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 22:25:42.200203) ========= ========= Total master_lag: 0.17 =========