builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1469085356.9 results: success (0) buildid: 20160720230019 builduid: 39a9f02d5c59469a8778405e42a10fb6 revision: d4ec1b2cf832673078935f9e57cff6061c1bec16 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:56.899982) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:56.900474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:56.900751) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:56.961020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:56.961287) ========= 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=920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 00:15:57-- 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% 43.2M=0s 2016-07-21 00:15:57 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.150401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:57.136435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:57.136719) ========= 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=920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019957 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:57.186310) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 00:15:57.186629) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d4ec1b2cf832673078935f9e57cff6061c1bec16 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d4ec1b2cf832673078935f9e57cff6061c1bec16 --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=920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 00:15:57,535 truncating revision to first 12 chars 2016-07-21 00:15:57,535 Setting DEBUG logging. 2016-07-21 00:15:57,535 attempt 1/10 2016-07-21 00:15:57,535 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d4ec1b2cf832?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 00:15:58,192 unpacking tar archive at: autoland-d4ec1b2cf832/testing/mozharness/ program finished with exit code 0 elapsedTime=1.052152 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 00:15:58.252723) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:58.253038) ========= script_repo_revision: d4ec1b2cf832673078935f9e57cff6061c1bec16 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:58.253456) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:58.253791) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 00:15:58.263580) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-21 00:15:58.264009) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:15:58 INFO - MultiFileLogger online at 20160721 00:15:58 in /builds/slave/test 00:15:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 00:15:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:15:58 INFO - {'append_to_log': False, 00:15:58 INFO - 'base_work_dir': '/builds/slave/test', 00:15:58 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 00:15:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:15:58 INFO - 'branch': 'Autoland-Non-PGO', 00:15:58 INFO - 'buildbot_json_path': 'buildprops.json', 00:15:58 INFO - 'config_files': ('talos/linux_config.py',), 00:15:58 INFO - 'default_actions': ('clobber', 00:15:58 INFO - 'read-buildbot-config', 00:15:58 INFO - 'download-and-extract', 00:15:58 INFO - 'populate-webroot', 00:15:58 INFO - 'create-virtualenv', 00:15:58 INFO - 'install', 00:15:58 INFO - 'run-tests'), 00:15:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:15:58 INFO - 'download_minidump_stackwalk': True, 00:15:58 INFO - 'download_symbols': 'ondemand', 00:15:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:15:58 INFO - 'tooltool.py': '/tools/tooltool.py', 00:15:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:15:58 INFO - '/tools/misc-python/virtualenv.py')}, 00:15:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:15:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:15:58 INFO - 'installer_path': 'installer.exe', 00:15:58 INFO - 'log_level': 'info', 00:15:58 INFO - 'log_name': 'talos', 00:15:58 INFO - 'log_to_console': True, 00:15:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:15:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:15:58 INFO - 'opt_config_files': (), 00:15:58 INFO - 'pip_index': False, 00:15:58 INFO - 'sps_profile': False, 00:15:58 INFO - 'sps_profile_interval': 0, 00:15:58 INFO - 'suite': 'dromaeojs', 00:15:58 INFO - 'system_bits': '32', 00:15:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:15:58 INFO - 'title': 'talos-linux64-ix-053', 00:15:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:15:58 INFO - 'use_talos_json': True, 00:15:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:15:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:15:58 INFO - 'work_dir': 'build'} 00:15:58 INFO - [mozharness: 2016-07-21 07:15:58.703882Z] Running clobber step. 00:15:58 INFO - Running pre-action listener: _resource_record_pre_action 00:15:58 INFO - Running main action method: clobber 00:15:58 INFO - rmtree: /builds/slave/test/build 00:15:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:15:59 INFO - Running post-action listener: _resource_record_post_action 00:15:59 INFO - [mozharness: 2016-07-21 07:15:59.177604Z] Finished clobber step (success) 00:15:59 INFO - [mozharness: 2016-07-21 07:15:59.177706Z] Running read-buildbot-config step. 00:15:59 INFO - Running pre-action listener: _resource_record_pre_action 00:15:59 INFO - Running main action method: read_buildbot_config 00:15:59 INFO - Using buildbot properties: 00:15:59 INFO - { 00:15:59 INFO - "project": "", 00:15:59 INFO - "product": "firefox", 00:15:59 INFO - "script_repo_revision": "production", 00:15:59 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 00:15:59 INFO - "repository": "", 00:15:59 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 00:15:59 INFO - "buildid": "20160720230019", 00:15:59 INFO - "pgo_build": "False", 00:15:59 INFO - "basedir": "/builds/slave/test", 00:15:59 INFO - "buildnumber": 77, 00:15:59 INFO - "slavename": "talos-linux64-ix-053", 00:15:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 00:15:59 INFO - "platform": "ubuntu64_hw", 00:15:59 INFO - "scheduler": "tests-autoland-linux64-talos", 00:15:59 INFO - "branch": "autoland", 00:15:59 INFO - "repo_path": "integration/autoland", 00:15:59 INFO - "revision": "d4ec1b2cf832673078935f9e57cff6061c1bec16", 00:15:59 INFO - "stage_platform": "linux64", 00:15:59 INFO - "builduid": "39a9f02d5c59469a8778405e42a10fb6", 00:15:59 INFO - "slavebuilddir": "test" 00:15:59 INFO - } 00:15:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 00:15:59 INFO - Running post-action listener: _resource_record_post_action 00:15:59 INFO - [mozharness: 2016-07-21 07:15:59.180157Z] Finished read-buildbot-config step (success) 00:15:59 INFO - [mozharness: 2016-07-21 07:15:59.180246Z] Running download-and-extract step. 00:15:59 INFO - Running pre-action listener: _resource_record_pre_action 00:15:59 INFO - Running main action method: download_and_extract 00:15:59 INFO - mkdir: /builds/slave/test/build/tests 00:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:15:59 INFO - https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 00:15:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 00:15:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 00:15:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:15:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:01 INFO - Downloaded 1361 bytes. 00:16:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 00:16:01 INFO - Using the following test package requirements: 00:16:01 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 00:16:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:16:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:16:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'jsshell-linux-x86_64.zip'], 00:16:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:16:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 00:16:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:16:01 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:16:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:16:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 00:16:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:16:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:01 INFO - https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:16:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 00:16:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 00:16:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:02 INFO - Downloaded 22730093 bytes. 00:16:02 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'] 00:16:02 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 00:16:04 INFO - Return code: 0 00:16:04 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 00:16: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')]} 00:16:04 INFO - https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:16:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 00:16:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 00:16:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:05 INFO - Downloaded 22730093 bytes. 00:16:05 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'] 00:16:05 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 00:16:06 INFO - Return code: 0 00:16:06 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')]} 00:16:06 INFO - https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 00:16:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 00:16:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 00:16:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:07 INFO - Downloaded 14403317 bytes. 00:16:07 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'] 00:16:07 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 00:16:08 INFO - Return code: 0 00:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:16:08 INFO - https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 00:16:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 00:16:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 00:16:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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 00:16:09 INFO - Downloaded 57150205 bytes. 00:16:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 00:16:09 INFO - mkdir: /builds/slave/test/properties 00:16:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:16:09 INFO - Writing to file /builds/slave/test/properties/build_url 00:16:09 INFO - Contents: 00:16:09 INFO - build_url:https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 00:16:10 INFO - Running post-action listener: _resource_record_post_action 00:16:10 INFO - Running post-action listener: set_extra_try_arguments 00:16:10 INFO - [mozharness: 2016-07-21 07:16:10.569687Z] Finished download-and-extract step (success) 00:16:10 INFO - [mozharness: 2016-07-21 07:16:10.569921Z] Running populate-webroot step. 00:16:10 INFO - Running pre-action listener: _resource_record_pre_action 00:16:10 INFO - Running main action method: populate_webroot 00:16:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:16:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:16:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:16:10 INFO - '--tppagecycles', 00:16:10 INFO - '7'], 00:16:10 INFO - 'tests': ['tsvgm']}}, 00:16:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:16:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:16:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:16:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:16:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:16:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:16:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['damp', 'tps']}, 00:16:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'tests': ['damp', 'tps']}, 00:16:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['dromaeo_dom']}, 00:16:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:16:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['basic_compositor_video']}, 00:16:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:16:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['a11yr', 00:16:10 INFO - 'ts_paint', 00:16:10 INFO - 'tpaint', 00:16:10 INFO - 'sessionrestore', 00:16:10 INFO - 'sessionrestore_no_auto_restore', 00:16:10 INFO - 'tabpaint']}, 00:16:10 INFO - 'other-e10s': {'tests': ['a11yr', 00:16:10 INFO - 'ts_paint', 00:16:10 INFO - 'tpaint', 00:16:10 INFO - 'sessionrestore', 00:16:10 INFO - 'sessionrestore_no_auto_restore', 00:16:10 INFO - 'tabpaint']}, 00:16:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:16:10 INFO - 'ts_paint', 00:16:10 INFO - 'tpaint', 00:16:10 INFO - 'sessionrestore', 00:16:10 INFO - 'sessionrestore_no_auto_restore', 00:16:10 INFO - 'tabpaint']}, 00:16:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:16:10 INFO - 'ts_paint', 00:16:10 INFO - 'tpaint', 00:16:10 INFO - 'sessionrestore', 00:16:10 INFO - 'sessionrestore_no_auto_restore', 00:16:10 INFO - 'tabpaint']}, 00:16:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['a11yr', 00:16:10 INFO - 'ts_paint', 00:16:10 INFO - 'tpaint', 00:16:10 INFO - 'sessionrestore', 00:16:10 INFO - 'sessionrestore_no_auto_restore', 00:16:10 INFO - 'tabpaint']}, 00:16:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['a11yr', 00:16:10 INFO - 'ts_paint', 00:16:10 INFO - 'tpaint', 00:16:10 INFO - 'sessionrestore', 00:16:10 INFO - 'sessionrestore_no_auto_restore', 00:16:10 INFO - 'tabpaint']}, 00:16:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['tsvgx', 00:16:10 INFO - 'tsvgr_opacity', 00:16:10 INFO - 'tart', 00:16:10 INFO - 'tscrollx', 00:16:10 INFO - 'cart']}, 00:16:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:16:10 INFO - 'tsvgr_opacity', 00:16:10 INFO - 'tart', 00:16:10 INFO - 'tscrollx', 00:16:10 INFO - 'cart']}, 00:16:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'talos_options': ['--disable-e10s'], 00:16:10 INFO - 'tests': ['tp5o']}, 00:16:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'tests': ['tp5o']}, 00:16:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'talos_options': ['--disable-e10s', 00:16:10 INFO - '--xperf_path', 00:16:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:16:10 INFO - 'tests': ['tp5n']}, 00:16:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:16:10 INFO - 'talos_options': ['--xperf_path', 00:16:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:16:10 INFO - 'tests': ['tp5n']}}, 00:16:10 INFO - 'talos.zip': {'path': '', 00:16:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:16:10 INFO - Running post-action listener: _resource_record_post_action 00:16:10 INFO - [mozharness: 2016-07-21 07:16:10.589366Z] Finished populate-webroot step (success) 00:16:10 INFO - [mozharness: 2016-07-21 07:16:10.589456Z] Running create-virtualenv step. 00:16:10 INFO - Running pre-action listener: _resource_record_pre_action 00:16:10 INFO - Running main action method: create_virtualenv 00:16:10 INFO - Creating virtualenv /builds/slave/test/build/venv 00:16:10 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 00:16:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:16:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:16:10 INFO - Using real prefix '/usr' 00:16:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:16:11 INFO - Installing distribute.............................................................................................................................................................................................done. 00:16:13 INFO - Installing pip.................done. 00:16:13 INFO - Return code: 0 00:16:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:16:13 INFO - Using env: {'DISPLAY': ':0', 00:16:13 INFO - 'HOME': '/home/cltbld', 00:16:13 INFO - 'LANG': 'en_US.UTF-8', 00:16:13 INFO - 'LANGUAGE': 'en_US:en', 00:16:13 INFO - 'LOGNAME': 'cltbld', 00:16:13 INFO - 'MAIL': '/var/mail/cltbld', 00:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:13 INFO - 'MOZ_NO_REMOTE': '1', 00:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:13 INFO - 'NO_EM_RESTART': '1', 00:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:13 INFO - 'PWD': '/builds/slave/test', 00:16:13 INFO - 'SHELL': '/bin/bash', 00:16:13 INFO - 'SHLVL': '1', 00:16:13 INFO - 'TERM': 'linux', 00:16:13 INFO - 'TMOUT': '86400', 00:16:13 INFO - 'USER': 'cltbld', 00:16:13 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:13 INFO - '_': '/tools/buildbot/bin/python'} 00:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:13 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 00:16:13 INFO - Cleaning up... 00:16:13 INFO - Return code: 0 00:16:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:16:13 INFO - Using env: {'DISPLAY': ':0', 00:16:13 INFO - 'HOME': '/home/cltbld', 00:16:13 INFO - 'LANG': 'en_US.UTF-8', 00:16:13 INFO - 'LANGUAGE': 'en_US:en', 00:16:13 INFO - 'LOGNAME': 'cltbld', 00:16:13 INFO - 'MAIL': '/var/mail/cltbld', 00:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:13 INFO - 'MOZ_NO_REMOTE': '1', 00:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:13 INFO - 'NO_EM_RESTART': '1', 00:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:13 INFO - 'PWD': '/builds/slave/test', 00:16:13 INFO - 'SHELL': '/bin/bash', 00:16:13 INFO - 'SHLVL': '1', 00:16:13 INFO - 'TERM': 'linux', 00:16:13 INFO - 'TMOUT': '86400', 00:16:13 INFO - 'USER': 'cltbld', 00:16:13 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:13 INFO - '_': '/tools/buildbot/bin/python'} 00:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:13 INFO - Downloading/unpacking psutil>=3.1.1 00:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:16:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:16:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:16:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:16:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:16:15 INFO - Installing collected packages: psutil 00:16:15 INFO - Running setup.py install for psutil 00:16:15 INFO - building 'psutil._psutil_linux' extension 00:16:15 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 00:16:15 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 00:16:15 INFO - building 'psutil._psutil_posix' extension 00:16:15 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 00:16:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 00:16:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:16:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:16:16 INFO - Successfully installed psutil 00:16:16 INFO - Cleaning up... 00:16:16 INFO - Return code: 0 00:16:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:16:16 INFO - Using env: {'DISPLAY': ':0', 00:16:16 INFO - 'HOME': '/home/cltbld', 00:16:16 INFO - 'LANG': 'en_US.UTF-8', 00:16:16 INFO - 'LANGUAGE': 'en_US:en', 00:16:16 INFO - 'LOGNAME': 'cltbld', 00:16:16 INFO - 'MAIL': '/var/mail/cltbld', 00:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:16 INFO - 'MOZ_NO_REMOTE': '1', 00:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:16 INFO - 'NO_EM_RESTART': '1', 00:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:16 INFO - 'PWD': '/builds/slave/test', 00:16:16 INFO - 'SHELL': '/bin/bash', 00:16:16 INFO - 'SHLVL': '1', 00:16:16 INFO - 'TERM': 'linux', 00:16:16 INFO - 'TMOUT': '86400', 00:16:16 INFO - 'USER': 'cltbld', 00:16:16 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:16 INFO - '_': '/tools/buildbot/bin/python'} 00:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:16:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:16:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:16:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:16:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:16:18 INFO - Installing collected packages: mozsystemmonitor 00:16:18 INFO - Running setup.py install for mozsystemmonitor 00:16:18 INFO - Successfully installed mozsystemmonitor 00:16:18 INFO - Cleaning up... 00:16:18 INFO - Return code: 0 00:16:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:16: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')]} 00:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16: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')]} 00:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:18 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 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:18 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 00:16:18 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 00:16:18 INFO - Using env: {'DISPLAY': ':0', 00:16:18 INFO - 'HOME': '/home/cltbld', 00:16:18 INFO - 'LANG': 'en_US.UTF-8', 00:16:18 INFO - 'LANGUAGE': 'en_US:en', 00:16:18 INFO - 'LOGNAME': 'cltbld', 00:16:18 INFO - 'MAIL': '/var/mail/cltbld', 00:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:18 INFO - 'MOZ_NO_REMOTE': '1', 00:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:18 INFO - 'NO_EM_RESTART': '1', 00:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:18 INFO - 'PWD': '/builds/slave/test', 00:16:18 INFO - 'SHELL': '/bin/bash', 00:16:18 INFO - 'SHLVL': '1', 00:16:18 INFO - 'TERM': 'linux', 00:16:18 INFO - 'TMOUT': '86400', 00:16:18 INFO - 'USER': 'cltbld', 00:16:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:18 INFO - '_': '/tools/buildbot/bin/python'} 00:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:18 INFO - Downloading/unpacking jsonschema==2.5.1 00:16:18 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 00:16:18 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 00:16:18 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 00:16:18 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 00:16:18 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 00:16:18 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 00:16:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:16:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:16:20 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 00:16:20 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 00:16:20 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 00:16:20 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 00:16:20 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 00:16:20 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 00:16:20 INFO - Downloading functools32-3.2.3-2.tar.gz 00:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:16:20 INFO - warning: no files found matching '*.txt' 00:16:20 INFO - no previously-included directories found matching 'build' 00:16:20 INFO - no previously-included directories found matching 'dist' 00:16:20 INFO - no previously-included directories found matching '.git*' 00:16:20 INFO - Installing collected packages: jsonschema, functools32 00:16:20 INFO - Running setup.py install for functools32 00:16:21 INFO - warning: no files found matching '*.txt' 00:16:21 INFO - no previously-included directories found matching 'build' 00:16:21 INFO - no previously-included directories found matching 'dist' 00:16:21 INFO - no previously-included directories found matching '.git*' 00:16:21 INFO - Successfully installed jsonschema functools32 00:16:21 INFO - Cleaning up... 00:16:21 INFO - Return code: 0 00:16:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:16:21 INFO - Using env: {'DISPLAY': ':0', 00:16:21 INFO - 'HOME': '/home/cltbld', 00:16:21 INFO - 'LANG': 'en_US.UTF-8', 00:16:21 INFO - 'LANGUAGE': 'en_US:en', 00:16:21 INFO - 'LOGNAME': 'cltbld', 00:16:21 INFO - 'MAIL': '/var/mail/cltbld', 00:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:21 INFO - 'MOZ_NO_REMOTE': '1', 00:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:21 INFO - 'NO_EM_RESTART': '1', 00:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:21 INFO - 'PWD': '/builds/slave/test', 00:16:21 INFO - 'SHELL': '/bin/bash', 00:16:21 INFO - 'SHLVL': '1', 00:16:21 INFO - 'TERM': 'linux', 00:16:21 INFO - 'TMOUT': '86400', 00:16:21 INFO - 'USER': 'cltbld', 00:16:21 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:21 INFO - '_': '/tools/buildbot/bin/python'} 00:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:21 INFO - Downloading/unpacking blobuploader==1.2.4 00:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:16:23 INFO - Downloading blobuploader-1.2.4.tar.gz 00:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:16:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:16:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 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 00:16:23 INFO - Downloading docopt-0.6.1.tar.gz 00:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:16:24 INFO - Installing collected packages: blobuploader, requests, docopt 00:16:24 INFO - Running setup.py install for blobuploader 00:16:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:16:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:16:24 INFO - Running setup.py install for requests 00:16:24 INFO - Running setup.py install for docopt 00:16:24 INFO - Successfully installed blobuploader requests docopt 00:16:24 INFO - Cleaning up... 00:16:24 INFO - Return code: 0 00:16:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:16: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')]} 00:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16: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')]} 00:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:24 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 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:24 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 00:16:24 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 00:16:24 INFO - Using env: {'DISPLAY': ':0', 00:16:24 INFO - 'HOME': '/home/cltbld', 00:16:24 INFO - 'LANG': 'en_US.UTF-8', 00:16:24 INFO - 'LANGUAGE': 'en_US:en', 00:16:24 INFO - 'LOGNAME': 'cltbld', 00:16:24 INFO - 'MAIL': '/var/mail/cltbld', 00:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:24 INFO - 'MOZ_NO_REMOTE': '1', 00:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:24 INFO - 'NO_EM_RESTART': '1', 00:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:24 INFO - 'PWD': '/builds/slave/test', 00:16:24 INFO - 'SHELL': '/bin/bash', 00:16:24 INFO - 'SHLVL': '1', 00:16:24 INFO - 'TERM': 'linux', 00:16:24 INFO - 'TMOUT': '86400', 00:16:24 INFO - 'USER': 'cltbld', 00:16:24 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:24 INFO - '_': '/tools/buildbot/bin/python'} 00:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:16:24 INFO - Running setup.py (path:/tmp/pip-Nr2EZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:16:24 INFO - Running setup.py (path:/tmp/pip-J5Lolf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:16:24 INFO - Running setup.py (path:/tmp/pip-uwTX55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:16:25 INFO - Running setup.py (path:/tmp/pip-8Yd8Zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:16:25 INFO - Running setup.py (path:/tmp/pip-TaZq2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:16:25 INFO - Running setup.py (path:/tmp/pip-oS4asz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:16:25 INFO - Running setup.py (path:/tmp/pip-ZJpM1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:16:25 INFO - Running setup.py (path:/tmp/pip-Exw5A5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:16:25 INFO - Running setup.py (path:/tmp/pip-VWejiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:16:25 INFO - Running setup.py (path:/tmp/pip-UzaHN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:16:25 INFO - Running setup.py (path:/tmp/pip-aFBSrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:16:25 INFO - Running setup.py (path:/tmp/pip-aBMQFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:16:25 INFO - Running setup.py (path:/tmp/pip-wVyeq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:16:25 INFO - Running setup.py (path:/tmp/pip-xgpFrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:16:25 INFO - Running setup.py (path:/tmp/pip-nH1oWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:16:25 INFO - Running setup.py (path:/tmp/pip-KXaWh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:16:26 INFO - Running setup.py (path:/tmp/pip-Bfvino-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:16:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:16:26 INFO - Running setup.py install for manifestparser 00:16:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Running setup.py install for mozcrash 00:16:26 INFO - Running setup.py install for mozdebug 00:16:26 INFO - Running setup.py install for mozdevice 00:16:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Running setup.py install for mozfile 00:16:26 INFO - Running setup.py install for mozhttpd 00:16:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Running setup.py install for mozinfo 00:16:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Running setup.py install for mozInstall 00:16:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:16:26 INFO - Running setup.py install for mozleak 00:16:26 INFO - Running setup.py install for mozlog 00:16:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Running setup.py install for moznetwork 00:16:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Running setup.py install for mozprocess 00:16:27 INFO - Running setup.py install for mozprofile 00:16:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Running setup.py install for mozrunner 00:16:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Running setup.py install for mozscreenshot 00:16:27 INFO - Running setup.py install for moztest 00:16:27 INFO - Running setup.py install for mozversion 00:16:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:16:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:16:27 INFO - Cleaning up... 00:16:27 INFO - Return code: 0 00:16:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:16: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')]} 00:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16: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')]} 00:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:27 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 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:27 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 00:16:27 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 00:16:27 INFO - Using env: {'DISPLAY': ':0', 00:16:27 INFO - 'HOME': '/home/cltbld', 00:16:27 INFO - 'LANG': 'en_US.UTF-8', 00:16:27 INFO - 'LANGUAGE': 'en_US:en', 00:16:27 INFO - 'LOGNAME': 'cltbld', 00:16:27 INFO - 'MAIL': '/var/mail/cltbld', 00:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:27 INFO - 'MOZ_NO_REMOTE': '1', 00:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:27 INFO - 'NO_EM_RESTART': '1', 00:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:27 INFO - 'PWD': '/builds/slave/test', 00:16:27 INFO - 'SHELL': '/bin/bash', 00:16:27 INFO - 'SHLVL': '1', 00:16:27 INFO - 'TERM': 'linux', 00:16:27 INFO - 'TMOUT': '86400', 00:16:27 INFO - 'USER': 'cltbld', 00:16:27 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:27 INFO - '_': '/tools/buildbot/bin/python'} 00:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:16:28 INFO - Running setup.py (path:/tmp/pip-dDm6hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:16:28 INFO - Running setup.py (path:/tmp/pip-Qo0TGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:16:28 INFO - Running setup.py (path:/tmp/pip-ZZzBdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:16:28 INFO - Running setup.py (path:/tmp/pip-PQkIAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:16:28 INFO - Running setup.py (path:/tmp/pip-r62Rpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:16:28 INFO - Running setup.py (path:/tmp/pip-hYT3dC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:16:28 INFO - Running setup.py (path:/tmp/pip-38ZF6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:16:28 INFO - Running setup.py (path:/tmp/pip-rvGOaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:16:28 INFO - Running setup.py (path:/tmp/pip-3WsIFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:16:28 INFO - Running setup.py (path:/tmp/pip-19ObgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:16:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:16:28 INFO - Running setup.py (path:/tmp/pip-f1Izuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:16:28 INFO - Running setup.py (path:/tmp/pip-w2MH6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:16:28 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)) 00:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:16:28 INFO - Running setup.py (path:/tmp/pip-frUF31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:16:29 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)) 00:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:16:29 INFO - Running setup.py (path:/tmp/pip-OIKSfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:16:29 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)) 00:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:16:29 INFO - Running setup.py (path:/tmp/pip-Fyjncl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:16:29 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)) 00:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:16:29 INFO - Running setup.py (path:/tmp/pip-h19TFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:16:29 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)) 00:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:16:29 INFO - Running setup.py (path:/tmp/pip-Omgk6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:16:29 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)) 00:16:29 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)) 00:16:29 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)) 00:16:29 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)) 00:16:29 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)) 00:16:29 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)) 00:16:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:16: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 00:16: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 00:16: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 00:16: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 00:16: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 00:16: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 00:16:31 INFO - Downloading blessings-1.6.tar.gz 00:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:16:31 INFO - Installing collected packages: blessings 00:16:31 INFO - Running setup.py install for blessings 00:16:31 INFO - Successfully installed blessings 00:16:31 INFO - Cleaning up... 00:16:31 INFO - Return code: 0 00:16:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:16:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:16:31 INFO - Reading from file tmpfile_stdout 00:16:31 INFO - Current package versions: 00:16:31 INFO - argparse == 1.2.1 00:16:31 INFO - blessings == 1.6 00:16:31 INFO - blobuploader == 1.2.4 00:16:31 INFO - docopt == 0.6.1 00:16:31 INFO - functools32 == 3.2.3-2 00:16:31 INFO - jsonschema == 2.5.1 00:16:31 INFO - manifestparser == 1.1 00:16:31 INFO - mozInstall == 1.12 00:16:31 INFO - mozcrash == 0.17 00:16:31 INFO - mozdebug == 0.1 00:16:31 INFO - mozdevice == 0.48 00:16:31 INFO - mozfile == 1.2 00:16:31 INFO - mozhttpd == 0.7 00:16:31 INFO - mozinfo == 0.9 00:16:31 INFO - mozleak == 0.1 00:16:31 INFO - mozlog == 3.3 00:16:31 INFO - moznetwork == 0.27 00:16:31 INFO - mozprocess == 0.23 00:16:31 INFO - mozprofile == 0.28 00:16:31 INFO - mozrunner == 6.12 00:16:31 INFO - mozscreenshot == 0.1 00:16:31 INFO - mozsystemmonitor == 0.3 00:16:31 INFO - moztest == 0.7 00:16:31 INFO - mozversion == 1.4 00:16:31 INFO - psutil == 3.1.1 00:16:31 INFO - requests == 1.2.3 00:16:31 INFO - wsgiref == 0.1.2 00:16:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:16: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')]} 00:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16: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')]} 00:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:31 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 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16:31 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 00:16:31 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 00:16:31 INFO - Using env: {'DISPLAY': ':0', 00:16:31 INFO - 'HOME': '/home/cltbld', 00:16:31 INFO - 'LANG': 'en_US.UTF-8', 00:16:31 INFO - 'LANGUAGE': 'en_US:en', 00:16:31 INFO - 'LOGNAME': 'cltbld', 00:16:31 INFO - 'MAIL': '/var/mail/cltbld', 00:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:31 INFO - 'MOZ_NO_REMOTE': '1', 00:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:31 INFO - 'NO_EM_RESTART': '1', 00:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:31 INFO - 'PWD': '/builds/slave/test', 00:16:31 INFO - 'SHELL': '/bin/bash', 00:16:31 INFO - 'SHLVL': '1', 00:16:31 INFO - 'TERM': 'linux', 00:16:31 INFO - 'TMOUT': '86400', 00:16:31 INFO - 'USER': 'cltbld', 00:16:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:31 INFO - '_': '/tools/buildbot/bin/python'} 00:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 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)) 00:16:31 INFO - Cleaning up... 00:16:31 INFO - Return code: 0 00:16:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:16: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')]} 00:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16: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')]} 00:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2894030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, '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': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', '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 00:16: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', 'jsonschema'] in /builds/slave/test/build 00:16: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 jsonschema 00:16:31 INFO - Using env: {'DISPLAY': ':0', 00:16:31 INFO - 'HOME': '/home/cltbld', 00:16:31 INFO - 'LANG': 'en_US.UTF-8', 00:16:31 INFO - 'LANGUAGE': 'en_US:en', 00:16:31 INFO - 'LOGNAME': 'cltbld', 00:16:31 INFO - 'MAIL': '/var/mail/cltbld', 00:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:31 INFO - 'MOZ_NO_REMOTE': '1', 00:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:31 INFO - 'NO_EM_RESTART': '1', 00:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:31 INFO - 'PWD': '/builds/slave/test', 00:16:31 INFO - 'SHELL': '/bin/bash', 00:16:31 INFO - 'SHLVL': '1', 00:16:31 INFO - 'TERM': 'linux', 00:16:31 INFO - 'TMOUT': '86400', 00:16:31 INFO - 'USER': 'cltbld', 00:16:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:31 INFO - '_': '/tools/buildbot/bin/python'} 00:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:16:32 INFO - Cleaning up... 00:16:32 INFO - Return code: 0 00:16:32 INFO - Running post-action listener: _resource_record_post_action 00:16:32 INFO - Running post-action listener: _start_resource_monitoring 00:16:32 INFO - Starting resource monitoring. 00:16:32 INFO - [mozharness: 2016-07-21 07:16:32.223483Z] Finished create-virtualenv step (success) 00:16:32 INFO - [mozharness: 2016-07-21 07:16:32.224552Z] Running install step. 00:16:32 INFO - Running pre-action listener: _resource_record_pre_action 00:16:32 INFO - Running main action method: install 00:16:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:16:32 INFO - Reading from file tmpfile_stdout 00:16:32 INFO - Detecting whether we're running mozinstall >=1.0... 00:16:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:16:32 INFO - Reading from file tmpfile_stdout 00:16:32 INFO - Output received: 00:16:32 INFO - Usage: mozinstall [options] installer 00:16:32 INFO - Options: 00:16:32 INFO - -h, --help show this help message and exit 00:16:32 INFO - -d DEST, --destination=DEST 00:16:32 INFO - Directory to install application into. [default: 00:16:32 INFO - "/builds/slave/test"] 00:16:32 INFO - --app=APP Application being installed. [default: firefox] 00:16:32 INFO - mkdir: /builds/slave/test/build/application 00:16:32 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'] 00:16:32 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 00:16:48 INFO - Reading from file tmpfile_stdout 00:16:48 INFO - Output received: 00:16:48 INFO - /builds/slave/test/build/application/firefox/firefox 00:16:48 INFO - Running post-action listener: _resource_record_post_action 00:16:48 INFO - [mozharness: 2016-07-21 07:16:48.905500Z] Finished install step (success) 00:16:48 INFO - [mozharness: 2016-07-21 07:16:48.905779Z] Running run-tests step. 00:16:48 INFO - Running pre-action listener: _resource_record_pre_action 00:16:48 INFO - Running main action method: run_tests 00:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:16:48 INFO - Python 2.7.3 00:16:48 INFO - Return code: 0 00:16:48 INFO - grabbing minidump binary from tooltool 00:16:48 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')]} 00:16:48 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 0x2b533b0>, '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 0x2b542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b54740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:16:48 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 00:16:48 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 00:16:48 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 00:16:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:16:48 INFO - Return code: 0 00:16:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:16:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:16:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:16:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:16:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 00:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 00:16:48 INFO - Using env: {'DISPLAY': ':0', 00:16:48 INFO - 'HOME': '/home/cltbld', 00:16:48 INFO - 'LANG': 'en_US.UTF-8', 00:16:48 INFO - 'LANGUAGE': 'en_US:en', 00:16:48 INFO - 'LOGNAME': 'cltbld', 00:16:48 INFO - 'MAIL': '/var/mail/cltbld', 00:16:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:16:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:48 INFO - 'MOZ_NO_REMOTE': '1', 00:16:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:16:48 INFO - 'NO_EM_RESTART': '1', 00:16:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:48 INFO - 'PWD': '/builds/slave/test', 00:16:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:16:48 INFO - 'SHELL': '/bin/bash', 00:16:48 INFO - 'SHLVL': '1', 00:16:48 INFO - 'TERM': 'linux', 00:16:48 INFO - 'TMOUT': '86400', 00:16:48 INFO - 'USER': 'cltbld', 00:16:48 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767', 00:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:48 INFO - '_': '/tools/buildbot/bin/python'} 00:16:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 00:16:49 INFO - mozversion application_buildid: 20160720230019 00:16:49 INFO - mozversion application_changeset: d4ec1b2cf832673078935f9e57cff6061c1bec16 00:16:49 INFO - mozversion application_display_name: Nightly 00:16:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:16:49 INFO - mozversion application_name: Firefox 00:16:49 INFO - mozversion application_remotingname: firefox 00:16:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 00:16:49 INFO - mozversion application_vendor: Mozilla 00:16:49 INFO - mozversion application_version: 50.0a1 00:16:49 INFO - mozversion platform_buildid: 20160720230019 00:16:49 INFO - mozversion platform_changeset: d4ec1b2cf832673078935f9e57cff6061c1bec16 00:16:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 00:16:49 INFO - mozversion platform_version: 50.0a1 00:16:49 INFO - using testdate: 1469085409 00:16:49 INFO - actual date: 1469085409 00:16:49 INFO - starting webserver on 'localhost:33733' 00:16:49 INFO - SUITE-START | Running 2 tests 00:16:49 INFO - TEST-START | dromaeo_css 00:16:49 INFO - operating with platform_type : linux_ 00:16:49 INFO - Initialising browser for dromaeo_css test... 00:16:49 INFO - TEST-INFO | started process 4056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0vlIXN/profile http://localhost:33733/getInfo.html) 00:16:53 INFO - PROCESS | 4056 | __metrics Screen width/height:1600/1200 00:16:53 INFO - PROCESS | 4056 | colorDepth:24 00:16:53 INFO - PROCESS | 4056 | Browser inner width/height: 1024/697 00:16:53 INFO - PROCESS | 4056 | __metrics 00:16:54 INFO - PROCESS | 4056 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:16:54 INFO - PROCESS | 4056 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:16:55 INFO - PROCESS | 4056 | console.error: PushService: 00:16:55 INFO - PROCESS | 4056 | stateChangeProcessEnqueue: Error transitioning state 00:16:55 INFO - PROCESS | 4056 | UnknownError 00:16:55 INFO - TEST-INFO | 4056: exit 0 00:16:55 INFO - Browser initialized. 00:16:55 INFO - Running cycle 1/1 for dromaeo_css test... 00:16:55 INFO - TEST-INFO | started process 4136 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0vlIXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:17:07 INFO - PROCESS | 4136 | RSS: Main: 158699520 00:17:07 INFO - PROCESS | 4136 | 00:17:59 INFO - PROCESS | 4136 | 1469085479383 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] 00:20:10 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:33733/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33733/tests/dromaeo/cssquery-ext.html) 00:20:11 INFO - PROCESS | 4136 | RSS: Main: 248119296 00:20:11 INFO - PROCESS | 4136 | 00:23:14 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:33733/tests/dromaeo/cssquery-ext.html (next: http://localhost:33733/tests/dromaeo/cssquery-jquery.html) 00:23:15 INFO - PROCESS | 4136 | RSS: Main: 239808512 00:23:15 INFO - PROCESS | 4136 | 00:26:17 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:33733/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33733/tests/dromaeo/cssquery-mootools.html) 00:26:17 INFO - PROCESS | 4136 | RSS: Main: 192716800 00:26:17 INFO - PROCESS | 4136 | 00:29:20 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:33733/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33733/tests/dromaeo/cssquery-prototype.html) 00:29:20 INFO - PROCESS | 4136 | RSS: Main: 188211200 00:29:20 INFO - PROCESS | 4136 | 00:32:23 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:33733/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33733/tests/dromaeo/cssquery-yui.html) 00:32:24 INFO - PROCESS | 4136 | RSS: Main: 189456384 00:32:24 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:33733/tests/dromaeo/cssquery-yui.html (next: http://localhost:33733/tests/dromaeo/cssquery-dojo.html) 00:35:27 INFO - PROCESS | 4136 | RSS: Main: 275980288 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | __start_tp_report 00:35:27 INFO - PROCESS | 4136 | _x_x_mozilla_page_load 00:35:27 INFO - PROCESS | 4136 | _x_x_mozilla_page_load_details 00:35:27 INFO - PROCESS | 4136 | |i|pagename|runs| 00:35:27 INFO - PROCESS | 4136 | |0;dojo.html;2569.2934443028166;2584.6769153855766;2611.7649411552957;2620.240130362195;2627.5270451318765;4557.065801510691;4660.021395506948;4726.692764970275;4780.378550788397;4784.83253086142;4715.481297057329;4745.833895813647;4764.309175169598;4772.880677983052;4787.49731278216;4295.957040429595;4327.048049429136;4384.956150438503;4428.623566996793;4434.534373890728;4331.978340108295;4375.9562404375865;4385.122975404915;4393.494748103961;4410.404595379615;4227.112306415656;4277.059047009653;4277.101808620194;4302.569743025672;4385.671074669397;4325.718828276152;4346.282863327548;4346.9347959780625;4349.086691794727;4404.955950440487;4376.256036473807;4381.9561804381865;4394.714343567658;4407.052483715985;4419.602235798793;4500.369948207253;4610.67725259232;4776.641751868624;4834.129856625806;4835.178019736653;4949.307097006422;5061.139606266944;5061.266116413136;5063.240513922904;5064.265681476165;5025.673331233458;5029.270755740397;5083.542481176712;5086.135356989321;5089.1094058539975;4524.09518096382;4670.813167473263;4691;4754.5245475452175;4755.215389460769;5175.301334319819;5202.92195617066;5216.478352164753;5226.5818734501145;5227.790888364499;4948.356713627206;4989.201727723546;4996.300517927493;4997.350344455198;5002.774875130628;4845.1278769821065;5003.274525193827;5047.570956468668;5058.114829904752;5060.342155519833;5001.499850015042;5055.772490237948;5086.821961231413;5090.516400941905;5094.974525127351;5029.094762942632;5040.672356296829;5041.773120209599;5043.067032598981;5092.032513822362;4054.270231358325;4279.40088387626;4283.635890949242;4285.400043993844;4331;3655.0131464504966;3702.185519185775;3739.121306492972;3740.8129593520216;3788.5643151037984;4411.7573533455325;4412.955870441318;4418.80115394808;4452.977735111369;4462.219111655448;5071.163258062452;5116.73771863425;5138.254953031789;5139;5179.507946745053;4266.5093514246255;4357.085012147484;4392.3191905254735;4396.824127034947;4397.208502469586;4560.429946256718;4579.17574836526;4584.472785629629;4612.192866248393;4616.76916154191;4812.302216178635;4821.4696383397795;4844.224924012212;4846.127697014571;4887.242477416006;4913.263010548446;4987.775550100182;4996.125678006407;5037.219231019198;5127.4103478099005;4773.761311934459;4802.51974802517;4805.8077676892535;4835.1296766580945;4838.104950584153;5002.749862506933;5003.524665156804;5008.223725322581;5010.317705581507;5014.122528557561;4908.631852610997;4979.800807967786;5002.124628189979;5025.221090730943;5114.284000239895;4923.286123512143;4929.507049295185;4929.679570827885;4936.679115857458;4939.925901111558;4895.7062576245535;4898.92651610219;4899.31409602663;4902.950970490249;4944.92582611254;4662.836800711959;4868.050730107595;4898.093852637274;4946.307516947558;4976.626752993468;5026.74866256693;5033.748312584429;5037.269595908646;5044.1172794761505;5056.418511871182;4927.384076990376;4930.975345123252;4931.038447502702;4931.630127740362;4965.106280869478 00:35:27 INFO - PROCESS | 4136 | |1;ext.html;553.7978637797205;567.3390500067421;569.2912324156426;582.6212961574978;587.2688502813998;27967.300817479547;41006.3094321511;42571.14857702848;42683.14633707324;46506;42520.149597008116;42793.78603106988;43004.13991720164;43171.3524297135;43185.7840710796;7822.960885195566;7958.323542498894;7960.6019699015105;7961.084475285329;7962.880556791652;7612.923870761277;9951.402915825074;10141.44222067786;10275.28073034884;10678.199135064862;10405.479726013707;10480.213983951235;10502.68491945243;10509.316894401878;10517.263791534595;7675.654595543214;10308.587656493732;10339.948300258451;10395.584176632925;10400.427976461253;9907.306488545804;10310.123639490652;10310.484475776182;10661.307015044074;10662.786744265071;39796;40143.19713605741;40150;40212.195756085006;40227.59772402268;2948.351362700224;2969.584258203853;3004.7145521175453;3022.9312063808575;3055.679153688875;29487;29919.551206731918;29984.700152998626;30308.54537181922;30416;3163.9367212655616;3277.2626159114247;3356;3394.8811791587177;3457.360388328167;265296;265929;266791.6660416705;267293;267988;57772.71113644405;58491;58510.41489585135;58637.41362586405;58746;33536.664633353845;34688.306233875184;34880;37127.81436092802;54627.453725463034;15642.296096675676;15757.606059848365;15818.367265309258;15818.60453488649;15818.841811581968;9944.45305508204;10145.137663298554;12107.636770896886;12137.029037676964;12189.634310970685;18776.183572463968;19267.132979015696;19361.15022604302;19426.125824337938;19475.513112172026;6853.7943861684225;6986.860262794716;7089.89365159523;7113.359797618135;7156.749513766993;827.9047909490365;874.2874557235832;924.7410724997012;967.3905439573024;1014.300132908291;28209.576856347172;29841;30179.396412071637;30310.69689303123;30332.332282643943;953.9666111686058;1025.7640742629087;1087.782443511301;1090.1442367741286;1100.9394483303336;15223.010504317184;15660.68678626444;15857.524274271791;15875.20623968797;15921.363145474052;428770;430207;431569;434049;434752;35571.82214088965;35823.64176358203;35855.64144358523;35929.82035089808;35937.82031089828;1054.773223756896;1164.5807509296646;1202.4528839378063;1224.1430998301046;1228.263042174688;29198.124056277495;30576.847115764278;30946;31077;31541.842290788398;45175.54824451714;45756.31365529453;45761;45797.31304030376;45822.77088614536;324444;325940;326426;326758;326765;25576.488470230863;26844.731552685003;27052.594211087242;28306.57540136941;28339.599248090362;41443.7927810359;44141.33787993118;45372.546274536835;45637;45811.54188458073;38440.80779596196;38447.423288650134;38984.22031559296;41106.383404248365;42687.786561067;37540;38277.42583861189;38619.80690096531;39601.80199098986;40001.59998400095;38037.809810951876;38552.42171367376;38597.22805543929;38852.222955540165;38897.222055558166;30550.847245763627;31188.37623247477;35095.47356789599;36102.63897361098;36220.4566931491;26619;26692.533255270853;26785;26933.326666833487;27180 00:35:27 INFO - PROCESS | 4136 | |2;jquery.html;360.27944111776446;364.761081491623;367.49285985340225;379.0504785512291;381.41833703602003;16231.918840405797;16554.66890662186;17614.735778963324;17750;17789.64420711585;16992.235349409275;17201.655966880688;17885.64228715425;17890.82109178908;17907.552311192227;5075.111855425304;5271.630985830993;5275.815346462856;5276.366835979668;5277.736113194325;5120.769565369549;5220.634555581111;5266.5523430508265;5267.262583238331;5376.354837419515;5460.208269800876;5467.097928841737;5469.699166545838;5470.070088085035;5472.644278121909;5761.68310742911;5766.452187042224;5904.881902361929;5942.64344139353;5975.880482390371;5250.7637156328055;5273.156294992821;5309.707966061865;5340.599455040888;5341.03861304965;20525.589488210302;20650.380488585368;21312.893435532722;21504.89247553768;21534.89232553843;1758.709812880886;1782.7147656374918;1875.6998880179242;2018.7476565429322;2208.2602328220064;9214.861777073349;9309.255259579217;9330.953345233298;9585.329026968115;13090.803637945439;1685.8398452147026;1716.3392094043884;1782.055510579395;1786.3836976243176;1831.7893442254178;71079;71787.28212717858;73003;74105.01491433651;76064.61967690181;9918.80162396748;10068.597256109673;10167.796644067077;10395.16838652906;10470.424126673106;14217.075890067112;14233.85766142346;14391.352389142514;14398.85601143996;14400.567982960529;7300.598467084255;7507.662155203029;7577.393808495307;7640.274173953466;7646.694132234759;7105.644717764091;7127.180374256923;7143.321384468467;7334.926650733424;7340;4297.290946993773;4323.459567554056;4352.238358287287;4451.643868490513;4481;1810.5926166612671;1830.108428682678;1842.3643842874187;1874.5032566369923;1879.539512819368;779;825.6779855856221;872.4154816272985;890.3722875372847;956.1108169402384;16619.169041547873;16990.91504542494;17620.118994050245;17890.731639025424;18130.546736331697;800.4864724764245;821.2384435386064;834.7120243515977;840.9075001749802;899.6176624934508;9677.661281855102;9761.170300524393;10721.35671859691;10870.67387978346;11113.44432778374;434160;436971;447253;447253;447807;53069.469305307226;57831;80845;86475.56762216148;90531.54734226418;913.5249670171473;929.2055292724623;959.6449313753956;960.2270172511056;961.5721004153085;16060.518184454486;16091.919540402223;16379.508614741577;17191.65616687684;17396.217170227625;39633.603663964146;39713.80143099266;40934.79532602318;41556;42239.155216896106;48265.517344826105;50721;56074;56944.715276423354;58380.12429813641;7904.20957904228;7959.323457506177;8092.231238032377;8112.797180070545;8169.387295952709;10006.899931000597;12233.3271670059;12293.631191064282;12377.071719621244;12429.75140497203;9883.209343252378;10624.415657138932;10624.787504249716;10723.517441714985;11184.440777961232;10654.467276635982;10674.199435042248;10684.57261709554;10761.784764304826;10911.181661375587;10314;10419;10626.787464250518;10656.360618362924;11094.889051109709;7775.144734079134;7866.528008319519;7896.09194942589;8248.422610417214;8312.168783121639;9805.460699661302;10746.46267686597;10807.945960270463;10895.291806032583;11132.499037543168 00:35:27 INFO - PROCESS | 4136 | |3;mootools.html;1241.590275209181;1373.1966799422337;1398.1611033379972;1455.2650911289797;1698.8725845561578;6908.101946746922;7631.198724133969;7904.249096335853;7930.246626570473;8172.63223154958;8011.839763204733;8072.112067672566;8076.111627720946;8077.273045425911;8079.798005049876;2237.1163390460797;2267.2178098556055;2267.614505534055;2270.2281224383705;2270.3416009357265;2122.108714339973;2189.102467988125;2195.1548653768336;2200.592890315289;2203.7025001624816;2144.9463763405884;2174.293354659742;2203.7575866654656;2204.614192516311;2214.2471559669707;2154.806067453929;2157.8273738100916;2160.7191065161587;2161.427221786228;2162.751283602382;2102.2326850699537;2143.431990522513;2144.142343062772;2145.324222869807;2149.881756503392;7736.535807851547;7965.2831245187635;8006.8790369348335;8022.237887400746;8024.27781499662;4553.658475614342;4744.95255047452;4747.881302967419;4757.286407038937;4762.2380419133115;2834.291427143214;2974.3456439583256;3016.441958237711;3035.210845180247;3044.2541577313477;996.1980605612479;998.7902540466511;1011.1708399112731;1012.4178597306504;1022.1005515146697;10606.310589811716;10657.520411581498;10676.412797296145;10678.519466623942;10696.144308455305;3949.2101579683604;4046.069404037088;4101.753894766323;4137.97931010343;4151.875443736692;8132.146124656826;8148.307393871469;8150.2664760171865;8158.592070396457;8160.102388737234;4598.632109431237;4602.424696912886;4604.148232576984;4604.815807367668;4607.861764147081;3631.092226943264;3710.7588006779474;4225.535191128974;4285;4288.206681763883;3449.1721986723505;3450.275442157125;3533.4699795030438;3556;3562.4478205877613;996.3225006995168;1031.3038698878227;1036.1192985961873;1038.5170895533613;1042.7028296935337;679.7654809090856;793.2622660925394;807.3500831830432;823.3413269384469;826.0954255090666;4949.7030178189425;5005.599552035828;5057.6459647824295;5125.33370661819;5326.121190003606;397.7434554712194;407.1978202940203;407.21407683171174;411.84555791578157;412.6801728660252;3266.591676040495;3303.306305675835;3377.8648854046055;3379.1552111972005;3435.3644575753565;9676.90323096774;10051.095401413908;10105.39367637944;10109.191264698806;10123.696289111338;10030.44832534203;10032.946540613277;10038.996100389902;10039.297249192487;10048.547815348327;7504.287092726182;7565.810854728676;7578.09062912454;7580.81047973794;7583.734569290048;4879.268109783561;4976.651634385558;4988.102141614544;5060.089183946925;5201.713905735221;9136.360454768102;9154.542272886461;9161.725148245563;9166.129217724305;9173.312001599774;7978.481398709066;8251.051129120227;9098.727038188865;9212.539373031188;9476.763080922756;6270.717817698123;6516.967415162894;6517;6670.733170673313;6672.366125218096;7661.693532258647;7682.154962953959;7690.500117492345;7729.381649467917;7802.336801371947;7013.509054366145;7032.789016329519;7059.752908648173;7080.822979425349;7108.537945033763;6783.457323414016;6879.2432832389395;6897.2757860423935;6900.6204658744255;6924.169099708067;6198.535109866688;6303.275123360872;6305.558610897377;6308.053791931247;6311.463525600376;4286.6784991126415;4523.502414734311;4544.204764166324;4547.204239258051;4563.6349092073215;4306.440162778818;4466.352378905106;4485.439320084989;4489.0572979674635;4508.481524624579 00:35:27 INFO - PROCESS | 4136 | |4;prototype.html;295.80476885255734;298.09825278532435;304.4611038461921;305.75234060411054;307.9184016235698;4896.461389247184;4910.852674419764;4920.311156438097;4938.38270216223;4957.75211239438;4841.128596852565;4883.413990321157;4904.141775189337;4913.680610760298;4917.4099108107075;3680.6871415929677;3764.774113553182;3805.847766089353;3847.3074846527616;3869.419587061935;3845.192509572993;3848.6151384861428;3855.1711382052854;3863.459115723801;3865.226954609075;3822.159124992511;3826.1773718650484;3826.712996525258;3867.168558759866;3873.741034163894;3686.2996030754207;3724.88825335239;3793.886183414502;3812.3519001769764;3842.8654997075246;3374.4432168692138;3376.4428869236544;3387.119348969261;3392.4741665041956;3408.6080100788477;4759.809607615691;4762.809487620491;4769.260764581495;4771.761411929389;4805.357053620494;4183.874483765492;4351.673624478145;4356.43366362374;4387.824487020515;4395.934060989088;5046.772895219724;5048.1418158913075;5061.089003979282;5061.481555533325;5100.770465329069;3715.10837399025;3715.7956312402807;3727.925441491155;3736.981315093407;3740.083679498513;5135.7674158202135;5327.627066105374;5334.866628334284;5344.6793192408195;5368.114261146906;3756.229972855558;3771.26460340231;3798.639129282714;3799.4490798834568;3807.4669546263544;3852.2680690668685;3861.67175790061;3867.9806600966817;3869.877735456686;3870.8838734838;4290.635295999875;4358.912821743547;4360.127974405068;4360.694751367438;4360.716553424018;4894.1891500145175;5005.474425185376;5028.84913452597;5051.408806226027;5125.410577783528;1320.6962398648172;1338.551585218943;1339.0760375340983;1355.6407551998727;1358.2393859438746;837.3970741066422;869.4479005831342;872.4721543466238;880.2210044111008;884.0584777212248;4309.655227581786;4423.889402764892;4430.601245887886;4447.666425018137;4453.599176074169;5046.47012063728;5135.79013116802;5167.682241028514;5244.842654720364;5256;4498.280275155959;4502.864914052584;4513.458384993822;4517.570830771071;4522.276435770261;2664.840109593392;2744.19046381318;2771.972280277171;2778.4304217635336;2786.9966811947684;4893.559579637849;4904.975475122602;4909.509049095133;4923.704577725348;4926.9014619707405;5116;5342.519173274424;5366.543843773323;5366.91949620756;5372.877713303224;4867.780949857265;4915.066137433948;4917;4924.803007879645;4938.8271410501175;5247.55395791362;5248.685078895279;5248.895022099613;5267.130923397596;5268.051750684914;5000.349954505891;5110.514501122388;5129.179331306973;5144.922826157685;5155.53600175986;4880.95119048805;4888.1180745413985;4912.533309335608;4917.532834380728;4921.8523444296725;3835.7506762060375;3844.519435070616;3854.730168888263;3868.9806550967064;3880.84476620932;5112.923306150336;5270.973645131749;5284.656497327662;5295.549878260237;5298.337707786527;5036.924446133237;5054.646174767731;5066.695998240118;5108.974455127701;5121.820736274212;5039;5069.290299358019;5082;5108.948910510847;5112.437631860566;4789.568938795525;4983.6511444200005;4997.5002499749735;5005.899882002413;5044.54599086084;4867.63492738039;4962.776675049704;5123.487651234846;5141.254518094781;5147.459516750837;4993.226049962262;4998.450170481171;5004.174311238605;5018.372703412074;5021.648484606042 00:35:27 INFO - PROCESS | 4136 | |5;yui.html;612.3570251236206;630.2248234671355;635.9491240700744;714.9356557909787;731.0897932074568;13153.934230328847;14307.642308942282;14343.713125737479;14349.497767578137;14365.28173591319;13090.149140305897;13261.005424593146;13314.068015238938;13357.53248636298;13366.732665346688;614.3549273263078;765.7319938021703;776.7048521561799;795.316028215734;798.4450806689354;782.9177936316662;793.5040599625235;793.5595744361876;793.8650429426982;794.1542257495778;843.5487014447272;844.6536919862857;845.6490556419079;845.7885528617845;846.0101681033206;638.7668500997149;786.9685212591489;811.2333844516875;839.4166054592062;840.2437805974641;600.3756093662586;602.4577879908118;603.2700432476709;604.2175382879182;604.6039843902252;13325;13354.131981421177;13386.665333366647;13401.329933503383;13402.262875541841;12317.876821231852;12399.93800031003;12726.427310771038;12977.091603587753;13166.934165329209;11547.307161570248;11715.7071073223;11848;11856.170068095236;11861.051115910792;4057.9391309130388;4109.116539943926;4159.230542349675;4248;4259.084296876155;60066.99999999956;60159.69920150371;60317.69841150766;60336.39663603352;60591.69704151495;9818.116369526777;10052.59789608422;10366.378017318984;10462.319949203278;10833.29583577065;19216.519587010436;19222;19239.711404328948;19297.324593639438;20078.598428031357;6363.300036995926;6644.468442524587;6677;6714.697838597177;7030.14625410413;3567.714582833367;3578.8926332210076;3590.6947909427467;3653.178034942117;3663.6153203913736;2874.3245337345707;2997.2806526433933;2997.9100626981226;2998.0705981145916;3000.084974082926;482.3054801086424;485.0662474736119;493.4005183701748;495.71000964436206;499.276049727896;436.07769567364795;484.2639188433561;519.6414474012919;584.2988413903248;589.4429763873185;7325.670344834495;7509.662065207079;7543.50967187142;7595.696172153052;7741.8451630967065;446.78331009460385;485.6284942019358;516.176698166424;527.0907684244647;551.2172714745071;3578.281129301506;3677.1174918019483;3796.3925771876366;3894.980525097413;3929.4891664083484;93863.53068234616;94820.52589737007;95601;98506;99522;81601.59199203966;83903.58048209842;84310;86210;86906;72897;74529.25470745223;75243.62378188183;76400.61799690966;77881.937674051;7296.7446139384865;7416.517926334771;7422.591757453176;7449.962750186214;7532.43506736986;44369.78822964723;44858.77570612126;45260.54739452563;46091.3086303699;46401.76799115983;56393;66310;66397.66801165963;66401;66860.6656966712;9275.62897484093;9352.158305752515;9498.050194980447;9521.76195595088;9526.095020972996;9853.901460985298;9863.950680246553;9892.455914924749;10122.44326562046;10407.68776936693;9214.815703685754;9485.430874147572;9885.11034006914;9932.751681208028;10049.447280399649;8677.392582519164;8769.079246679006;8796.868046979429;9574.138327550554;10007.949960250444;9198.586063627019;9208.907910920805;9429.717108486757;9434.622615095319;9458;7576.62116894142;7686.116096648957;7807.648655810389;8259.21537453941;8508.27679647237;7979.601019948863;8058.758237252891;8433.19884610961;8679.392442528962;8798.296136309207 00:35:27 INFO - PROCESS | 4136 | __end_tp_report 00:35:27 INFO - PROCESS | 4136 | __start_cc_report 00:35:27 INFO - PROCESS | 4136 | _x_x_mozilla_cycle_collect,992 00:35:27 INFO - PROCESS | 4136 | __end_cc_report 00:35:27 INFO - PROCESS | 4136 | __startTimestamp1469086527431__endTimestamp 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | ------- Summary: start ------- 00:35:27 INFO - PROCESS | 4136 | Number of tests: 6 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | [#0] dojo.html Cycles:165 Average:4682.41 Median:4838.10 stddev:498.83 (10.3%) stddev-sans-first:472.01 00:35:27 INFO - PROCESS | 4136 | Values: 2569.3 2584.7 2611.8 2620.2 2627.5 4557.1 4660.0 4726.7 4780.4 4784.8 4715.5 4745.8 4764.3 4772.9 4787.5 4296.0 4327.0 4385.0 4428.6 4434.5 4332.0 4376.0 4385.1 4393.5 4410.4 4227.1 4277.1 4277.1 4302.6 4385.7 4325.7 4346.3 4346.9 4349.1 4405.0 4376.3 4382.0 4394.7 4407.1 4419.6 4500.4 4610.7 4776.6 4834.1 4835.2 4949.3 5061.1 5061.3 5063.2 5064.3 5025.7 5029.3 5083.5 5086.1 5089.1 4524.1 4670.8 4691.0 4754.5 4755.2 5175.3 5202.9 5216.5 5226.6 5227.8 4948.4 4989.2 4996.3 4997.4 5002.8 4845.1 5003.3 5047.6 5058.1 5060.3 5001.5 5055.8 5086.8 5090.5 5095.0 5029.1 5040.7 5041.8 5043.1 5092.0 4054.3 4279.4 4283.6 4285.4 4331.0 3655.0 3702.2 3739.1 3740.8 3788.6 4411.8 4413.0 4418.8 4453.0 4462.2 5071.2 5116.7 5138.3 5139.0 5179.5 4266.5 4357.1 4392.3 4396.8 4397.2 4560.4 4579.2 4584.5 4612.2 4616.8 4812.3 4821.5 4844.2 4846.1 4887.2 4913.3 4987.8 4996.1 5037.2 5127.4 4773.8 4802.5 4805.8 4835.1 4838.1 5002.7 5003.5 5008.2 5010.3 5014.1 4908.6 4979.8 5002.1 5025.2 5114.3 4923.3 4929.5 4929.7 4936.7 4939.9 4895.7 4898.9 4899.3 4903.0 4944.9 4662.8 4868.1 4898.1 4946.3 4976.6 5026.7 5033.7 5037.3 5044.1 5056.4 4927.4 4931.0 4931.0 4931.6 4965.1 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | [#1] ext.html Cycles:180 Average:49842.34 Median:18446.33 stddev:91761.24 (497.4%) stddev-sans-first:91944.03 00:35:27 INFO - PROCESS | 4136 | Values: 553.8 567.3 569.3 582.6 587.3 27967.3 41006.3 42571.1 42683.1 46506.0 42520.1 42793.8 43004.1 43171.4 43185.8 7823.0 7958.3 7960.6 7961.1 7962.9 7612.9 9951.4 10141.4 10275.3 10678.2 10405.5 10480.2 10502.7 10509.3 10517.3 7675.7 10308.6 10339.9 10395.6 10400.4 9907.3 10310.1 10310.5 10661.3 10662.8 39796.0 40143.2 40150.0 40212.2 40227.6 2948.4 2969.6 3004.7 3022.9 3055.7 29487.0 29919.6 29984.7 30308.5 30416.0 3163.9 3277.3 3356.0 3394.9 3457.4 265296.0 265929.0 266791.7 267293.0 267988.0 57772.7 58491.0 58510.4 58637.4 58746.0 33536.7 34688.3 34880.0 37127.8 54627.5 15642.3 15757.6 15818.4 15818.6 15818.8 9944.5 10145.1 12107.6 12137.0 12189.6 18776.2 19267.1 19361.2 19426.1 19475.5 6853.8 6986.9 7089.9 7113.4 7156.7 827.9 874.3 924.7 967.4 1014.3 28209.6 29841.0 30179.4 30310.7 30332.3 954.0 1025.8 1087.8 1090.1 1100.9 15223.0 15660.7 15857.5 15875.2 15921.4 428770.0 430207.0 431569.0 434049.0 434752.0 35571.8 35823.6 35855.6 35929.8 35937.8 1054.8 1164.6 1202.5 1224.1 1228.3 29198.1 30576.8 30946.0 31077.0 31541.8 45175.5 45756.3 45761.0 45797.3 45822.8 324444.0 325940.0 326426.0 326758.0 326765.0 25576.5 26844.7 27052.6 28306.6 28339.6 41443.8 44141.3 45372.5 45637.0 45811.5 38440.8 38447.4 38984.2 41106.4 42687.8 37540.0 38277.4 38619.8 39601.8 40001.6 38037.8 38552.4 38597.2 38852.2 38897.2 30550.8 31188.4 35095.5 36102.6 36220.5 26619.0 26692.5 26785.0 26933.3 27180.0 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | [#2] jquery.html Cycles:180 Average:26248.35 Median:220711.32 stddev:72854.26 (33.0%) stddev-sans-first:73032.70 00:35:27 INFO - PROCESS | 4136 | Values: 360.3 364.8 367.5 379.1 381.4 16231.9 16554.7 17614.7 17750.0 17789.6 16992.2 17201.7 17885.6 17890.8 17907.6 5075.1 5271.6 5275.8 5276.4 5277.7 5120.8 5220.6 5266.6 5267.3 5376.4 5460.2 5467.1 5469.7 5470.1 5472.6 5761.7 5766.5 5904.9 5942.6 5975.9 5250.8 5273.2 5309.7 5340.6 5341.0 20525.6 20650.4 21312.9 21504.9 21534.9 1758.7 1782.7 1875.7 2018.7 2208.3 9214.9 9309.3 9331.0 9585.3 13090.8 1685.8 1716.3 1782.1 1786.4 1831.8 71079.0 71787.3 73003.0 74105.0 76064.6 9918.8 10068.6 10167.8 10395.2 10470.4 14217.1 14233.9 14391.4 14398.9 14400.6 7300.6 7507.7 7577.4 7640.3 7646.7 7105.6 7127.2 7143.3 7334.9 7340.0 4297.3 4323.5 4352.2 4451.6 4481.0 1810.6 1830.1 1842.4 1874.5 1879.5 779.0 825.7 872.4 890.4 956.1 16619.2 16990.9 17620.1 17890.7 18130.5 800.5 821.2 834.7 840.9 899.6 9677.7 9761.2 10721.4 10870.7 11113.4 434160.0 436971.0 447253.0 447253.0 447807.0 53069.5 57831.0 80845.0 86475.6 90531.5 913.5 929.2 959.6 960.2 961.6 16060.5 16091.9 16379.5 17191.7 17396.2 39633.6 39713.8 40934.8 41556.0 42239.2 48265.5 50721.0 56074.0 56944.7 58380.1 7904.2 7959.3 8092.2 8112.8 8169.4 10006.9 12233.3 12293.6 12377.1 12429.8 9883.2 10624.4 10624.8 10723.5 11184.4 10654.5 10674.2 10684.6 10761.8 10911.2 10314.0 10419.0 10626.8 10656.4 11094.9 7775.1 7866.5 7896.1 8248.4 8312.2 9805.5 10746.5 10807.9 10895.3 11132.5 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | [#3] mootools.html Cycles:180 Average:5124.84 Median:4558.65 stddev:2943.12 (64.6%) stddev-sans-first:2936.90 00:35:27 INFO - PROCESS | 4136 | Values: 1241.6 1373.2 1398.2 1455.3 1698.9 6908.1 7631.2 7904.2 7930.2 8172.6 8011.8 8072.1 8076.1 8077.3 8079.8 2237.1 2267.2 2267.6 2270.2 2270.3 2122.1 2189.1 2195.2 2200.6 2203.7 2144.9 2174.3 2203.8 2204.6 2214.2 2154.8 2157.8 2160.7 2161.4 2162.8 2102.2 2143.4 2144.1 2145.3 2149.9 7736.5 7965.3 8006.9 8022.2 8024.3 4553.7 4745.0 4747.9 4757.3 4762.2 2834.3 2974.3 3016.4 3035.2 3044.3 996.2 998.8 1011.2 1012.4 1022.1 10606.3 10657.5 10676.4 10678.5 10696.1 3949.2 4046.1 4101.8 4138.0 4151.9 8132.1 8148.3 8150.3 8158.6 8160.1 4598.6 4602.4 4604.1 4604.8 4607.9 3631.1 3710.8 4225.5 4285.0 4288.2 3449.2 3450.3 3533.5 3556.0 3562.4 996.3 1031.3 1036.1 1038.5 1042.7 679.8 793.3 807.4 823.3 826.1 4949.7 5005.6 5057.6 5125.3 5326.1 397.7 407.2 407.2 411.8 412.7 3266.6 3303.3 3377.9 3379.2 3435.4 9676.9 10051.1 10105.4 10109.2 10123.7 10030.4 10032.9 10039.0 10039.3 10048.5 7504.3 7565.8 7578.1 7580.8 7583.7 4879.3 4976.7 4988.1 5060.1 5201.7 9136.4 9154.5 9161.7 9166.1 9173.3 7978.5 8251.1 9098.7 9212.5 9476.8 6270.7 6517.0 6517.0 6670.7 6672.4 7661.7 7682.2 7690.5 7729.4 7802.3 7013.5 7032.8 7059.8 7080.8 7108.5 6783.5 6879.2 6897.3 6900.6 6924.2 6198.5 6303.3 6305.6 6308.1 6311.5 4286.7 4523.5 4544.2 4547.2 4563.6 4306.4 4466.4 4485.4 4489.1 4508.5 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | [#4] prototype.html Cycles:180 Average:4242.82 Median:4885.77 stddev:1214.99 (24.9%) stddev-sans-first:1181.73 00:35:27 INFO - PROCESS | 4136 | Values: 295.8 298.1 304.5 305.8 307.9 4896.5 4910.9 4920.3 4938.4 4957.8 4841.1 4883.4 4904.1 4913.7 4917.4 3680.7 3764.8 3805.8 3847.3 3869.4 3845.2 3848.6 3855.2 3863.5 3865.2 3822.2 3826.2 3826.7 3867.2 3873.7 3686.3 3724.9 3793.9 3812.4 3842.9 3374.4 3376.4 3387.1 3392.5 3408.6 4759.8 4762.8 4769.3 4771.8 4805.4 4183.9 4351.7 4356.4 4387.8 4395.9 5046.8 5048.1 5061.1 5061.5 5100.8 3715.1 3715.8 3727.9 3737.0 3740.1 5135.8 5327.6 5334.9 5344.7 5368.1 3756.2 3771.3 3798.6 3799.4 3807.5 3852.3 3861.7 3868.0 3869.9 3870.9 4290.6 4358.9 4360.1 4360.7 4360.7 4894.2 5005.5 5028.8 5051.4 5125.4 1320.7 1338.6 1339.1 1355.6 1358.2 837.4 869.4 872.5 880.2 884.1 4309.7 4423.9 4430.6 4447.7 4453.6 5046.5 5135.8 5167.7 5244.8 5256.0 4498.3 4502.9 4513.5 4517.6 4522.3 2664.8 2744.2 2772.0 2778.4 2787.0 4893.6 4905.0 4909.5 4923.7 4926.9 5116.0 5342.5 5366.5 5366.9 5372.9 4867.8 4915.1 4917.0 4924.8 4938.8 5247.6 5248.7 5248.9 5267.1 5268.1 5000.3 5110.5 5129.2 5144.9 5155.5 4881.0 4888.1 4912.5 4917.5 4921.9 3835.8 3844.5 3854.7 3869.0 3880.8 5112.9 5271.0 5284.7 5295.5 5298.3 5036.9 5054.6 5066.7 5109.0 5121.8 5039.0 5069.3 5082.0 5108.9 5112.4 4789.6 4983.7 4997.5 5005.9 5044.5 4867.6 4962.8 5123.5 5141.3 5147.5 4993.2 4998.5 5004.2 5018.4 5021.6 00:35:27 INFO - PROCESS | 4136 | 00:35:27 INFO - PROCESS | 4136 | [#5] yui.html Cycles:180 Average:17437.24 Median:622.29 stddev:25468.04 (4092.6%) stddev-sans-first:25508.15 00:35:27 INFO - PROCESS | 4136 | Values: 612.4 630.2 635.9 714.9 731.1 13153.9 14307.6 14343.7 14349.5 14365.3 13090.1 13261.0 13314.1 13357.5 13366.7 614.4 765.7 776.7 795.3 798.4 782.9 793.5 793.6 793.9 794.2 843.5 844.7 845.6 845.8 846.0 638.8 787.0 811.2 839.4 840.2 600.4 602.5 603.3 604.2 604.6 13325.0 13354.1 13386.7 13401.3 13402.3 12317.9 12399.9 12726.4 12977.1 13166.9 11547.3 11715.7 11848.0 11856.2 11861.1 4057.9 4109.1 4159.2 4248.0 4259.1 60067.0 60159.7 60317.7 60336.4 60591.7 9818.1 10052.6 10366.4 10462.3 10833.3 19216.5 19222.0 19239.7 19297.3 20078.6 6363.3 6644.5 6677.0 6714.7 7030.1 3567.7 3578.9 3590.7 3653.2 3663.6 2874.3 2997.3 2997.9 2998.1 3000.1 482.3 485.1 493.4 495.7 499.3 436.1 484.3 519.6 584.3 589.4 7325.7 7509.7 7543.5 7595.7 7741.8 446.8 485.6 516.2 527.1 551.2 3578.3 3677.1 3796.4 3895.0 3929.5 93863.5 94820.5 95601.0 98506.0 99522.0 81601.6 83903.6 84310.0 86210.0 86906.0 72897.0 74529.3 75243.6 76400.6 77881.9 7296.7 7416.5 7422.6 7450.0 7532.4 44369.8 44858.8 45260.5 46091.3 46401.8 56393.0 66310.0 66397.7 66401.0 66860.7 9275.6 9352.2 9498.1 9521.8 9526.1 9853.9 9864.0 9892.5 10122.4 10407.7 9214.8 9485.4 9885.1 9932.8 10049.4 8677.4 8769.1 8796.9 9574.1 10007.9 9198.6 9208.9 9429.7 9434.6 9458.0 7576.6 7686.1 7807.6 8259.2 8508.3 7979.6 8058.8 8433.2 8679.4 8798.3 00:35:27 INFO - PROCESS | 4136 | -------- Summary: end -------- 00:35:27 INFO - PROCESS | 4136 | 00:35:28 INFO - TEST-INFO | 4136: exit 0 00:35:28 INFO - TEST-OK | dromaeo_css | took 1119139ms 00:35:28 INFO - TEST-START | kraken 00:35:28 INFO - operating with platform_type : linux_ 00:35:28 INFO - Initialising browser for kraken test... 00:35:28 INFO - TEST-INFO | started process 4319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzgDISF/profile http://localhost:33733/getInfo.html) 00:35:32 INFO - PROCESS | 4319 | __metrics Screen width/height:1600/1200 00:35:32 INFO - PROCESS | 4319 | colorDepth:24 00:35:32 INFO - PROCESS | 4319 | Browser inner width/height: 1024/697 00:35:32 INFO - PROCESS | 4319 | __metrics 00:35:33 INFO - PROCESS | 4319 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:35:33 INFO - PROCESS | 4319 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:35:34 INFO - TEST-INFO | 4319: exit 0 00:35:34 INFO - Browser initialized. 00:35:34 INFO - Running cycle 1/1 for kraken test... 00:35:34 INFO - TEST-INFO | started process 4399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzgDISF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:35:46 INFO - PROCESS | 4399 | RSS: Main: 158752768 00:35:46 INFO - PROCESS | 4399 | 00:35:49 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33733/tests/kraken/driver.html?testName=audio-beat-detection) 00:35:50 INFO - PROCESS | 4399 | RSS: Main: 215142400 00:35:50 INFO - PROCESS | 4399 | 00:35:53 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33733/tests/kraken/driver.html?testName=audio-dft) 00:35:53 INFO - PROCESS | 4399 | RSS: Main: 193351680 00:35:53 INFO - PROCESS | 4399 | 00:35:56 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33733/tests/kraken/driver.html?testName=audio-fft) 00:35:57 INFO - PROCESS | 4399 | RSS: Main: 272617472 00:35:57 INFO - PROCESS | 4399 | 00:35:59 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33733/tests/kraken/driver.html?testName=audio-oscillator) 00:36:00 INFO - PROCESS | 4399 | RSS: Main: 181665792 00:36:00 INFO - PROCESS | 4399 | 00:36:02 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33733/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:36:03 INFO - PROCESS | 4399 | RSS: Main: 186781696 00:36:03 INFO - PROCESS | 4399 | 00:36:06 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33733/tests/kraken/driver.html?testName=imaging-darkroom) 00:36:06 INFO - PROCESS | 4399 | RSS: Main: 223244288 00:36:06 INFO - PROCESS | 4399 | 00:36:10 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33733/tests/kraken/driver.html?testName=imaging-desaturate) 00:36:10 INFO - PROCESS | 4399 | RSS: Main: 217219072 00:36:11 INFO - PROCESS | 4399 | 00:36:13 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33733/tests/kraken/driver.html?testName=json-parse-financial) 00:36:14 INFO - PROCESS | 4399 | RSS: Main: 220008448 00:36:14 INFO - PROCESS | 4399 | 00:36:16 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33733/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:36:16 INFO - PROCESS | 4399 | RSS: Main: 186228736 00:36:16 INFO - PROCESS | 4399 | 00:36:18 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:36:19 INFO - PROCESS | 4399 | RSS: Main: 190873600 00:36:19 INFO - PROCESS | 4399 | 00:36:21 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:36:22 INFO - PROCESS | 4399 | RSS: Main: 195407872 00:36:22 INFO - PROCESS | 4399 | 00:36:24 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:36:25 INFO - PROCESS | 4399 | RSS: Main: 191397888 00:36:25 INFO - PROCESS | 4399 | 00:36:28 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:36:28 INFO - PROCESS | 4399 | RSS: Main: 194301952 00:36:28 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | Cycle 1(1): loaded http://localhost:33733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33733/tests/kraken/driver.html?testName=ai-astar) 00:36:30 INFO - PROCESS | 4399 | RSS: Main: 244744192 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | __start_tp_report 00:36:30 INFO - PROCESS | 4399 | _x_x_mozilla_page_load 00:36:30 INFO - PROCESS | 4399 | _x_x_mozilla_page_load_details 00:36:30 INFO - PROCESS | 4399 | |i|pagename|runs| 00:36:30 INFO - PROCESS | 4399 | |0;ai-astar;105;112;113;114;113;113;112;114;113;114 00:36:30 INFO - PROCESS | 4399 | |1;audio-beat-detection;154;130;136;178;124;129;156;124;175;123 00:36:30 INFO - PROCESS | 4399 | |2;audio-dft;161;162;162;160;160;162;165;161;161;161 00:36:30 INFO - PROCESS | 4399 | |3;audio-fft;75;76;75;108;76;76;77;78;99;75 00:36:30 INFO - PROCESS | 4399 | |4;audio-oscillator;86;86;132;84;84;86;120;86;84;125 00:36:30 INFO - PROCESS | 4399 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 00:36:30 INFO - PROCESS | 4399 | |6;imaging-darkroom;146;146;146;147;146;149;146;146;146;147 00:36:30 INFO - PROCESS | 4399 | |7;imaging-desaturate;94;96;95;95;95;95;94;96;94;95 00:36:30 INFO - PROCESS | 4399 | |8;json-parse-financial;64;63;62;63;63;63;64;64;63;64 00:36:30 INFO - PROCESS | 4399 | |9;json-stringify-tinderbox;65;67;65;64;65;64;65;64;66;65 00:36:30 INFO - PROCESS | 4399 | |10;stanford-crypto-aes;82;85;79;80;79;89;81;79;79;86 00:36:30 INFO - PROCESS | 4399 | |11;stanford-crypto-ccm;129;129;151;135;129;128;129;134;167;132 00:36:30 INFO - PROCESS | 4399 | |12;stanford-crypto-pbkdf2;167;162;167;163;166;160;160;161;162;161 00:36:30 INFO - PROCESS | 4399 | |13;stanford-crypto-sha256-iterative;68;73;67;66;67;66;71;65;65;65 00:36:30 INFO - PROCESS | 4399 | __end_tp_report 00:36:30 INFO - PROCESS | 4399 | __start_cc_report 00:36:30 INFO - PROCESS | 4399 | _x_x_mozilla_cycle_collect,1955 00:36:30 INFO - PROCESS | 4399 | __end_cc_report 00:36:30 INFO - PROCESS | 4399 | __startTimestamp1469086590961__endTimestamp 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | ------- Summary: start ------- 00:36:30 INFO - PROCESS | 4399 | Number of tests: 14 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:2.67 (2.4%) stddev-sans-first:0.78 00:36:30 INFO - PROCESS | 4399 | Values: 105.0 112.0 113.0 114.0 113.0 113.0 112.0 114.0 113.0 114.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:145.00 stddev:21.28 (14.7%) stddev-sans-first:22.19 00:36:30 INFO - PROCESS | 4399 | Values: 154.0 130.0 136.0 178.0 124.0 129.0 156.0 124.0 175.0 123.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.43 (0.9%) stddev-sans-first:1.51 00:36:30 INFO - PROCESS | 4399 | Values: 161.0 162.0 162.0 160.0 160.0 162.0 165.0 161.0 161.0 161.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:11.83 (15.6%) stddev-sans-first:12.31 00:36:30 INFO - PROCESS | 4399 | Values: 75.0 76.0 75.0 108.0 76.0 76.0 77.0 78.0 99.0 75.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:85.00 stddev:19.80 (23.3%) stddev-sans-first:20.57 00:36:30 INFO - PROCESS | 4399 | Values: 86.0 86.0 132.0 84.0 84.0 86.0 120.0 86.0 84.0 125.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 00:36:30 INFO - PROCESS | 4399 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 00:36:30 INFO - PROCESS | 4399 | Values: 146.0 146.0 146.0 147.0 146.0 149.0 146.0 146.0 146.0 147.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 00:36:30 INFO - PROCESS | 4399 | Values: 94.0 96.0 95.0 95.0 95.0 95.0 94.0 96.0 94.0 95.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 00:36:30 INFO - PROCESS | 4399 | Values: 64.0 63.0 62.0 63.0 63.0 63.0 64.0 64.0 63.0 64.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.94 (1.5%) stddev-sans-first:1.00 00:36:30 INFO - PROCESS | 4399 | Values: 65.0 67.0 65.0 64.0 65.0 64.0 65.0 64.0 66.0 65.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:81.50 stddev:3.57 (4.4%) stddev-sans-first:3.79 00:36:30 INFO - PROCESS | 4399 | Values: 82.0 85.0 79.0 80.0 79.0 89.0 81.0 79.0 79.0 86.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#11] stanford-crypto-ccm Cycles:10 Average:136.30 Median:133.00 stddev:12.76 (9.6%) stddev-sans-first:13.26 00:36:30 INFO - PROCESS | 4399 | Values: 129.0 129.0 151.0 135.0 129.0 128.0 129.0 134.0 167.0 132.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:162.50 stddev:2.77 (1.7%) stddev-sans-first:2.51 00:36:30 INFO - PROCESS | 4399 | Values: 167.0 162.0 167.0 163.0 166.0 160.0 160.0 161.0 162.0 161.0 00:36:30 INFO - PROCESS | 4399 | 00:36:30 INFO - PROCESS | 4399 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:2.71 (4.0%) stddev-sans-first:2.86 00:36:30 INFO - PROCESS | 4399 | Values: 68.0 73.0 67.0 66.0 67.0 66.0 71.0 65.0 65.0 65.0 00:36:30 INFO - PROCESS | 4399 | -------- Summary: end -------- 00:36:30 INFO - PROCESS | 4399 | 00:36:31 INFO - TEST-INFO | 4399: exit 0 00:36:32 INFO - TEST-OK | kraken | took 63733ms 00:36:32 INFO - SUITE-END | took 1182s 00:36:32 INFO - Completed test suite (00:19:42) 00:36:32 INFO - javascript benchmark 00:36:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2569.2934443028166, 2584.6769153855766, 2611.7649411552957, 2620.240130362195, 2627.5270451318765, 4557.065801510691, 4660.021395506948, 4726.692764970275, 4780.378550788397, 4784.83253086142, 4715.481297057329, 4745.833895813647, 4764.309175169598, 4772.880677983052, 4787.49731278216, 4295.957040429595, 4327.048049429136, 4384.956150438503, 4428.623566996793, 4434.534373890728, 4331.978340108295, 4375.9562404375865, 4385.122975404915, 4393.494748103961, 4410.404595379615, 4227.112306415656, 4277.059047009653, 4277.101808620194, 4302.569743025672, 4385.671074669397, 4325.718828276152, 4346.282863327548, 4346.9347959780625, 4349.086691794727, 4404.955950440487, 4376.256036473807, 4381.9561804381865, 4394.714343567658, 4407.052483715985, 4419.602235798793, 4500.369948207253, 4610.67725259232, 4776.641751868624, 4834.129856625806, 4835.178019736653, 4949.307097006422, 5061.139606266944, 5061.266116413136, 5063.240513922904, 5064.265681476165, 5025.673331233458, 5029.270755740397, 5083.542481176712, 5086.135356989321, 5089.1094058539975, 4524.09518096382, 4670.813167473263, 4691.0, 4754.5245475452175, 4755.215389460769, 5175.301334319819, 5202.92195617066, 5216.478352164753, 5226.5818734501145, 5227.790888364499, 4948.356713627206, 4989.201727723546, 4996.300517927493, 4997.350344455198, 5002.774875130628, 4845.1278769821065, 5003.274525193827, 5047.570956468668, 5058.114829904752, 5060.342155519833, 5001.499850015042, 5055.772490237948, 5086.821961231413, 5090.516400941905, 5094.974525127351, 5029.094762942632, 5040.672356296829, 5041.773120209599, 5043.067032598981, 5092.032513822362, 4054.270231358325, 4279.40088387626, 4283.635890949242, 4285.400043993844, 4331.0, 3655.0131464504966, 3702.185519185775, 3739.121306492972, 3740.8129593520216, 3788.5643151037984, 4411.7573533455325, 4412.955870441318, 4418.80115394808, 4452.977735111369, 4462.219111655448, 5071.163258062452, 5116.73771863425, 5138.254953031789, 5139.0, 5179.507946745053, 4266.5093514246255, 4357.085012147484, 4392.3191905254735, 4396.824127034947, 4397.208502469586, 4560.429946256718, 4579.17574836526, 4584.472785629629, 4612.192866248393, 4616.76916154191, 4812.302216178635, 4821.4696383397795, 4844.224924012212, 4846.127697014571, 4887.242477416006, 4913.263010548446, 4987.775550100182, 4996.125678006407, 5037.219231019198, 5127.4103478099005, 4773.761311934459, 4802.51974802517, 4805.8077676892535, 4835.1296766580945, 4838.104950584153, 5002.749862506933, 5003.524665156804, 5008.223725322581, 5010.317705581507, 5014.122528557561, 4908.631852610997, 4979.800807967786, 5002.124628189979, 5025.221090730943, 5114.284000239895, 4923.286123512143, 4929.507049295185, 4929.679570827885, 4936.679115857458, 4939.925901111558, 4895.7062576245535, 4898.92651610219, 4899.31409602663, 4902.950970490249, 4944.92582611254, 4662.836800711959, 4868.050730107595, 4898.093852637274, 4946.307516947558, 4976.626752993468, 5026.74866256693, 5033.748312584429, 5037.269595908646, 5044.1172794761505, 5056.418511871182, 4927.384076990376, 4930.975345123252, 4931.038447502702, 4931.630127740362, 4965.106280869478], "value": 4649.797270317623, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.7978637797205, 567.3390500067421, 569.2912324156426, 582.6212961574978, 587.2688502813998, 27967.300817479547, 41006.3094321511, 42571.14857702848, 42683.14633707324, 46506.0, 42520.149597008116, 42793.78603106988, 43004.13991720164, 43171.3524297135, 43185.7840710796, 7822.960885195566, 7958.323542498894, 7960.6019699015105, 7961.084475285329, 7962.880556791652, 7612.923870761277, 9951.402915825074, 10141.44222067786, 10275.28073034884, 10678.199135064862, 10405.479726013707, 10480.213983951235, 10502.68491945243, 10509.316894401878, 10517.263791534595, 7675.654595543214, 10308.587656493732, 10339.948300258451, 10395.584176632925, 10400.427976461253, 9907.306488545804, 10310.123639490652, 10310.484475776182, 10661.307015044074, 10662.786744265071, 39796.0, 40143.19713605741, 40150.0, 40212.195756085006, 40227.59772402268, 2948.351362700224, 2969.584258203853, 3004.7145521175453, 3022.9312063808575, 3055.679153688875, 29487.0, 29919.551206731918, 29984.700152998626, 30308.54537181922, 30416.0, 3163.9367212655616, 3277.2626159114247, 3356.0, 3394.8811791587177, 3457.360388328167, 265296.0, 265929.0, 266791.6660416705, 267293.0, 267988.0, 57772.71113644405, 58491.0, 58510.41489585135, 58637.41362586405, 58746.0, 33536.664633353845, 34688.306233875184, 34880.0, 37127.81436092802, 54627.453725463034, 15642.296096675676, 15757.606059848365, 15818.367265309258, 15818.60453488649, 15818.841811581968, 9944.45305508204, 10145.137663298554, 12107.636770896886, 12137.029037676964, 12189.634310970685, 18776.183572463968, 19267.132979015696, 19361.15022604302, 19426.125824337938, 19475.513112172026, 6853.7943861684225, 6986.860262794716, 7089.89365159523, 7113.359797618135, 7156.749513766993, 827.9047909490365, 874.2874557235832, 924.7410724997012, 967.3905439573024, 1014.300132908291, 28209.576856347172, 29841.0, 30179.396412071637, 30310.69689303123, 30332.332282643943, 953.9666111686058, 1025.7640742629087, 1087.782443511301, 1090.1442367741286, 1100.9394483303336, 15223.010504317184, 15660.68678626444, 15857.524274271791, 15875.20623968797, 15921.363145474052, 428770.0, 430207.0, 431569.0, 434049.0, 434752.0, 35571.82214088965, 35823.64176358203, 35855.64144358523, 35929.82035089808, 35937.82031089828, 1054.773223756896, 1164.5807509296646, 1202.4528839378063, 1224.1430998301046, 1228.263042174688, 29198.124056277495, 30576.847115764278, 30946.0, 31077.0, 31541.842290788398, 45175.54824451714, 45756.31365529453, 45761.0, 45797.31304030376, 45822.77088614536, 324444.0, 325940.0, 326426.0, 326758.0, 326765.0, 25576.488470230863, 26844.731552685003, 27052.594211087242, 28306.57540136941, 28339.599248090362, 41443.7927810359, 44141.33787993118, 45372.546274536835, 45637.0, 45811.54188458073, 38440.80779596196, 38447.423288650134, 38984.22031559296, 41106.383404248365, 42687.786561067, 37540.0, 38277.42583861189, 38619.80690096531, 39601.80199098986, 40001.59998400095, 38037.809810951876, 38552.42171367376, 38597.22805543929, 38852.222955540165, 38897.222055558166, 30550.847245763627, 31188.37623247477, 35095.47356789599, 36102.63897361098, 36220.4566931491, 26619.0, 26692.533255270853, 26785.0, 26933.326666833487, 27180.0], "value": 18422.617172616545, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.27944111776446, 364.761081491623, 367.49285985340225, 379.0504785512291, 381.41833703602003, 16231.918840405797, 16554.66890662186, 17614.735778963324, 17750.0, 17789.64420711585, 16992.235349409275, 17201.655966880688, 17885.64228715425, 17890.82109178908, 17907.552311192227, 5075.111855425304, 5271.630985830993, 5275.815346462856, 5276.366835979668, 5277.736113194325, 5120.769565369549, 5220.634555581111, 5266.5523430508265, 5267.262583238331, 5376.354837419515, 5460.208269800876, 5467.097928841737, 5469.699166545838, 5470.070088085035, 5472.644278121909, 5761.68310742911, 5766.452187042224, 5904.881902361929, 5942.64344139353, 5975.880482390371, 5250.7637156328055, 5273.156294992821, 5309.707966061865, 5340.599455040888, 5341.03861304965, 20525.589488210302, 20650.380488585368, 21312.893435532722, 21504.89247553768, 21534.89232553843, 1758.709812880886, 1782.7147656374918, 1875.6998880179242, 2018.7476565429322, 2208.2602328220064, 9214.861777073349, 9309.255259579217, 9330.953345233298, 9585.329026968115, 13090.803637945439, 1685.8398452147026, 1716.3392094043884, 1782.055510579395, 1786.3836976243176, 1831.7893442254178, 71079.0, 71787.28212717858, 73003.0, 74105.01491433651, 76064.61967690181, 9918.80162396748, 10068.597256109673, 10167.796644067077, 10395.16838652906, 10470.424126673106, 14217.075890067112, 14233.85766142346, 14391.352389142514, 14398.85601143996, 14400.567982960529, 7300.598467084255, 7507.662155203029, 7577.393808495307, 7640.274173953466, 7646.694132234759, 7105.644717764091, 7127.180374256923, 7143.321384468467, 7334.926650733424, 7340.0, 4297.290946993773, 4323.459567554056, 4352.238358287287, 4451.643868490513, 4481.0, 1810.5926166612671, 1830.108428682678, 1842.3643842874187, 1874.5032566369923, 1879.539512819368, 779.0, 825.6779855856221, 872.4154816272985, 890.3722875372847, 956.1108169402384, 16619.169041547873, 16990.91504542494, 17620.118994050245, 17890.731639025424, 18130.546736331697, 800.4864724764245, 821.2384435386064, 834.7120243515977, 840.9075001749802, 899.6176624934508, 9677.661281855102, 9761.170300524393, 10721.35671859691, 10870.67387978346, 11113.44432778374, 434160.0, 436971.0, 447253.0, 447253.0, 447807.0, 53069.469305307226, 57831.0, 80845.0, 86475.56762216148, 90531.54734226418, 913.5249670171473, 929.2055292724623, 959.6449313753956, 960.2270172511056, 961.5721004153085, 16060.518184454486, 16091.919540402223, 16379.508614741577, 17191.65616687684, 17396.217170227625, 39633.603663964146, 39713.80143099266, 40934.79532602318, 41556.0, 42239.155216896106, 48265.517344826105, 50721.0, 56074.0, 56944.715276423354, 58380.12429813641, 7904.20957904228, 7959.323457506177, 8092.231238032377, 8112.797180070545, 8169.387295952709, 10006.899931000597, 12233.3271670059, 12293.631191064282, 12377.071719621244, 12429.75140497203, 9883.209343252378, 10624.415657138932, 10624.787504249716, 10723.517441714985, 11184.440777961232, 10654.467276635982, 10674.199435042248, 10684.57261709554, 10761.784764304826, 10911.181661375587, 10314.0, 10419.0, 10626.787464250518, 10656.360618362924, 11094.889051109709, 7775.144734079134, 7866.528008319519, 7896.09194942589, 8248.422610417214, 8312.168783121639, 9805.460699661302, 10746.46267686597, 10807.945960270463, 10895.291806032583, 11132.499037543168], "value": 8481.2880657892, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1241.590275209181, 1373.1966799422337, 1398.1611033379972, 1455.2650911289797, 1698.8725845561578, 6908.101946746922, 7631.198724133969, 7904.249096335853, 7930.246626570473, 8172.63223154958, 8011.839763204733, 8072.112067672566, 8076.111627720946, 8077.273045425911, 8079.798005049876, 2237.1163390460797, 2267.2178098556055, 2267.614505534055, 2270.2281224383705, 2270.3416009357265, 2122.108714339973, 2189.102467988125, 2195.1548653768336, 2200.592890315289, 2203.7025001624816, 2144.9463763405884, 2174.293354659742, 2203.7575866654656, 2204.614192516311, 2214.2471559669707, 2154.806067453929, 2157.8273738100916, 2160.7191065161587, 2161.427221786228, 2162.751283602382, 2102.2326850699537, 2143.431990522513, 2144.142343062772, 2145.324222869807, 2149.881756503392, 7736.535807851547, 7965.2831245187635, 8006.8790369348335, 8022.237887400746, 8024.27781499662, 4553.658475614342, 4744.95255047452, 4747.881302967419, 4757.286407038937, 4762.2380419133115, 2834.291427143214, 2974.3456439583256, 3016.441958237711, 3035.210845180247, 3044.2541577313477, 996.1980605612479, 998.7902540466511, 1011.1708399112731, 1012.4178597306504, 1022.1005515146697, 10606.310589811716, 10657.520411581498, 10676.412797296145, 10678.519466623942, 10696.144308455305, 3949.2101579683604, 4046.069404037088, 4101.753894766323, 4137.97931010343, 4151.875443736692, 8132.146124656826, 8148.307393871469, 8150.2664760171865, 8158.592070396457, 8160.102388737234, 4598.632109431237, 4602.424696912886, 4604.148232576984, 4604.815807367668, 4607.861764147081, 3631.092226943264, 3710.7588006779474, 4225.535191128974, 4285.0, 4288.206681763883, 3449.1721986723505, 3450.275442157125, 3533.4699795030438, 3556.0, 3562.4478205877613, 996.3225006995168, 1031.3038698878227, 1036.1192985961873, 1038.5170895533613, 1042.7028296935337, 679.7654809090856, 793.2622660925394, 807.3500831830432, 823.3413269384469, 826.0954255090666, 4949.7030178189425, 5005.599552035828, 5057.6459647824295, 5125.33370661819, 5326.121190003606, 397.7434554712194, 407.1978202940203, 407.21407683171174, 411.84555791578157, 412.6801728660252, 3266.591676040495, 3303.306305675835, 3377.8648854046055, 3379.1552111972005, 3435.3644575753565, 9676.90323096774, 10051.095401413908, 10105.39367637944, 10109.191264698806, 10123.696289111338, 10030.44832534203, 10032.946540613277, 10038.996100389902, 10039.297249192487, 10048.547815348327, 7504.287092726182, 7565.810854728676, 7578.09062912454, 7580.81047973794, 7583.734569290048, 4879.268109783561, 4976.651634385558, 4988.102141614544, 5060.089183946925, 5201.713905735221, 9136.360454768102, 9154.542272886461, 9161.725148245563, 9166.129217724305, 9173.312001599774, 7978.481398709066, 8251.051129120227, 9098.727038188865, 9212.539373031188, 9476.763080922756, 6270.717817698123, 6516.967415162894, 6517.0, 6670.733170673313, 6672.366125218096, 7661.693532258647, 7682.154962953959, 7690.500117492345, 7729.381649467917, 7802.336801371947, 7013.509054366145, 7032.789016329519, 7059.752908648173, 7080.822979425349, 7108.537945033763, 6783.457323414016, 6879.2432832389395, 6897.2757860423935, 6900.6204658744255, 6924.169099708067, 6198.535109866688, 6303.275123360872, 6305.558610897377, 6308.053791931247, 6311.463525600376, 4286.6784991126415, 4523.502414734311, 4544.204764166324, 4547.204239258051, 4563.6349092073215, 4306.440162778818, 4466.352378905106, 4485.439320084989, 4489.0572979674635, 4508.481524624579], "value": 4029.7363558777442, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.80476885255734, 298.09825278532435, 304.4611038461921, 305.75234060411054, 307.9184016235698, 4896.461389247184, 4910.852674419764, 4920.311156438097, 4938.38270216223, 4957.75211239438, 4841.128596852565, 4883.413990321157, 4904.141775189337, 4913.680610760298, 4917.4099108107075, 3680.6871415929677, 3764.774113553182, 3805.847766089353, 3847.3074846527616, 3869.419587061935, 3845.192509572993, 3848.6151384861428, 3855.1711382052854, 3863.459115723801, 3865.226954609075, 3822.159124992511, 3826.1773718650484, 3826.712996525258, 3867.168558759866, 3873.741034163894, 3686.2996030754207, 3724.88825335239, 3793.886183414502, 3812.3519001769764, 3842.8654997075246, 3374.4432168692138, 3376.4428869236544, 3387.119348969261, 3392.4741665041956, 3408.6080100788477, 4759.809607615691, 4762.809487620491, 4769.260764581495, 4771.761411929389, 4805.357053620494, 4183.874483765492, 4351.673624478145, 4356.43366362374, 4387.824487020515, 4395.934060989088, 5046.772895219724, 5048.1418158913075, 5061.089003979282, 5061.481555533325, 5100.770465329069, 3715.10837399025, 3715.7956312402807, 3727.925441491155, 3736.981315093407, 3740.083679498513, 5135.7674158202135, 5327.627066105374, 5334.866628334284, 5344.6793192408195, 5368.114261146906, 3756.229972855558, 3771.26460340231, 3798.639129282714, 3799.4490798834568, 3807.4669546263544, 3852.2680690668685, 3861.67175790061, 3867.9806600966817, 3869.877735456686, 3870.8838734838, 4290.635295999875, 4358.912821743547, 4360.127974405068, 4360.694751367438, 4360.716553424018, 4894.1891500145175, 5005.474425185376, 5028.84913452597, 5051.408806226027, 5125.410577783528, 1320.6962398648172, 1338.551585218943, 1339.0760375340983, 1355.6407551998727, 1358.2393859438746, 837.3970741066422, 869.4479005831342, 872.4721543466238, 880.2210044111008, 884.0584777212248, 4309.655227581786, 4423.889402764892, 4430.601245887886, 4447.666425018137, 4453.599176074169, 5046.47012063728, 5135.79013116802, 5167.682241028514, 5244.842654720364, 5256.0, 4498.280275155959, 4502.864914052584, 4513.458384993822, 4517.570830771071, 4522.276435770261, 2664.840109593392, 2744.19046381318, 2771.972280277171, 2778.4304217635336, 2786.9966811947684, 4893.559579637849, 4904.975475122602, 4909.509049095133, 4923.704577725348, 4926.9014619707405, 5116.0, 5342.519173274424, 5366.543843773323, 5366.91949620756, 5372.877713303224, 4867.780949857265, 4915.066137433948, 4917.0, 4924.803007879645, 4938.8271410501175, 5247.55395791362, 5248.685078895279, 5248.895022099613, 5267.130923397596, 5268.051750684914, 5000.349954505891, 5110.514501122388, 5129.179331306973, 5144.922826157685, 5155.53600175986, 4880.95119048805, 4888.1180745413985, 4912.533309335608, 4917.532834380728, 4921.8523444296725, 3835.7506762060375, 3844.519435070616, 3854.730168888263, 3868.9806550967064, 3880.84476620932, 5112.923306150336, 5270.973645131749, 5284.656497327662, 5295.549878260237, 5298.337707786527, 5036.924446133237, 5054.646174767731, 5066.695998240118, 5108.974455127701, 5121.820736274212, 5039.0, 5069.290299358019, 5082.0, 5108.948910510847, 5112.437631860566, 4789.568938795525, 4983.6511444200005, 4997.5002499749735, 5005.899882002413, 5044.54599086084, 4867.63492738039, 4962.776675049704, 5123.487651234846, 5141.254518094781, 5147.459516750837, 4993.226049962262, 4998.450170481171, 5004.174311238605, 5018.372703412074, 5021.648484606042], "value": 3856.6289729331816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.3570251236206, 630.2248234671355, 635.9491240700744, 714.9356557909787, 731.0897932074568, 13153.934230328847, 14307.642308942282, 14343.713125737479, 14349.497767578137, 14365.28173591319, 13090.149140305897, 13261.005424593146, 13314.068015238938, 13357.53248636298, 13366.732665346688, 614.3549273263078, 765.7319938021703, 776.7048521561799, 795.316028215734, 798.4450806689354, 782.9177936316662, 793.5040599625235, 793.5595744361876, 793.8650429426982, 794.1542257495778, 843.5487014447272, 844.6536919862857, 845.6490556419079, 845.7885528617845, 846.0101681033206, 638.7668500997149, 786.9685212591489, 811.2333844516875, 839.4166054592062, 840.2437805974641, 600.3756093662586, 602.4577879908118, 603.2700432476709, 604.2175382879182, 604.6039843902252, 13325.0, 13354.131981421177, 13386.665333366647, 13401.329933503383, 13402.262875541841, 12317.876821231852, 12399.93800031003, 12726.427310771038, 12977.091603587753, 13166.934165329209, 11547.307161570248, 11715.7071073223, 11848.0, 11856.170068095236, 11861.051115910792, 4057.9391309130388, 4109.116539943926, 4159.230542349675, 4248.0, 4259.084296876155, 60066.99999999956, 60159.69920150371, 60317.69841150766, 60336.39663603352, 60591.69704151495, 9818.116369526777, 10052.59789608422, 10366.378017318984, 10462.319949203278, 10833.29583577065, 19216.519587010436, 19222.0, 19239.711404328948, 19297.324593639438, 20078.598428031357, 6363.300036995926, 6644.468442524587, 6677.0, 6714.697838597177, 7030.14625410413, 3567.714582833367, 3578.8926332210076, 3590.6947909427467, 3653.178034942117, 3663.6153203913736, 2874.3245337345707, 2997.2806526433933, 2997.9100626981226, 2998.0705981145916, 3000.084974082926, 482.3054801086424, 485.0662474736119, 493.4005183701748, 495.71000964436206, 499.276049727896, 436.07769567364795, 484.2639188433561, 519.6414474012919, 584.2988413903248, 589.4429763873185, 7325.670344834495, 7509.662065207079, 7543.50967187142, 7595.696172153052, 7741.8451630967065, 446.78331009460385, 485.6284942019358, 516.176698166424, 527.0907684244647, 551.2172714745071, 3578.281129301506, 3677.1174918019483, 3796.3925771876366, 3894.980525097413, 3929.4891664083484, 93863.53068234616, 94820.52589737007, 95601.0, 98506.0, 99522.0, 81601.59199203966, 83903.58048209842, 84310.0, 86210.0, 86906.0, 72897.0, 74529.25470745223, 75243.62378188183, 76400.61799690966, 77881.937674051, 7296.7446139384865, 7416.517926334771, 7422.591757453176, 7449.962750186214, 7532.43506736986, 44369.78822964723, 44858.77570612126, 45260.54739452563, 46091.3086303699, 46401.76799115983, 56393.0, 66310.0, 66397.66801165963, 66401.0, 66860.6656966712, 9275.62897484093, 9352.158305752515, 9498.050194980447, 9521.76195595088, 9526.095020972996, 9853.901460985298, 9863.950680246553, 9892.455914924749, 10122.44326562046, 10407.68776936693, 9214.815703685754, 9485.430874147572, 9885.11034006914, 9932.751681208028, 10049.447280399649, 8677.392582519164, 8769.079246679006, 8796.868046979429, 9574.138327550554, 10007.949960250444, 9198.586063627019, 9208.907910920805, 9429.717108486757, 9434.622615095319, 9458.0, 7576.62116894142, 7686.116096648957, 7807.648655810389, 8259.21537453941, 8508.27679647237, 7979.601019948863, 8058.758237252891, 8433.19884610961, 8679.392442528962, 8798.296136309207], "value": 6288.739938029394, "unit": "score"}], "name": "dromaeo_css", "value": 6435.139924010411, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 112.0, 113.0, 114.0, 113.0, 113.0, 112.0, 114.0, 113.0, 114.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 130.0, 136.0, 178.0, 124.0, 129.0, 156.0, 124.0, 175.0, 123.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 160.0, 160.0, 162.0, 165.0, 161.0, 161.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 108.0, 76.0, 76.0, 77.0, 78.0, 99.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 132.0, 84.0, 84.0, 86.0, 120.0, 86.0, 84.0, 125.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 149.0, 146.0, 146.0, 146.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0, 66.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 79.0, 80.0, 79.0, 89.0, 81.0, 79.0, 79.0, 86.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 151.0, 135.0, 129.0, 128.0, 129.0, 134.0, 167.0, 132.0], "value": 136.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 167.0, 163.0, 166.0, 160.0, 160.0, 161.0, 162.0, 161.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 67.0, 66.0, 67.0, 66.0, 71.0, 65.0, 65.0, 65.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1530.5, "alertThreshold": 2.0}]} 00:36:32 INFO - Return code: 0 00:36:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:36:32 INFO - # TBPL SUCCESS # 00:36:32 INFO - Running post-action listener: _resource_record_post_action 00:36:32 INFO - [mozharness: 2016-07-21 07:36:32.241301Z] Finished run-tests step (success) 00:36:32 INFO - Running post-run listener: _resource_record_post_run 00:36:32 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 542605312; Read time: 0; Write time: 426460 00:36:32 INFO - TinderboxPrint: CPU usage
13.0% 00:36:32 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 00:36:32 INFO - TinderboxPrint: I/O write bytes / time
542,605,312 / 426,460 00:36:32 INFO - TinderboxPrint: CPU idle
8,327.3 (87.0%) 00:36:32 INFO - TinderboxPrint: CPU user
1,199.0 (12.5%) 00:36:32 INFO - TinderboxPrint: Swap in / out
0 / 0 00:36:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 254185472; Read time: 0; Write time: 225928 00:36:32 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 287883264; Read time: 0; Write time: 200532 00:36:32 INFO - Running post-run listener: _upload_blobber_files 00:36:32 INFO - Blob upload gear active. 00:36:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:36:32 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 00:36:32 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:36:32 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:36:33 INFO - (blobuploader) - INFO - Open directory for files ... 00:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:36:34 INFO - (blobuploader) - INFO - Done attempting. 00:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:36:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:36:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:36:59 INFO - (blobuploader) - INFO - Done attempting. 00:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:37:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:37:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:37:01 INFO - (blobuploader) - INFO - Done attempting. 00:37:01 INFO - (blobuploader) - INFO - Iteration through files over. 00:37:01 INFO - Return code: 0 00:37:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:37:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:37:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3e05e53d49bc6988803b40b841b36b617cb5c9d1b5e5a54ea9f5a404992d4ea5b7436dab4923acd005c5f21700cb98e3433037b691eefffbb184eb48f4f69c4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d15a5b064fef001ae5617e816a30f4c000e30682760db849ae8f73f313709e2ee4736cdf1ad263c78d9caf95b665a1cd601080b4b07baf2c55e3f18355eda6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd14fce71957de6fddde4821f42f0d2f976e173ac3465f7846f721f5c20bf28ea3380bcb5672f785293762d0abeeb8352f0adb901a61f9018cafeb94745d0d4d"} 00:37:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:37:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:37:01 INFO - Contents: 00:37:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3e05e53d49bc6988803b40b841b36b617cb5c9d1b5e5a54ea9f5a404992d4ea5b7436dab4923acd005c5f21700cb98e3433037b691eefffbb184eb48f4f69c4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d15a5b064fef001ae5617e816a30f4c000e30682760db849ae8f73f313709e2ee4736cdf1ad263c78d9caf95b665a1cd601080b4b07baf2c55e3f18355eda6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd14fce71957de6fddde4821f42f0d2f976e173ac3465f7846f721f5c20bf28ea3380bcb5672f785293762d0abeeb8352f0adb901a61f9018cafeb94745d0d4d"} 00:37:01 INFO - Running post-run listener: copy_logs_to_upload_dir 00:37:01 INFO - Copying logs to upload dir... 00:37:01 INFO - mkdir: /builds/slave/test/build/upload/logs 00:37:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.550913 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-21 00:37:00.821087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 00:37:00.825053) ========= 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=920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3e05e53d49bc6988803b40b841b36b617cb5c9d1b5e5a54ea9f5a404992d4ea5b7436dab4923acd005c5f21700cb98e3433037b691eefffbb184eb48f4f69c4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d15a5b064fef001ae5617e816a30f4c000e30682760db849ae8f73f313709e2ee4736cdf1ad263c78d9caf95b665a1cd601080b4b07baf2c55e3f18355eda6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd14fce71957de6fddde4821f42f0d2f976e173ac3465f7846f721f5c20bf28ea3380bcb5672f785293762d0abeeb8352f0adb901a61f9018cafeb94745d0d4d"} build_url:https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/UVrZYEXqRkGC_tTuDwMxbA/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/Autoland-Non-PGO/sha512/3e05e53d49bc6988803b40b841b36b617cb5c9d1b5e5a54ea9f5a404992d4ea5b7436dab4923acd005c5f21700cb98e3433037b691eefffbb184eb48f4f69c4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d15a5b064fef001ae5617e816a30f4c000e30682760db849ae8f73f313709e2ee4736cdf1ad263c78d9caf95b665a1cd601080b4b07baf2c55e3f18355eda6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd14fce71957de6fddde4821f42f0d2f976e173ac3465f7846f721f5c20bf28ea3380bcb5672f785293762d0abeeb8352f0adb901a61f9018cafeb94745d0d4d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 00:37:00.853928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 00:37:00.854578) ========= 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=920470bf432669ad7e3a5387000001ca-1469085160.760616-1900714767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004615 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 00:37:00.936848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 00:37:00.937163) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 00:37:00.937487) ========= ========= Total master_lag: 0.17 =========