builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1471821230.81 results: success (0) buildid: 20160821133003 builduid: d9771ec68d66488382d1263cef831fc2 revision: f823117225343b73be4df1ee89c904580d66d148 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.810028) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.810404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.810671) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.847796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.848068) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-21 16:13:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.7M=0s 2016-08-21 16:13:51 (40.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.985102) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:50.985377) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019951 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.049899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.050283) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f823117225343b73be4df1ee89c904580d66d148 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f823117225343b73be4df1ee89c904580d66d148 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-21 16:13:51,522 truncating revision to first 12 chars 2016-08-21 16:13:51,523 Setting DEBUG logging. 2016-08-21 16:13:51,523 attempt 1/10 2016-08-21 16:13:51,523 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/f82311722534?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-21 16:13:51,982 unpacking tar archive at: mozilla-inbound-f82311722534/testing/mozharness/ program finished with exit code 0 elapsedTime=0.823161 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.895391) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.895664) ========= script_repo_revision: f823117225343b73be4df1ee89c904580d66d148 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.896055) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.896379) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 16:13:51.917118) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-21 16:13:51.917383) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:13:52 INFO - MultiFileLogger online at 20160821 16:13:52 in /builds/slave/test 16:13:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:13:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:13:52 INFO - {'append_to_log': False, 16:13:52 INFO - 'base_work_dir': '/builds/slave/test', 16:13:52 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:13:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:13:52 INFO - 'branch': 'Mozilla-Inbound', 16:13:52 INFO - 'buildbot_json_path': 'buildprops.json', 16:13:52 INFO - 'config_files': ('talos/linux_config.py',), 16:13:52 INFO - 'default_actions': ('clobber', 16:13:52 INFO - 'read-buildbot-config', 16:13:52 INFO - 'download-and-extract', 16:13:52 INFO - 'populate-webroot', 16:13:52 INFO - 'create-virtualenv', 16:13:52 INFO - 'install', 16:13:52 INFO - 'run-tests'), 16:13:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:13:52 INFO - 'download_minidump_stackwalk': True, 16:13:52 INFO - 'download_symbols': 'ondemand', 16:13:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:13:52 INFO - 'tooltool.py': '/tools/tooltool.py', 16:13:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:13:52 INFO - '/tools/misc-python/virtualenv.py')}, 16:13:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:13:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:13:52 INFO - 'installer_path': 'installer.exe', 16:13:52 INFO - 'log_level': 'info', 16:13:52 INFO - 'log_name': 'talos', 16:13:52 INFO - 'log_to_console': True, 16:13:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:13:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:13:52 INFO - 'opt_config_files': (), 16:13:52 INFO - 'pip_index': False, 16:13:52 INFO - 'sps_profile': False, 16:13:52 INFO - 'sps_profile_interval': 0, 16:13:52 INFO - 'suite': 'dromaeojs', 16:13:52 INFO - 'system_bits': '32', 16:13:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:13:52 INFO - 'title': 'talos-linux64-ix-012', 16:13:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:13:52 INFO - 'use_talos_json': True, 16:13:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:13:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:13:52 INFO - 'work_dir': 'build'} 16:13:52 INFO - [mozharness: 2016-08-21 23:13:52.475050Z] Running clobber step. 16:13:52 INFO - Running pre-action listener: _resource_record_pre_action 16:13:52 INFO - Running main action method: clobber 16:13:52 INFO - rmtree: /builds/slave/test/build 16:13:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:13:53 INFO - Running post-action listener: _resource_record_post_action 16:13:53 INFO - [mozharness: 2016-08-21 23:13:53.442054Z] Finished clobber step (success) 16:13:53 INFO - [mozharness: 2016-08-21 23:13:53.442154Z] Running read-buildbot-config step. 16:13:53 INFO - Running pre-action listener: _resource_record_pre_action 16:13:53 INFO - Running main action method: read_buildbot_config 16:13:53 INFO - Using buildbot properties: 16:13:53 INFO - { 16:13:53 INFO - "project": "", 16:13:53 INFO - "product": "firefox", 16:13:53 INFO - "script_repo_revision": "production", 16:13:53 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:13:53 INFO - "repository": "", 16:13:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:13:53 INFO - "buildid": "20160821133003", 16:13:53 INFO - "pgo_build": "True", 16:13:53 INFO - "basedir": "/builds/slave/test", 16:13:53 INFO - "buildnumber": 1575, 16:13:53 INFO - "slavename": "talos-linux64-ix-012", 16:13:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:13:53 INFO - "platform": "ubuntu64_hw", 16:13:53 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:13:53 INFO - "branch": "mozilla-inbound", 16:13:53 INFO - "repo_path": "integration/mozilla-inbound", 16:13:53 INFO - "revision": "f823117225343b73be4df1ee89c904580d66d148", 16:13:53 INFO - "stage_platform": "linux64-pgo", 16:13:53 INFO - "builduid": "d9771ec68d66488382d1263cef831fc2", 16:13:53 INFO - "slavebuilddir": "test" 16:13:53 INFO - } 16:13:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:13:53 INFO - Running post-action listener: _resource_record_post_action 16:13:53 INFO - [mozharness: 2016-08-21 23:13:53.444601Z] Finished read-buildbot-config step (success) 16:13:53 INFO - [mozharness: 2016-08-21 23:13:53.444688Z] Running download-and-extract step. 16:13:53 INFO - Running pre-action listener: _resource_record_pre_action 16:13:53 INFO - Running main action method: download_and_extract 16:13:53 INFO - mkdir: /builds/slave/test/build/tests 16:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:53 INFO - trying https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:13:53 INFO - Downloading https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:13:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:13:53 INFO - Downloaded 1361 bytes. 16:13:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:13:53 INFO - Using the following test package requirements: 16:13:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:13:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:13:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:13:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'jsshell-linux-x86_64.zip'], 16:13:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:13:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:13:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:13:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:13:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:13:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:13:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:13:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:54 INFO - trying https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:13:54 INFO - Downloading https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:13:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:13:55 INFO - Downloaded 21921208 bytes. 16:13:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 16:13:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:59 INFO - trying https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:13:59 INFO - Downloading https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:13:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:14:00 INFO - Downloaded 21921208 bytes. 16:14:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:04 INFO - trying https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:14:04 INFO - Downloading https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:14:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:14:06 INFO - Downloaded 14413981 bytes. 16:14:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:06 INFO - trying https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:14:06 INFO - Downloading https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:14:08 INFO - Downloaded 59698686 bytes. 16:14:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:14:08 INFO - mkdir: /builds/slave/test/properties 16:14:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:14:08 INFO - Writing to file /builds/slave/test/properties/build_url 16:14:08 INFO - Contents: 16:14:08 INFO - build_url:https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:14:09 INFO - Running post-action listener: _resource_record_post_action 16:14:09 INFO - Running post-action listener: set_extra_try_arguments 16:14:09 INFO - [mozharness: 2016-08-21 23:14:09.467933Z] Finished download-and-extract step (success) 16:14:09 INFO - [mozharness: 2016-08-21 23:14:09.468159Z] Running populate-webroot step. 16:14:09 INFO - Running pre-action listener: _resource_record_pre_action 16:14:09 INFO - Running main action method: populate_webroot 16:14:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:14:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:14:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:14:09 INFO - '--tppagecycles', 16:14:09 INFO - '7'], 16:14:09 INFO - 'tests': ['tsvgm']}}, 16:14:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:14:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:14:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:14:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:14:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['damp', 'tps']}, 16:14:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'tests': ['damp', 'tps']}, 16:14:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['dromaeo_dom']}, 16:14:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:14:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['basic_compositor_video']}, 16:14:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:14:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['a11yr', 16:14:09 INFO - 'ts_paint', 16:14:09 INFO - 'tpaint', 16:14:09 INFO - 'sessionrestore', 16:14:09 INFO - 'sessionrestore_no_auto_restore', 16:14:09 INFO - 'tabpaint']}, 16:14:09 INFO - 'other-e10s': {'tests': ['a11yr', 16:14:09 INFO - 'ts_paint', 16:14:09 INFO - 'tpaint', 16:14:09 INFO - 'sessionrestore', 16:14:09 INFO - 'sessionrestore_no_auto_restore', 16:14:09 INFO - 'tabpaint']}, 16:14:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:14:09 INFO - 'ts_paint', 16:14:09 INFO - 'tpaint', 16:14:09 INFO - 'sessionrestore', 16:14:09 INFO - 'sessionrestore_no_auto_restore', 16:14:09 INFO - 'tabpaint']}, 16:14:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:14:09 INFO - 'ts_paint', 16:14:09 INFO - 'tpaint', 16:14:09 INFO - 'sessionrestore', 16:14:09 INFO - 'sessionrestore_no_auto_restore', 16:14:09 INFO - 'tabpaint']}, 16:14:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['a11yr', 16:14:09 INFO - 'ts_paint', 16:14:09 INFO - 'tpaint', 16:14:09 INFO - 'sessionrestore', 16:14:09 INFO - 'sessionrestore_no_auto_restore', 16:14:09 INFO - 'tabpaint']}, 16:14:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['a11yr', 16:14:09 INFO - 'ts_paint', 16:14:09 INFO - 'tpaint', 16:14:09 INFO - 'sessionrestore', 16:14:09 INFO - 'sessionrestore_no_auto_restore', 16:14:09 INFO - 'tabpaint']}, 16:14:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['tsvgx', 16:14:09 INFO - 'tsvgr_opacity', 16:14:09 INFO - 'tart', 16:14:09 INFO - 'tscrollx', 16:14:09 INFO - 'cart']}, 16:14:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:14:09 INFO - 'tsvgr_opacity', 16:14:09 INFO - 'tart', 16:14:09 INFO - 'tscrollx', 16:14:09 INFO - 'cart']}, 16:14:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'talos_options': ['--disable-e10s'], 16:14:09 INFO - 'tests': ['tp5o']}, 16:14:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'tests': ['tp5o']}, 16:14:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'talos_options': ['--disable-e10s', 16:14:09 INFO - '--xperf_path', 16:14:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:09 INFO - 'tests': ['tp5n']}, 16:14:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:09 INFO - 'talos_options': ['--xperf_path', 16:14:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:09 INFO - 'tests': ['tp5n']}}, 16:14:09 INFO - 'talos.zip': {'path': '', 16:14:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:14:09 INFO - Running post-action listener: _resource_record_post_action 16:14:09 INFO - [mozharness: 2016-08-21 23:14:09.486489Z] Finished populate-webroot step (success) 16:14:09 INFO - [mozharness: 2016-08-21 23:14:09.486577Z] Running create-virtualenv step. 16:14:09 INFO - Running pre-action listener: _resource_record_pre_action 16:14:09 INFO - Running main action method: create_virtualenv 16:14:09 INFO - Creating virtualenv /builds/slave/test/build/venv 16:14:09 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 16:14:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:14:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:14:09 INFO - Using real prefix '/usr' 16:14:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:14:10 INFO - Installing distribute.............................................................................................................................................................................................done. 16:14:12 INFO - Installing pip.................done. 16:14:12 INFO - Return code: 0 16:14:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:12 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:12 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 16:14:12 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 16:14:12 INFO - Using env: {'DISPLAY': ':0', 16:14:12 INFO - 'HOME': '/home/cltbld', 16:14:12 INFO - 'LANG': 'en_US.UTF-8', 16:14:12 INFO - 'LANGUAGE': 'en_US:en', 16:14:12 INFO - 'LOGNAME': 'cltbld', 16:14:12 INFO - 'MAIL': '/var/mail/cltbld', 16:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:12 INFO - 'MOZ_NO_REMOTE': '1', 16:14:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:12 INFO - 'NO_EM_RESTART': '1', 16:14:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:12 INFO - 'PWD': '/builds/slave/test', 16:14:12 INFO - 'SHELL': '/bin/bash', 16:14:12 INFO - 'SHLVL': '1', 16:14:12 INFO - 'TERM': 'linux', 16:14:12 INFO - 'TMOUT': '86400', 16:14:12 INFO - 'USER': 'cltbld', 16:14:12 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:12 INFO - '_': '/tools/buildbot/bin/python'} 16:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:12 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 16:14:12 INFO - Cleaning up... 16:14:12 INFO - Return code: 0 16:14:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:12 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:12 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 16:14:12 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 16:14:12 INFO - Using env: {'DISPLAY': ':0', 16:14:12 INFO - 'HOME': '/home/cltbld', 16:14:12 INFO - 'LANG': 'en_US.UTF-8', 16:14:12 INFO - 'LANGUAGE': 'en_US:en', 16:14:12 INFO - 'LOGNAME': 'cltbld', 16:14:12 INFO - 'MAIL': '/var/mail/cltbld', 16:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:12 INFO - 'MOZ_NO_REMOTE': '1', 16:14:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:12 INFO - 'NO_EM_RESTART': '1', 16:14:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:12 INFO - 'PWD': '/builds/slave/test', 16:14:12 INFO - 'SHELL': '/bin/bash', 16:14:12 INFO - 'SHLVL': '1', 16:14:12 INFO - 'TERM': 'linux', 16:14:12 INFO - 'TMOUT': '86400', 16:14:12 INFO - 'USER': 'cltbld', 16:14:12 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:12 INFO - '_': '/tools/buildbot/bin/python'} 16:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:12 INFO - Downloading/unpacking psutil>=3.1.1 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 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 16:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:14:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:14 INFO - Installing collected packages: psutil 16:14:14 INFO - Running setup.py install for psutil 16:14:14 INFO - building 'psutil._psutil_linux' extension 16:14:14 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 16:14:14 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 16:14:14 INFO - building 'psutil._psutil_posix' extension 16:14:14 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 16:14:14 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 16:14:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:14 INFO - Successfully installed psutil 16:14:14 INFO - Cleaning up... 16:14:15 INFO - Return code: 0 16:14:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:15 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:15 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 16:14:15 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 16:14:15 INFO - Using env: {'DISPLAY': ':0', 16:14:15 INFO - 'HOME': '/home/cltbld', 16:14:15 INFO - 'LANG': 'en_US.UTF-8', 16:14:15 INFO - 'LANGUAGE': 'en_US:en', 16:14:15 INFO - 'LOGNAME': 'cltbld', 16:14:15 INFO - 'MAIL': '/var/mail/cltbld', 16:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:15 INFO - 'MOZ_NO_REMOTE': '1', 16:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:15 INFO - 'NO_EM_RESTART': '1', 16:14:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:15 INFO - 'PWD': '/builds/slave/test', 16:14:15 INFO - 'SHELL': '/bin/bash', 16:14:15 INFO - 'SHLVL': '1', 16:14:15 INFO - 'TERM': 'linux', 16:14:15 INFO - 'TMOUT': '86400', 16:14:15 INFO - 'USER': 'cltbld', 16:14:15 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:15 INFO - '_': '/tools/buildbot/bin/python'} 16:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:14:15 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 16:14:15 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 16:14:15 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 16:14:15 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 16:14:15 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 16:14:15 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 16:14:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:14:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:14:17 INFO - Installing collected packages: mozsystemmonitor 16:14:17 INFO - Running setup.py install for mozsystemmonitor 16:14:17 INFO - Successfully installed mozsystemmonitor 16:14:17 INFO - Cleaning up... 16:14:17 INFO - Return code: 0 16:14:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:14:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:17 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:17 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 16:14:17 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 16:14:17 INFO - Using env: {'DISPLAY': ':0', 16:14:17 INFO - 'HOME': '/home/cltbld', 16:14:17 INFO - 'LANG': 'en_US.UTF-8', 16:14:17 INFO - 'LANGUAGE': 'en_US:en', 16:14:17 INFO - 'LOGNAME': 'cltbld', 16:14:17 INFO - 'MAIL': '/var/mail/cltbld', 16:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:17 INFO - 'MOZ_NO_REMOTE': '1', 16:14:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:17 INFO - 'NO_EM_RESTART': '1', 16:14:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:17 INFO - 'PWD': '/builds/slave/test', 16:14:17 INFO - 'SHELL': '/bin/bash', 16:14:17 INFO - 'SHLVL': '1', 16:14:17 INFO - 'TERM': 'linux', 16:14:17 INFO - 'TMOUT': '86400', 16:14:17 INFO - 'USER': 'cltbld', 16:14:17 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:17 INFO - '_': '/tools/buildbot/bin/python'} 16:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:17 INFO - Downloading/unpacking jsonschema==2.5.1 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:14:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:19 INFO - Downloading functools32-3.2.3-2.tar.gz 16:14:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:14:19 INFO - warning: no files found matching '*.txt' 16:14:19 INFO - no previously-included directories found matching 'build' 16:14:19 INFO - no previously-included directories found matching 'dist' 16:14:19 INFO - no previously-included directories found matching '.git*' 16:14:19 INFO - Installing collected packages: jsonschema, functools32 16:14:19 INFO - Running setup.py install for functools32 16:14:20 INFO - warning: no files found matching '*.txt' 16:14:20 INFO - no previously-included directories found matching 'build' 16:14:20 INFO - no previously-included directories found matching 'dist' 16:14:20 INFO - no previously-included directories found matching '.git*' 16:14:20 INFO - Successfully installed jsonschema functools32 16:14:20 INFO - Cleaning up... 16:14:20 INFO - Return code: 0 16:14:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:14:20 INFO - Using env: {'DISPLAY': ':0', 16:14:20 INFO - 'HOME': '/home/cltbld', 16:14:20 INFO - 'LANG': 'en_US.UTF-8', 16:14:20 INFO - 'LANGUAGE': 'en_US:en', 16:14:20 INFO - 'LOGNAME': 'cltbld', 16:14:20 INFO - 'MAIL': '/var/mail/cltbld', 16:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:20 INFO - 'MOZ_NO_REMOTE': '1', 16:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:20 INFO - 'NO_EM_RESTART': '1', 16:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:20 INFO - 'PWD': '/builds/slave/test', 16:14:20 INFO - 'SHELL': '/bin/bash', 16:14:20 INFO - 'SHLVL': '1', 16:14:20 INFO - 'TERM': 'linux', 16:14:20 INFO - 'TMOUT': '86400', 16:14:20 INFO - 'USER': 'cltbld', 16:14:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:20 INFO - '_': '/tools/buildbot/bin/python'} 16:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:14:20 INFO - Cleaning up... 16:14:20 INFO - Return code: 0 16:14:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:20 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:20 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 16:14:20 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 16:14:20 INFO - Using env: {'DISPLAY': ':0', 16:14:20 INFO - 'HOME': '/home/cltbld', 16:14:20 INFO - 'LANG': 'en_US.UTF-8', 16:14:20 INFO - 'LANGUAGE': 'en_US:en', 16:14:20 INFO - 'LOGNAME': 'cltbld', 16:14:20 INFO - 'MAIL': '/var/mail/cltbld', 16:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:20 INFO - 'MOZ_NO_REMOTE': '1', 16:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:20 INFO - 'NO_EM_RESTART': '1', 16:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:20 INFO - 'PWD': '/builds/slave/test', 16:14:20 INFO - 'SHELL': '/bin/bash', 16:14:20 INFO - 'SHLVL': '1', 16:14:20 INFO - 'TERM': 'linux', 16:14:20 INFO - 'TMOUT': '86400', 16:14:20 INFO - 'USER': 'cltbld', 16:14:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:20 INFO - '_': '/tools/buildbot/bin/python'} 16:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:20 INFO - Downloading/unpacking blobuploader==1.2.4 16:14: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 16:14: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 16:14: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 16:14: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 16:14: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 16:14: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 16:14:22 INFO - Downloading blobuploader-1.2.4.tar.gz 16:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:14:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:14:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:23 INFO - Downloading docopt-0.6.1.tar.gz 16:14:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:14:23 INFO - Installing collected packages: blobuploader, requests, docopt 16:14:23 INFO - Running setup.py install for blobuploader 16:14:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:14:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:14:23 INFO - Running setup.py install for requests 16:14:23 INFO - Running setup.py install for docopt 16:14:23 INFO - Successfully installed blobuploader requests docopt 16:14:23 INFO - Cleaning up... 16:14:23 INFO - Return code: 0 16:14:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:23 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:23 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 16:14:23 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 16:14:23 INFO - Using env: {'DISPLAY': ':0', 16:14:23 INFO - 'HOME': '/home/cltbld', 16:14:23 INFO - 'LANG': 'en_US.UTF-8', 16:14:23 INFO - 'LANGUAGE': 'en_US:en', 16:14:23 INFO - 'LOGNAME': 'cltbld', 16:14:23 INFO - 'MAIL': '/var/mail/cltbld', 16:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:23 INFO - 'MOZ_NO_REMOTE': '1', 16:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:23 INFO - 'NO_EM_RESTART': '1', 16:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:23 INFO - 'PWD': '/builds/slave/test', 16:14:23 INFO - 'SHELL': '/bin/bash', 16:14:23 INFO - 'SHLVL': '1', 16:14:23 INFO - 'TERM': 'linux', 16:14:23 INFO - 'TMOUT': '86400', 16:14:23 INFO - 'USER': 'cltbld', 16:14:23 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:23 INFO - '_': '/tools/buildbot/bin/python'} 16:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:14:24 INFO - Running setup.py (path:/tmp/pip-eEVB7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:14:24 INFO - Running setup.py (path:/tmp/pip-RongGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:14:24 INFO - Running setup.py (path:/tmp/pip-TDQyf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:14:24 INFO - Running setup.py (path:/tmp/pip-obKada-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:14:24 INFO - Running setup.py (path:/tmp/pip-ZSClRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:14:24 INFO - Running setup.py (path:/tmp/pip-IeSu_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:14:24 INFO - Running setup.py (path:/tmp/pip-Rf2tii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:14:24 INFO - Running setup.py (path:/tmp/pip-_vm72k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:14:24 INFO - Running setup.py (path:/tmp/pip-5DjWs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:14:24 INFO - Running setup.py (path:/tmp/pip-fBeENg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:14:24 INFO - Running setup.py (path:/tmp/pip-OP3_eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:14:24 INFO - Running setup.py (path:/tmp/pip-qKmDph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:14:24 INFO - Running setup.py (path:/tmp/pip-e0f38a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:14:24 INFO - Running setup.py (path:/tmp/pip-95UNgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:25 INFO - Running setup.py (path:/tmp/pip-HvKj44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:14:25 INFO - Running setup.py (path:/tmp/pip-yQwlsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:14:25 INFO - Running setup.py (path:/tmp/pip-mJ9Mwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:14:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:14:25 INFO - Running setup.py install for manifestparser 16:14:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:14:25 INFO - Running setup.py install for mozcrash 16:14:25 INFO - Running setup.py install for mozdebug 16:14:25 INFO - Running setup.py install for mozdevice 16:14:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:14:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:14:25 INFO - Running setup.py install for mozfile 16:14:25 INFO - Running setup.py install for mozhttpd 16:14:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:14:25 INFO - Running setup.py install for mozinfo 16:14:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:14:25 INFO - Running setup.py install for mozInstall 16:14:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Running setup.py install for mozleak 16:14:26 INFO - Running setup.py install for mozlog 16:14:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Running setup.py install for moznetwork 16:14:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Running setup.py install for mozprocess 16:14:26 INFO - Running setup.py install for mozprofile 16:14:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Running setup.py install for mozrunner 16:14:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:14:26 INFO - Running setup.py install for mozscreenshot 16:14:26 INFO - Running setup.py install for moztest 16:14:26 INFO - Running setup.py install for mozversion 16:14:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:14:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:14:27 INFO - Cleaning up... 16:14:27 INFO - Return code: 0 16:14:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14: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 16:14: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 16:14:27 INFO - Using env: {'DISPLAY': ':0', 16:14:27 INFO - 'HOME': '/home/cltbld', 16:14:27 INFO - 'LANG': 'en_US.UTF-8', 16:14:27 INFO - 'LANGUAGE': 'en_US:en', 16:14:27 INFO - 'LOGNAME': 'cltbld', 16:14:27 INFO - 'MAIL': '/var/mail/cltbld', 16:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:27 INFO - 'MOZ_NO_REMOTE': '1', 16:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:27 INFO - 'NO_EM_RESTART': '1', 16:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:27 INFO - 'PWD': '/builds/slave/test', 16:14:27 INFO - 'SHELL': '/bin/bash', 16:14:27 INFO - 'SHLVL': '1', 16:14:27 INFO - 'TERM': 'linux', 16:14:27 INFO - 'TMOUT': '86400', 16:14:27 INFO - 'USER': 'cltbld', 16:14:27 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:27 INFO - '_': '/tools/buildbot/bin/python'} 16:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:14:27 INFO - Running setup.py (path:/tmp/pip-2_icAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:14:27 INFO - Running setup.py (path:/tmp/pip-dZaOUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:14:27 INFO - Running setup.py (path:/tmp/pip-dFD_vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:14:27 INFO - Running setup.py (path:/tmp/pip-kHFQEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:14:27 INFO - Running setup.py (path:/tmp/pip-7lfJo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:14:27 INFO - Running setup.py (path:/tmp/pip-KFyES0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:14:27 INFO - Running setup.py (path:/tmp/pip-km9e5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:14:27 INFO - Running setup.py (path:/tmp/pip-pLAzc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:14:27 INFO - Running setup.py (path:/tmp/pip-Q5LyWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:14:27 INFO - Running setup.py (path:/tmp/pip-ojJYrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:14:27 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)) 16:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:14:27 INFO - Running setup.py (path:/tmp/pip-B9XFFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:14: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)) 16:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:14:28 INFO - Running setup.py (path:/tmp/pip-LOZEQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:14: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)) 16:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:14:28 INFO - Running setup.py (path:/tmp/pip-5dXMNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:14:28 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)) 16:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:14:28 INFO - Running setup.py (path:/tmp/pip-519guy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:14:28 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)) 16:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:28 INFO - Running setup.py (path:/tmp/pip-HTM0W2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:28 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)) 16:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:14:28 INFO - Running setup.py (path:/tmp/pip-1jSUq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:14:28 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)) 16:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:14:28 INFO - Running setup.py (path:/tmp/pip-lrKf_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:14:28 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)) 16:14:28 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)) 16:14:28 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)) 16:14:28 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)) 16:14:28 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)) 16:14:28 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)) 16:14:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:30 INFO - Downloading blessings-1.6.tar.gz 16:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:14:30 INFO - Installing collected packages: blessings 16:14:30 INFO - Running setup.py install for blessings 16:14:30 INFO - Successfully installed blessings 16:14:30 INFO - Cleaning up... 16:14:30 INFO - Return code: 0 16:14:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:14:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:14:30 INFO - Reading from file tmpfile_stdout 16:14:30 INFO - Current package versions: 16:14:30 INFO - argparse == 1.2.1 16:14:30 INFO - blessings == 1.6 16:14:30 INFO - blobuploader == 1.2.4 16:14:30 INFO - docopt == 0.6.1 16:14:30 INFO - functools32 == 3.2.3-2 16:14:30 INFO - jsonschema == 2.5.1 16:14:30 INFO - manifestparser == 1.1 16:14:30 INFO - mozInstall == 1.12 16:14:30 INFO - mozcrash == 0.17 16:14:30 INFO - mozdebug == 0.1 16:14:30 INFO - mozdevice == 0.48 16:14:30 INFO - mozfile == 1.2 16:14:30 INFO - mozhttpd == 0.7 16:14:30 INFO - mozinfo == 0.9 16:14:30 INFO - mozleak == 0.1 16:14:30 INFO - mozlog == 3.3 16:14:30 INFO - moznetwork == 0.27 16:14:30 INFO - mozprocess == 0.23 16:14:30 INFO - mozprofile == 0.28 16:14:30 INFO - mozrunner == 6.12 16:14:30 INFO - mozscreenshot == 0.1 16:14:30 INFO - mozsystemmonitor == 0.3 16:14:30 INFO - moztest == 0.7 16:14:30 INFO - mozversion == 1.4 16:14:30 INFO - psutil == 3.1.1 16:14:30 INFO - requests == 1.2.3 16:14:30 INFO - wsgiref == 0.1.2 16:14:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:30 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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14:30 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 16:14:30 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 16:14:30 INFO - Using env: {'DISPLAY': ':0', 16:14:30 INFO - 'HOME': '/home/cltbld', 16:14:30 INFO - 'LANG': 'en_US.UTF-8', 16:14:30 INFO - 'LANGUAGE': 'en_US:en', 16:14:30 INFO - 'LOGNAME': 'cltbld', 16:14:30 INFO - 'MAIL': '/var/mail/cltbld', 16:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:30 INFO - 'MOZ_NO_REMOTE': '1', 16:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:30 INFO - 'NO_EM_RESTART': '1', 16:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:30 INFO - 'PWD': '/builds/slave/test', 16:14:30 INFO - 'SHELL': '/bin/bash', 16:14:30 INFO - 'SHLVL': '1', 16:14:30 INFO - 'TERM': 'linux', 16:14:30 INFO - 'TMOUT': '86400', 16:14:30 INFO - 'USER': 'cltbld', 16:14:30 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:30 INFO - '_': '/tools/buildbot/bin/python'} 16:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14: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)) 16:14:31 INFO - Cleaning up... 16:14:31 INFO - Return code: 0 16:14:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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 0x7fe088fd9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', '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 16:14: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 16:14: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 16:14:31 INFO - Using env: {'DISPLAY': ':0', 16:14:31 INFO - 'HOME': '/home/cltbld', 16:14:31 INFO - 'LANG': 'en_US.UTF-8', 16:14:31 INFO - 'LANGUAGE': 'en_US:en', 16:14:31 INFO - 'LOGNAME': 'cltbld', 16:14:31 INFO - 'MAIL': '/var/mail/cltbld', 16:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:31 INFO - 'MOZ_NO_REMOTE': '1', 16:14:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:31 INFO - 'NO_EM_RESTART': '1', 16:14:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:31 INFO - 'PWD': '/builds/slave/test', 16:14:31 INFO - 'SHELL': '/bin/bash', 16:14:31 INFO - 'SHLVL': '1', 16:14:31 INFO - 'TERM': 'linux', 16:14:31 INFO - 'TMOUT': '86400', 16:14:31 INFO - 'USER': 'cltbld', 16:14:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:31 INFO - '_': '/tools/buildbot/bin/python'} 16:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:14:31 INFO - Cleaning up... 16:14:31 INFO - Return code: 0 16:14:31 INFO - Running post-action listener: _resource_record_post_action 16:14:31 INFO - Running post-action listener: _start_resource_monitoring 16:14:31 INFO - Starting resource monitoring. 16:14:31 INFO - [mozharness: 2016-08-21 23:14:31.323203Z] Finished create-virtualenv step (success) 16:14:31 INFO - [mozharness: 2016-08-21 23:14:31.323785Z] Running install step. 16:14:31 INFO - Running pre-action listener: _resource_record_pre_action 16:14:31 INFO - Running main action method: install 16:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:14:31 INFO - Reading from file tmpfile_stdout 16:14:31 INFO - Detecting whether we're running mozinstall >=1.0... 16:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:14:31 INFO - Reading from file tmpfile_stdout 16:14:31 INFO - Output received: 16:14:31 INFO - Usage: mozinstall [options] installer 16:14:31 INFO - Options: 16:14:31 INFO - -h, --help show this help message and exit 16:14:31 INFO - -d DEST, --destination=DEST 16:14:31 INFO - Directory to install application into. [default: 16:14:31 INFO - "/builds/slave/test"] 16:14:31 INFO - --app=APP Application being installed. [default: firefox] 16:14:31 INFO - mkdir: /builds/slave/test/build/application 16:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:14:48 INFO - Reading from file tmpfile_stdout 16:14:48 INFO - Output received: 16:14:48 INFO - /builds/slave/test/build/application/firefox/firefox 16:14:48 INFO - Running post-action listener: _resource_record_post_action 16:14:48 INFO - [mozharness: 2016-08-21 23:14:48.604263Z] Finished install step (success) 16:14:48 INFO - [mozharness: 2016-08-21 23:14:48.604511Z] Running run-tests step. 16:14:48 INFO - Running pre-action listener: _resource_record_pre_action 16:14:48 INFO - Running main action method: run_tests 16:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:14:48 INFO - Python 2.7.3 16:14:48 INFO - Return code: 0 16:14:48 INFO - grabbing minidump binary from tooltool 16:14: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14: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 0x11b8090>, '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 0x11bb9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11bbe40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:14: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 16:14: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 16:14: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 16:14:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:14:48 INFO - Return code: 0 16:14:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:14:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:14:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:14:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:14:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:14:48 INFO - Using env: {'DISPLAY': ':0', 16:14:48 INFO - 'HOME': '/home/cltbld', 16:14:48 INFO - 'LANG': 'en_US.UTF-8', 16:14:48 INFO - 'LANGUAGE': 'en_US:en', 16:14:48 INFO - 'LOGNAME': 'cltbld', 16:14:48 INFO - 'MAIL': '/var/mail/cltbld', 16:14:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:14:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:48 INFO - 'MOZ_NO_REMOTE': '1', 16:14:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:14:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:48 INFO - 'NO_EM_RESTART': '1', 16:14:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:48 INFO - 'PWD': '/builds/slave/test', 16:14:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:14:48 INFO - 'SHELL': '/bin/bash', 16:14:48 INFO - 'SHLVL': '1', 16:14:48 INFO - 'TERM': 'linux', 16:14:48 INFO - 'TMOUT': '86400', 16:14:48 INFO - 'USER': 'cltbld', 16:14:48 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187', 16:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:48 INFO - '_': '/tools/buildbot/bin/python'} 16:14:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:14:48 INFO - mozversion application_buildid: 20160821133003 16:14:48 INFO - mozversion application_changeset: f823117225343b73be4df1ee89c904580d66d148 16:14:48 INFO - mozversion application_display_name: Nightly 16:14:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:14:48 INFO - mozversion application_name: Firefox 16:14:48 INFO - mozversion application_remotingname: firefox 16:14:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:14:48 INFO - mozversion application_vendor: Mozilla 16:14:48 INFO - mozversion application_version: 51.0a1 16:14:48 INFO - mozversion platform_buildid: 20160821133003 16:14:48 INFO - mozversion platform_changeset: f823117225343b73be4df1ee89c904580d66d148 16:14:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:14:48 INFO - mozversion platform_version: 51.0a1 16:14:48 INFO - using testdate: 1471821288 16:14:48 INFO - actual date: 1471821288 16:14:48 INFO - starting webserver on 'localhost:49958' 16:14:48 INFO - SUITE-START | Running 2 tests 16:14:48 INFO - TEST-START | dromaeo_css 16:14:48 INFO - operating with platform_type : linux_ 16:14:48 INFO - Initialising browser for dromaeo_css test... 16:14:48 INFO - TEST-INFO | started process 14761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeeQQEW/profile http://localhost:49958/getInfo.html) 16:14:52 INFO - PROCESS | 14761 | __metrics Screen width/height:1600/1200 16:14:52 INFO - PROCESS | 14761 | colorDepth:24 16:14:52 INFO - PROCESS | 14761 | Browser inner width/height: 1024/697 16:14:52 INFO - PROCESS | 14761 | __metrics 16:14:53 INFO - PROCESS | 14761 | 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" 16:14:53 INFO - PROCESS | 14761 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:53 INFO - PROCESS | 14761 | console.error: PushService: 16:14:53 INFO - PROCESS | 14761 | stateChangeProcessEnqueue: Error transitioning state 16:14:53 INFO - PROCESS | 14761 | UnknownError 16:14:54 INFO - TEST-INFO | 14761: exit 0 16:14:54 INFO - Browser initialized. 16:14:54 INFO - Running cycle 1/1 for dromaeo_css test... 16:14:54 INFO - TEST-INFO | started process 14842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeeQQEW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:06 INFO - PROCESS | 14842 | RSS: Main: 174563328 16:15:06 INFO - PROCESS | 14842 | 16:15:57 INFO - PROCESS | 14842 | 1471821357666 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] 16:18:06 INFO - PROCESS | 14842 | Cycle 1(1): loaded http://localhost:49958/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49958/tests/dromaeo/cssquery-ext.html) 16:18:07 INFO - PROCESS | 14842 | RSS: Main: 273240064 16:18:07 INFO - PROCESS | 14842 | 16:21:10 INFO - PROCESS | 14842 | Cycle 1(1): loaded http://localhost:49958/tests/dromaeo/cssquery-ext.html (next: http://localhost:49958/tests/dromaeo/cssquery-jquery.html) 16:21:10 INFO - PROCESS | 14842 | RSS: Main: 272699392 16:21:10 INFO - PROCESS | 14842 | 16:24:12 INFO - PROCESS | 14842 | Cycle 1(1): loaded http://localhost:49958/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49958/tests/dromaeo/cssquery-mootools.html) 16:24:13 INFO - PROCESS | 14842 | RSS: Main: 218734592 16:24:13 INFO - PROCESS | 14842 | 16:27:15 INFO - PROCESS | 14842 | Cycle 1(1): loaded http://localhost:49958/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49958/tests/dromaeo/cssquery-prototype.html) 16:27:16 INFO - PROCESS | 14842 | RSS: Main: 246595584 16:27:16 INFO - PROCESS | 14842 | 16:30:18 INFO - PROCESS | 14842 | Cycle 1(1): loaded http://localhost:49958/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49958/tests/dromaeo/cssquery-yui.html) 16:30:19 INFO - PROCESS | 14842 | RSS: Main: 220913664 16:30:19 INFO - PROCESS | 14842 | 16:33:22 INFO - PROCESS | 14842 | Cycle 1(1): loaded http://localhost:49958/tests/dromaeo/cssquery-yui.html (next: http://localhost:49958/tests/dromaeo/cssquery-dojo.html) 16:33:23 INFO - PROCESS | 14842 | RSS: Main: 310697984 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | __start_tp_report 16:33:23 INFO - PROCESS | 14842 | _x_x_mozilla_page_load 16:33:23 INFO - PROCESS | 14842 | _x_x_mozilla_page_load_details 16:33:23 INFO - PROCESS | 14842 | |i|pagename|runs| 16:33:23 INFO - PROCESS | 14842 | |0;dojo.html;2959.9852000739993;2960.5411161270004;2976.6874478179775;2988.0886329669447;3015.2461884528866;5248.947510524894;5283.762230699618;5302.814401495948;5386.084365657838;5414.702191379472;5328.946710532893;5343.545798607123;5377.7580008899695;5384.5692344612435;5424.159255315432;4843.878903027435;4895.534924182215;4918.778654960535;4970.105381031413;4997.50024997501;4961.925571116436;4976.601871850244;4997.700137991714;4998.050370429607;4998.5001499849905;4745.59662428694;4815.927761083586;4822.927656085161;4889.168841296971;4932.876678083041;4793.472718001017;4834.6374021948495;4836.298736683197;4866.7079975201605;4880.316755654211;4793.668400461681;4822.421309442854;4852.126617208901;4860.149473842099;4866.124097662419;5434.3207099112615;5437.8368648940595;5445.50990410861;5446.183072539111;5459.836204913859;5542.140968149944;5551.250581171531;5599.272094627672;5606.21512988182;5807.506361959238;5603.80386686464;5673.773049078073;5677.347105082886;5680.801171959003;5707.086866101446;5320.574354051667;5327.813526526553;5328.76020579071;5330;5338.75975581092;5688.11834165705;5772.04761214395;5820.185174075633;5833.737481813244;5899.911501327483;5651.660900345993;5652.802151924663;5658.63218890779;5658.74535645897;5660.915086273709;5738.569607279471;5742.310922689304;5743.195952566644;5751.051076572318;5751.827445176651;5462.890742185134;5534.335879694462;5572.693501857437;5585;5736.1682556029145;5700.800471983461;5706.771729130871;5711.228984087178;5711.9714401428555;5713.485786279255;4401.383806267125;4423.690341676116;4433.224185767478;4438.778061096932;4443.33349997503;3827.138893748884;3846.2307538492414;3890.688744900401;3914.080191155076;3941.467901833273;5468.781248750085;5469.781208751685;5505.779768809283;5508.55931525477;5512.366077901012;5785.768569257267;5794.420557944172;5801;5807.767689292381;5811.360750317462;5304.734763261821;5355.892882142413;5405.78376864921;5406.162044883049;5516.227728118067;4996.7501624918605;5005.299258103868;5009.148444764399;5018.322526458881;5021.84934451967;5926.822195334147;5938.703064846741;5939.732712027969;5942.64344139353;5951.940480595225;5541.501264886018;5629.437056294338;5664.291963504562;5704.344000439836;5734.5125664319;5930.11048342752;5949.970250148895;5959.67221802805;5965.940340596539;5972.970135149297;5688.288963879515;5691.402402747652;5694;5700.572457065654;5700.8289751305865;5547.528460080777;5654.321481422256;5682.659040457585;5700.087985922232;5710.37185909558;5629.296337957755;5631.831045068655;5633.295838020247;5635.690037048002;5638.661680299195;5619.129034999582;5631.352394474688;5632.408597097409;5635.605507614428;5638.689872057076;5367.731613419392;5433.185022246695;5471.945280547144;5527.585431092603;5550.5282051026115;5668.886622267614;5711.086226203787;5717.37108918027;5726.141078838041;5729.684867332337;5570.136628822539;5585.69278689676;5586.776528938797;5592.356878958972;5592.664440133605 16:33:23 INFO - PROCESS | 14842 | |1;ext.html;577.690935349588;583.5856541855283;596.5138412194059;600.339626410948;610.5542953643838;32966.670333296686;33537;36246.275074498524;42881;44123.117537649225;45634.7718261409;46208.768956155254;46218.76890615542;46262.7686861566;46278.305825412644;8962.775930601738;9100.044495327995;9105.590248438803;9114.817703645924;9119.316051296146;9033;9108.908910910906;9117.863232051524;9127.087291270886;9130.954345228265;9132.817343653123;9173.816523669522;9188.540572971358;9189.816203675957;9191.95404022979;9120.589573469177;9133.132352426508;9165.862512062324;9169.037251088674;9172.8624070639;8692.782680432976;9062.682806101755;9062.909370906273;9070.047644997247;9074;43217.78391108056;43613.56386436127;43722;43722;43776;3532.4877892705413;3758.924821503582;3802.125511132455;3852.345101332752;4120.196561670476;33083.834580827184;33377.3324533508;33440.16399590005;33536.16159596029;33574;4059.3302105152616;4097.651699605537;4108.5275193352845;4156;4220.683448741357;372591;381278.0936095329;381515;381931;382524;56925;57141;57195.14207286894;57281.71359143178;57283.42716572781;60193.69903150456;60611.69694151501;60781.69609152014;60803.39196607978;60868.39131608716;14041.297935103205;14158.07972481806;14168.22074785876;14171.078879872775;14173.645658858612;11641.47613357401;11691.824622630667;11696;11700.06399488039;11706.70733231676;14306.85693143076;15851.524454266391;15863.444779432895;15882.682346353009;16028.11845348517;6030.366811484819;6246.531510136758;6266.624002559861;6308;6334.144890439732;907.5825120444538;1049.7113293844254;1060.1571750458404;1065.9626913058005;1096.6764804382567;28613.28466788347;28782.85608571944;28977.85511072431;29502.704972949996;29528.261793455335;866;1060.8992145746142;1112.1992165640727;1161.3902701082;1168.2639936839737;14868.553943381716;14932;14947.281062677714;15001.249937503082;15005.849941500444;593920;598440;598670;598799;600449;58550.41449585535;59161;59220.11169832456;59344.70327648334;59393.40606593965;1225;1348.8516263211038;1357.592722183341;1376.951806686761;1395.4976208564908;27419.588706169823;28870.566941495475;29442.705572943996;29592.40815183641;29972.85013574918;50670.74664626653;50966;51089.48910510996;51155.23267150921;51161.48838511567;445639;447087;447123;448864;498555;24652;25933;26079.217623471326;26281.73718262793;26329.73670263273;49720;49796.50203497919;50860.74569627128;51402.74298628633;51556;41592.792036039624;41789.37315940324;42332.57667423286;42362.57637423586;44544.33183502315;41295.380569290886;41475.79262103791;41815;43390.34914476222;43979.34030989474;34278.314433710686;35667.46498802572;35878.82060589785;36344.81827590845;38341;34005.82997084999;37478;37619.62380376161;37892.43161352637;38692.6130738689;24931.252062438154;25149;25270.873645631655;25292.204239572457;25586.8720656403 16:33:23 INFO - PROCESS | 14842 | |2;jquery.html;365.18745790615867;369.9038250054985;377.0554760325384;389.54228781182115;412.47821505795173;17470.563235919093;19447.708284375745;19543.70684439731;19739.802601973977;20323.288684896033;19803.504912377197;19928.9003554982;20012.599748005032;20228.08973596188;20565.177392904305;5474.534664553498;5601.019821531236;5671.7164141792955;5828.41715828418;5894.292684877799;5804.854878628026;5818.06910894257;5819.592628516006;5838.941610583904;5853.736581853806;5623.409541998092;5740.483356497914;5781.682007489587;5782.3639399666;5820.457578741637;5702.372738998707;5749.022666146765;5750.05124154514;5753.453421924932;5756.683382413966;4933.7286449245275;5103.668261562986;5134.512221338967;5139.331886854685;5140.408852981917;24259.514809703884;24342.105263157988;24443.755562444327;24512.877435612707;24783.62824557615;2410.5215203913926;2451.5587194305017;2966.5846781450614;3094.226443389153;3123.141136187544;14101.294935253196;14424.783628245585;14443.133411995314;14800.777988330183;14906.925465372604;1860.2187081425834;1931.6329897319465;2006.1975209916004;2019.2326915771912;2035.1248962945926;55570.72214638901;82607.58696206541;83398.58300708518;84927.57536212342;85270.57364713198;10100.848487272697;10232.488375581192;10501.527431265611;10529.947350263199;10548.736281593021;14168.64578385528;14206.644833879029;14246.928765356108;14291.85708142926;14300.85699143016;7665.156832748393;7818.687252509836;7840.6863725451485;8028.397870159762;8081.595920204084;7573.697052117964;7657.655405506765;7838.059432868093;7869.213078692085;7884.960575197088;6366.36336366369;6453.386928241809;6460.903086453707;6527.54307198501;6567.1462709848365;1820.517562845847;1832.7250912362986;1843.1613615804777;1877.164661725519;1887.6130393269348;889.4707648948994;1044.12815299226;1057.9365238085738;1073.3203233900344;1094.1848322999417;17311.48065558035;18968.715469267972;19113.139908704143;19354.419367419;19468.31860884862;998.6554638649654;1019.9592016319413;1020.9846852297221;1048.4286064095086;1080.8108580998294;9966.80066398668;10756.19328550362;10798.946005269923;10808.621698240524;10885.673429797118;475817;479641;481538;484121;492078;83379;84507;86114;86693.56653216694;87286;994.4928086675703;1018.8879223285433;1041.796849614333;1045.6131231444415;1046.340805292676;15975.20123993819;16642;16875.74686379681;16882;17307;42369.576304236565;42531;43316.13367732691;43680.56319436765;44439.77780111079;55069.724651376484;56649;56924;57647.71176144092;57946;8332.541710205754;8383.874241886255;8446.535440550828;8503.160228055804;8809.07504711996;13637.363516378098;13753.38109785042;13845.58463246064;13976.301184940916;14099;10992.395418252074;11071.187626495832;11480.598179063692;11649.417529123679;11655.358955257543;11864.881351186377;11899.167058305835;11954.760904781682;12263.32551709629;12333.93833030829;11026;11100.333979961228;11319.320840749582;11581.884181158417;11724.88275117272;8290.46112002742;8341.708040218806;8475.110113438;8665.740027799176;8913.910860891308;11246;11256.943715281372;11281.887181128084;11414.372209528556;11592.6522204334 16:33:23 INFO - PROCESS | 14842 | |3;mootools.html;1184.4551506307098;1501.7897494350789;1522.2312732070302;1680.5714542791593;1808.9547761305973;8717.389782715212;9137.269018478522;9139.543022848857;9478.81042379152;9519.714408567754;9284.350095493319;9396.71809845704;9422.057794220575;9427.48148851813;9427.858582121273;2146.1415433826514;2429.441228517433;2468.777809997104;2485.6768620079365;2507.5987841945293;2502.1117503286346;2527.494501099778;2537;2539.073238268028;2540.530001949636;2390.2590197038885;2414.1188466209796;2421.225207933462;2456.6069428891383;2461.852288862665;2334.509752951882;2407.5305315463493;2413.2036427978724;2418.4679370538365;2423.7333893271725;2457.840240384387;2473.938151546208;2479.2438306316567;2479.4049428137296;2493.800495960319;9388.483633400161;9571.712848614552;9584.5686944087;9586.328956973015;9631.422114673142;5538.6399884007815;5622.662640241558;5649.293838270216;5719.428057194247;5725.083986562172;3701.722370822199;3741.7006639468777;3783.659470647655;3849.903752406184;3894.7468414552964;1211.1340391620033;1214.8542174938975;1228.4717571444276;1259.5276771210797;1266.83531140952;13090.86909130906;13129.671758206026;13161.802572961413;13236.338183090807;13248.80126798089;4676.8129274828625;4704.647676161919;4799.088173247142;4803.255495398219;4966.428860680996;10084.69745907624;10114.393136411692;10134.898651013396;10164.237682173867;10209.489525523693;5194.506521880416;5199.454057324002;5204.843854684365;5205.6616319939085;5208.713520756319;4368.4102646143;4848.927266091011;4850.563449289581;4897.191963326082;4966.8261610844165;3940.5074365704286;4003.2393845169327;4083.1221287422754;4104.240715467588;4117.423560701504;1197.904167666602;1214.2714371377103;1220.829083928233;1231.7474917641862;1235.6540168752763;957.129012598532;959.4339339789558;968.5835090910836;973.3964941736169;998.6485949362052;5742.770289188469;5871.148683440877;5966.582339236299;6005.399460054047;6027.517798576104;480.3779106057664;480.526681218999;485.48296064690993;491.22386629125896;497.54226111977067;4097.836086556564;4098.426220329156;4098.5286692030795;4116.176764647083;4133.42132101506;11416.771664566828;11760.647180584596;11786.705332366588;11816.350100744541;11850.288982661068;11677.182597218112;11678.24091434054;11695.181337306478;11695.941520292345;11757.823632645517;8113.229243221885;8364.665413383518;8461.069282378934;8465.36509761758;8664.91335086641;5730;5908.940910590839;6022.969885150546;6034.39656034393;6286.27707813589;10675.89324106749;10968.341899486057;10984.945075274893;10994.065504432212;11005.834912476159;10525.263231574023;10799.56801727922;10896.94551527269;10909.39998300101;10945.23383363157;7219.205887352492;7857.489263197874;7967.362610991223;8179.427440079137;8311.501309921425;9160.221381182673;9239;9243.2143267823;9246;9271.907280927375;7804.726834560764;7961.124276329715;8057.556834373931;8114.066882308374;8377.078521362524;7810.179931107379;7987.16134805837;8033.317168040782;8147.226013528705;8246.3815213858;6930.237673855972;6989.895151572832;7041.3310735480045;7047.964760176166;7091.574505529685;5082.771275292546;5237.319148510669;5248.527632513092;5347.598930080182;5431.022415965164;5005.924911126263;5221.6344855859725;5245.213218017328;5303.310569625924;5391.245225668331 16:33:23 INFO - PROCESS | 14842 | |4;prototype.html;316.12275934282366;329.9307145499445;334.1629218806888;335.0885591191957;339.32982359839303;5335.279737235472;5336.092864213083;5415.025295446818;5416.972915135429;5420.262767302512;4968.478309777466;5042.218456139295;5164.457731938149;5183.896322073556;5293.9735301323435;4101.5488296287385;4103.1793641271715;4106.055607210344;4106.8767936961785;4132.752034877917;4090;4094.078832262747;4102.589741025903;4123.773162492251;4126;3834.539855217378;3851.480050193216;4009.1981603679237;4042.8787136385954;4067.1662309226685;4056.14820887611;4067;4074.0629655179177;4076.61272179144;4077.694172937027;3537.435359364466;3561.982190089059;3580.5166302549083;3582.5880023797076;3587.892363229107;4869.537393947569;5091.185410334366;5144.279800827887;5146.819861304836;5152.227165925104;4670.883227919295;4676.321933319649;4678.3216433616935;4678.69588476748;4687.20317546018;5498.285222920995;5501;5519.834404967817;5545.223668686387;5547.667139971614;3718.8326525306156;3882.1265215326325;3968.980155099206;3981.820818063194;3998.4002399639994;5692.402297758758;5694.658320500783;5724.542036637059;5738.569607279471;5747.08046712524;3985.461962635065;3993.5806740292433;3994.4807175067635;4018.537868145142;4039.303492533143;3857.2092720992127;4079.2453396121814;4085.7752823594988;4092.202020606013;4093;4610.7694615269775;4616.399868017136;4625.768711564476;4655.3249778782565;4673.509281525459;5475.67145971243;5483.945160548344;5524.392316845144;5553.416891226444;5587.664740115606;1334.3194970564887;1374.0793668242302;1392.5613431769164;1393.5679939218671;1393.877928267734;870.7126648206204;897.4570384917162;900.4912224593083;905.3888625177979;916.9816603667889;4736.4713288600215;4768.642351823558;4772.665913386099;4773.663374255214;4781.928271075936;4869.902601947942;4891.853244402673;4942.5057494251005;4975.626827987915;4999.700017998932;4314.848980285675;4746.572808447257;4763.332833508244;4766.523347665275;4768.523147685273;2653.482570898695;2664.46875468302;2670.799690023256;2672.318558767502;2678.370582913014;4983.476734942851;5137.768800403991;5161.954446876361;5188.195829646411;5221.843344699665;4983.352164218701;5009.924851127236;5020.974895125574;5043.823466178666;5100.617453690988;5049.873753156127;5058.367704036996;5089.3638295213095;5123.948760512421;5143.331366922276;4770.332153498583;4858.58702010333;4938.604911607134;4976.527229913152;4999.725015124203;4926.3842019747535;4932.778024988956;4942.4316203637045;4959;4987.526185012418;5105.412877519133;5119;5119.0529751996;5121.46881368129;5125.795438072125;3480.3039392121173;3582.194006348551;3604.261126469015;3654.6893514051603;3670.3116566379763;4910.337104490991;4914.287428322945;4919.827806026915;4920.8769780755765;4951.252055109305;4767.451743049455;4780.948191397887;4794.736289503971;4796.592289655418;4866.440359358719;4548.704334218265;4864.586510146536;4866.250568428968;4875.219964805614;4882.487338829372;4762.880927976828;4777.877198858335;4783.617310615073;4786.6410019248;4802.375691160127;4791.1854984652;4837.95162048375;4842.080004799077;4857.077155340474;4881.681945874523;4454.020115574568;4768.785404656729;4803.879903002453;4823.903521929612;4853.427683327842 16:33:23 INFO - PROCESS | 14842 | |5;yui.html;736.7347754808268;758.2947858491603;766.1801871996962;817.1215942861426;889.1775108025075;14098.295085245734;15291.541253762378;15306.387744490221;15308.46420375287;15321.846781532195;15082.472113476031;15347.693046139098;15353.769693454578;15387.461438849643;15397.84602153978;810.1817164663673;813.8819871118684;820.0323618130603;826.5990994367727;829.1873963515758;660.8050625065594;683.4942142814332;685.2907241005536;685.6880119545594;821.322409012564;847.9448835825651;868.1622234543664;870.7126648206076;870.9259712924409;871.1593312453481;872.3936863879607;872.8516152254132;872.8647059705725;873.2708188662443;873.3144481581928;633.012500499218;633.3729607688401;633.5153607490273;633.6134957675814;634.2674211285972;10939.343639381583;14385.640358991004;14396.568102956928;14691.55925322242;14774.26128693561;13405.731885362238;14649.414023439049;14714.264286785618;14784.778228326584;14843.851561484464;11966.64100076999;12084.154109212359;12123.272603643722;12283.385830708428;12378.690532736753;3761.6238376162437;3782.6406491383273;3820.1472285941295;3953.2291203215386;3975.662068724162;85515.57242213811;85787.57106214491;86911.69632455519;87440.5627971856;89477;10748.462576871125;10803.513841877135;10879;10967.890321096687;11140.49867755953;20873;21030;21085.578288434146;21157.153713851272;21196.682049769264;8223.835523289501;8263.586820658944;8315.875261871077;8385.664573416994;8683.478991260545;4537.523560026149;4769.689970151929;4796.664233503621;4889.877753056131;4937.925931111036;3480.3387356402204;3609.873654422083;3636.1091532574687;3646.6535679110443;3653.287608916289;639.9168108145911;644.6647743173523;649.1560970738116;649.3506493506494;671.0571646836203;698.7763915546976;718.5832217313946;720.8846584546552;736.9152547457004;755.4598462099625;9531.809363812685;9678.467684277432;10026.64906728261;10444.373337599769;10503.2122590806;604.3050491934223;616.442119881508;677.50609905159;699.8705239530703;715.3275920634587;4541.886452838639;4834;5012.083325011461;5022.20669694098;5052.368453943257;74298.25701743021;74312.62843685747;75161;75178.62410687911;99611;84988;96839.51580242053;98475;99044.50477747565;99257.50371248242;58239;88444.5577772107;88973;89208.280633696;93970.53014734882;9515.191208747334;9728.902710973083;9836.262280329147;9921.65274215399;10001.799964000826;49620.50379496159;51253;51522;51741.482585175174;52778.73610631922;64054.3594564067;65895;67378.66310668415;72278.63860680663;73853;13746.312684365541;13815.792763108762;13980.510682126076;13992.923027989595;14270.072445291024;14618.926905365404;15465.149416782186;15507.069575825486;15968;15968.361265549713;14180.121566637932;14800.777988329968;14871.256437178314;15057;15062.472813451477;12293.692657683629;12444.315562644142;13154.473821047051;13189.80215296752;13195.406206720529;13307.46770129184;13864.450636942434;14006.089604175697;14474.203918784568;14518.564443066725;9964.701058968243;10029;10217.642382516577;10227.41375801104;10524;9767.413955162712;10356.17150627963;10733.50776485587;10906.672799816019;10957.890421096006 16:33:23 INFO - PROCESS | 14842 | __end_tp_report 16:33:23 INFO - PROCESS | 14842 | __start_cc_report 16:33:23 INFO - PROCESS | 14842 | _x_x_mozilla_cycle_collect,908 16:33:23 INFO - PROCESS | 14842 | __end_cc_report 16:33:23 INFO - PROCESS | 14842 | __startTimestamp1471822403247__endTimestamp 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | ------- Summary: start ------- 16:33:23 INFO - PROCESS | 14842 | Number of tests: 6 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | [#0] dojo.html Cycles:165 Average:5321.76 Median:5542.14 stddev:611.95 (11.0%) stddev-sans-first:585.11 16:33:23 INFO - PROCESS | 14842 | Values: 2960.0 2960.5 2976.7 2988.1 3015.2 5248.9 5283.8 5302.8 5386.1 5414.7 5328.9 5343.5 5377.8 5384.6 5424.2 4843.9 4895.5 4918.8 4970.1 4997.5 4961.9 4976.6 4997.7 4998.1 4998.5 4745.6 4815.9 4822.9 4889.2 4932.9 4793.5 4834.6 4836.3 4866.7 4880.3 4793.7 4822.4 4852.1 4860.1 4866.1 5434.3 5437.8 5445.5 5446.2 5459.8 5542.1 5551.3 5599.3 5606.2 5807.5 5603.8 5673.8 5677.3 5680.8 5707.1 5320.6 5327.8 5328.8 5330.0 5338.8 5688.1 5772.0 5820.2 5833.7 5899.9 5651.7 5652.8 5658.6 5658.7 5660.9 5738.6 5742.3 5743.2 5751.1 5751.8 5462.9 5534.3 5572.7 5585.0 5736.2 5700.8 5706.8 5711.2 5712.0 5713.5 4401.4 4423.7 4433.2 4438.8 4443.3 3827.1 3846.2 3890.7 3914.1 3941.5 5468.8 5469.8 5505.8 5508.6 5512.4 5785.8 5794.4 5801.0 5807.8 5811.4 5304.7 5355.9 5405.8 5406.2 5516.2 4996.8 5005.3 5009.1 5018.3 5021.8 5926.8 5938.7 5939.7 5942.6 5951.9 5541.5 5629.4 5664.3 5704.3 5734.5 5930.1 5950.0 5959.7 5965.9 5973.0 5688.3 5691.4 5694.0 5700.6 5700.8 5547.5 5654.3 5682.7 5700.1 5710.4 5629.3 5631.8 5633.3 5635.7 5638.7 5619.1 5631.4 5632.4 5635.6 5638.7 5367.7 5433.2 5471.9 5527.6 5550.5 5668.9 5711.1 5717.4 5726.1 5729.7 5570.1 5585.7 5586.8 5592.4 5592.7 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | [#1] ext.html Cycles:180 Average:62852.21 Median:23276.63 stddev:129711.98 (557.3%) stddev-sans-first:129991.59 16:33:23 INFO - PROCESS | 14842 | Values: 577.7 583.6 596.5 600.3 610.6 32966.7 33537.0 36246.3 42881.0 44123.1 45634.8 46208.8 46218.8 46262.8 46278.3 8962.8 9100.0 9105.6 9114.8 9119.3 9033.0 9108.9 9117.9 9127.1 9131.0 9132.8 9173.8 9188.5 9189.8 9192.0 9120.6 9133.1 9165.9 9169.0 9172.9 8692.8 9062.7 9062.9 9070.0 9074.0 43217.8 43613.6 43722.0 43722.0 43776.0 3532.5 3758.9 3802.1 3852.3 4120.2 33083.8 33377.3 33440.2 33536.2 33574.0 4059.3 4097.7 4108.5 4156.0 4220.7 372591.0 381278.1 381515.0 381931.0 382524.0 56925.0 57141.0 57195.1 57281.7 57283.4 60193.7 60611.7 60781.7 60803.4 60868.4 14041.3 14158.1 14168.2 14171.1 14173.6 11641.5 11691.8 11696.0 11700.1 11706.7 14306.9 15851.5 15863.4 15882.7 16028.1 6030.4 6246.5 6266.6 6308.0 6334.1 907.6 1049.7 1060.2 1066.0 1096.7 28613.3 28782.9 28977.9 29502.7 29528.3 866.0 1060.9 1112.2 1161.4 1168.3 14868.6 14932.0 14947.3 15001.2 15005.8 593920.0 598440.0 598670.0 598799.0 600449.0 58550.4 59161.0 59220.1 59344.7 59393.4 1225.0 1348.9 1357.6 1377.0 1395.5 27419.6 28870.6 29442.7 29592.4 29972.9 50670.7 50966.0 51089.5 51155.2 51161.5 445639.0 447087.0 447123.0 448864.0 498555.0 24652.0 25933.0 26079.2 26281.7 26329.7 49720.0 49796.5 50860.7 51402.7 51556.0 41592.8 41789.4 42332.6 42362.6 44544.3 41295.4 41475.8 41815.0 43390.3 43979.3 34278.3 35667.5 35878.8 36344.8 38341.0 34005.8 37478.0 37619.6 37892.4 38692.6 24931.3 25149.0 25270.9 25292.2 25586.9 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | [#2] jquery.html Cycles:180 Average:28704.06 Median:13482.22 stddev:79431.09 (589.2%) stddev-sans-first:79625.41 16:33:23 INFO - PROCESS | 14842 | Values: 365.2 369.9 377.1 389.5 412.5 17470.6 19447.7 19543.7 19739.8 20323.3 19803.5 19928.9 20012.6 20228.1 20565.2 5474.5 5601.0 5671.7 5828.4 5894.3 5804.9 5818.1 5819.6 5838.9 5853.7 5623.4 5740.5 5781.7 5782.4 5820.5 5702.4 5749.0 5750.1 5753.5 5756.7 4933.7 5103.7 5134.5 5139.3 5140.4 24259.5 24342.1 24443.8 24512.9 24783.6 2410.5 2451.6 2966.6 3094.2 3123.1 14101.3 14424.8 14443.1 14800.8 14906.9 1860.2 1931.6 2006.2 2019.2 2035.1 55570.7 82607.6 83398.6 84927.6 85270.6 10100.8 10232.5 10501.5 10529.9 10548.7 14168.6 14206.6 14246.9 14291.9 14300.9 7665.2 7818.7 7840.7 8028.4 8081.6 7573.7 7657.7 7838.1 7869.2 7885.0 6366.4 6453.4 6460.9 6527.5 6567.1 1820.5 1832.7 1843.2 1877.2 1887.6 889.5 1044.1 1057.9 1073.3 1094.2 17311.5 18968.7 19113.1 19354.4 19468.3 998.7 1020.0 1021.0 1048.4 1080.8 9966.8 10756.2 10798.9 10808.6 10885.7 475817.0 479641.0 481538.0 484121.0 492078.0 83379.0 84507.0 86114.0 86693.6 87286.0 994.5 1018.9 1041.8 1045.6 1046.3 15975.2 16642.0 16875.7 16882.0 17307.0 42369.6 42531.0 43316.1 43680.6 44439.8 55069.7 56649.0 56924.0 57647.7 57946.0 8332.5 8383.9 8446.5 8503.2 8809.1 13637.4 13753.4 13845.6 13976.3 14099.0 10992.4 11071.2 11480.6 11649.4 11655.4 11864.9 11899.2 11954.8 12263.3 12333.9 11026.0 11100.3 11319.3 11581.9 11724.9 8290.5 8341.7 8475.1 8665.7 8913.9 11246.0 11256.9 11281.9 11414.4 11592.7 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | [#3] mootools.html Cycles:180 Average:6050.23 Median:2641.89 stddev:3533.08 (133.7%) stddev-sans-first:3524.06 16:33:23 INFO - PROCESS | 14842 | Values: 1184.5 1501.8 1522.2 1680.6 1809.0 8717.4 9137.3 9139.5 9478.8 9519.7 9284.4 9396.7 9422.1 9427.5 9427.9 2146.1 2429.4 2468.8 2485.7 2507.6 2502.1 2527.5 2537.0 2539.1 2540.5 2390.3 2414.1 2421.2 2456.6 2461.9 2334.5 2407.5 2413.2 2418.5 2423.7 2457.8 2473.9 2479.2 2479.4 2493.8 9388.5 9571.7 9584.6 9586.3 9631.4 5538.6 5622.7 5649.3 5719.4 5725.1 3701.7 3741.7 3783.7 3849.9 3894.7 1211.1 1214.9 1228.5 1259.5 1266.8 13090.9 13129.7 13161.8 13236.3 13248.8 4676.8 4704.6 4799.1 4803.3 4966.4 10084.7 10114.4 10134.9 10164.2 10209.5 5194.5 5199.5 5204.8 5205.7 5208.7 4368.4 4848.9 4850.6 4897.2 4966.8 3940.5 4003.2 4083.1 4104.2 4117.4 1197.9 1214.3 1220.8 1231.7 1235.7 957.1 959.4 968.6 973.4 998.6 5742.8 5871.1 5966.6 6005.4 6027.5 480.4 480.5 485.5 491.2 497.5 4097.8 4098.4 4098.5 4116.2 4133.4 11416.8 11760.6 11786.7 11816.4 11850.3 11677.2 11678.2 11695.2 11695.9 11757.8 8113.2 8364.7 8461.1 8465.4 8664.9 5730.0 5908.9 6023.0 6034.4 6286.3 10675.9 10968.3 10984.9 10994.1 11005.8 10525.3 10799.6 10896.9 10909.4 10945.2 7219.2 7857.5 7967.4 8179.4 8311.5 9160.2 9239.0 9243.2 9246.0 9271.9 7804.7 7961.1 8057.6 8114.1 8377.1 7810.2 7987.2 8033.3 8147.2 8246.4 6930.2 6989.9 7041.3 7048.0 7091.6 5082.8 5237.3 5248.5 5347.6 5431.0 5005.9 5221.6 5245.2 5303.3 5391.2 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | [#4] prototype.html Cycles:180 Average:4328.87 Median:4792.96 stddev:1224.50 (25.5%) stddev-sans-first:1190.32 16:33:23 INFO - PROCESS | 14842 | Values: 316.1 329.9 334.2 335.1 339.3 5335.3 5336.1 5415.0 5417.0 5420.3 4968.5 5042.2 5164.5 5183.9 5294.0 4101.5 4103.2 4106.1 4106.9 4132.8 4090.0 4094.1 4102.6 4123.8 4126.0 3834.5 3851.5 4009.2 4042.9 4067.2 4056.1 4067.0 4074.1 4076.6 4077.7 3537.4 3562.0 3580.5 3582.6 3587.9 4869.5 5091.2 5144.3 5146.8 5152.2 4670.9 4676.3 4678.3 4678.7 4687.2 5498.3 5501.0 5519.8 5545.2 5547.7 3718.8 3882.1 3969.0 3981.8 3998.4 5692.4 5694.7 5724.5 5738.6 5747.1 3985.5 3993.6 3994.5 4018.5 4039.3 3857.2 4079.2 4085.8 4092.2 4093.0 4610.8 4616.4 4625.8 4655.3 4673.5 5475.7 5483.9 5524.4 5553.4 5587.7 1334.3 1374.1 1392.6 1393.6 1393.9 870.7 897.5 900.5 905.4 917.0 4736.5 4768.6 4772.7 4773.7 4781.9 4869.9 4891.9 4942.5 4975.6 4999.7 4314.8 4746.6 4763.3 4766.5 4768.5 2653.5 2664.5 2670.8 2672.3 2678.4 4983.5 5137.8 5162.0 5188.2 5221.8 4983.4 5009.9 5021.0 5043.8 5100.6 5049.9 5058.4 5089.4 5123.9 5143.3 4770.3 4858.6 4938.6 4976.5 4999.7 4926.4 4932.8 4942.4 4959.0 4987.5 5105.4 5119.0 5119.1 5121.5 5125.8 3480.3 3582.2 3604.3 3654.7 3670.3 4910.3 4914.3 4919.8 4920.9 4951.3 4767.5 4780.9 4794.7 4796.6 4866.4 4548.7 4864.6 4866.3 4875.2 4882.5 4762.9 4777.9 4783.6 4786.6 4802.4 4791.2 4838.0 4842.1 4857.1 4881.7 4454.0 4768.8 4803.9 4823.9 4853.4 16:33:23 INFO - PROCESS | 14842 | 16:33:23 INFO - PROCESS | 14842 | [#5] yui.html Cycles:180 Average:19808.80 Median:5017.15 stddev:27364.87 (545.4%) stddev-sans-first:27404.16 16:33:23 INFO - PROCESS | 14842 | Values: 736.7 758.3 766.2 817.1 889.2 14098.3 15291.5 15306.4 15308.5 15321.8 15082.5 15347.7 15353.8 15387.5 15397.8 810.2 813.9 820.0 826.6 829.2 660.8 683.5 685.3 685.7 821.3 847.9 868.2 870.7 870.9 871.2 872.4 872.9 872.9 873.3 873.3 633.0 633.4 633.5 633.6 634.3 10939.3 14385.6 14396.6 14691.6 14774.3 13405.7 14649.4 14714.3 14784.8 14843.9 11966.6 12084.2 12123.3 12283.4 12378.7 3761.6 3782.6 3820.1 3953.2 3975.7 85515.6 85787.6 86911.7 87440.6 89477.0 10748.5 10803.5 10879.0 10967.9 11140.5 20873.0 21030.0 21085.6 21157.2 21196.7 8223.8 8263.6 8315.9 8385.7 8683.5 4537.5 4769.7 4796.7 4889.9 4937.9 3480.3 3609.9 3636.1 3646.7 3653.3 639.9 644.7 649.2 649.4 671.1 698.8 718.6 720.9 736.9 755.5 9531.8 9678.5 10026.6 10444.4 10503.2 604.3 616.4 677.5 699.9 715.3 4541.9 4834.0 5012.1 5022.2 5052.4 74298.3 74312.6 75161.0 75178.6 99611.0 84988.0 96839.5 98475.0 99044.5 99257.5 58239.0 88444.6 88973.0 89208.3 93970.5 9515.2 9728.9 9836.3 9921.7 10001.8 49620.5 51253.0 51522.0 51741.5 52778.7 64054.4 65895.0 67378.7 72278.6 73853.0 13746.3 13815.8 13980.5 13992.9 14270.1 14618.9 15465.1 15507.1 15968.0 15968.4 14180.1 14800.8 14871.3 15057.0 15062.5 12293.7 12444.3 13154.5 13189.8 13195.4 13307.5 13864.5 14006.1 14474.2 14518.6 9964.7 10029.0 10217.6 10227.4 10524.0 9767.4 10356.2 10733.5 10906.7 10957.9 16:33:23 INFO - PROCESS | 14842 | -------- Summary: end -------- 16:33:23 INFO - PROCESS | 14842 | 16:33:24 INFO - TEST-INFO | 14842: exit 0 16:33:24 INFO - TEST-OK | dromaeo_css | took 1115298ms 16:33:24 INFO - TEST-START | kraken 16:33:24 INFO - operating with platform_type : linux_ 16:33:24 INFO - Initialising browser for kraken test... 16:33:24 INFO - TEST-INFO | started process 15166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPmzlvt/profile http://localhost:49958/getInfo.html) 16:33:27 INFO - PROCESS | 15166 | __metrics Screen width/height:1600/1200 16:33:27 INFO - PROCESS | 15166 | colorDepth:24 16:33:27 INFO - PROCESS | 15166 | Browser inner width/height: 1024/697 16:33:27 INFO - PROCESS | 15166 | __metrics 16:33:28 INFO - PROCESS | 15166 | 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" 16:33:28 INFO - PROCESS | 15166 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:33:28 INFO - PROCESS | 15166 | console.error: PushService: 16:33:28 INFO - PROCESS | 15166 | stateChangeProcessEnqueue: Error transitioning state 16:33:28 INFO - PROCESS | 15166 | UnknownError 16:33:29 INFO - TEST-INFO | 15166: exit 0 16:33:29 INFO - Browser initialized. 16:33:29 INFO - Running cycle 1/1 for kraken test... 16:33:29 INFO - TEST-INFO | started process 15247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPmzlvt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:41 INFO - PROCESS | 15247 | RSS: Main: 174370816 16:33:41 INFO - PROCESS | 15247 | 16:33:44 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49958/tests/kraken/driver.html?testName=audio-beat-detection) 16:33:44 INFO - PROCESS | 15247 | RSS: Main: 235237376 16:33:44 INFO - PROCESS | 15247 | 16:33:47 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49958/tests/kraken/driver.html?testName=audio-dft) 16:33:48 INFO - PROCESS | 15247 | RSS: Main: 226230272 16:33:48 INFO - PROCESS | 15247 | 16:33:51 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49958/tests/kraken/driver.html?testName=audio-fft) 16:33:51 INFO - PROCESS | 15247 | RSS: Main: 301527040 16:33:51 INFO - PROCESS | 15247 | 16:33:54 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49958/tests/kraken/driver.html?testName=audio-oscillator) 16:33:54 INFO - PROCESS | 15247 | RSS: Main: 209813504 16:33:54 INFO - PROCESS | 15247 | 16:33:56 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49958/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:33:57 INFO - PROCESS | 15247 | RSS: Main: 213979136 16:33:57 INFO - PROCESS | 15247 | 16:34:00 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49958/tests/kraken/driver.html?testName=imaging-darkroom) 16:34:01 INFO - PROCESS | 15247 | RSS: Main: 250089472 16:34:01 INFO - PROCESS | 15247 | 16:34:04 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49958/tests/kraken/driver.html?testName=imaging-desaturate) 16:34:05 INFO - PROCESS | 15247 | RSS: Main: 249065472 16:34:05 INFO - PROCESS | 15247 | 16:34:08 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49958/tests/kraken/driver.html?testName=json-parse-financial) 16:34:08 INFO - PROCESS | 15247 | RSS: Main: 249151488 16:34:08 INFO - PROCESS | 15247 | 16:34:10 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49958/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:34:10 INFO - PROCESS | 15247 | RSS: Main: 211517440 16:34:10 INFO - PROCESS | 15247 | 16:34:12 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:34:13 INFO - PROCESS | 15247 | RSS: Main: 218435584 16:34:13 INFO - PROCESS | 15247 | 16:34:15 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:34:16 INFO - PROCESS | 15247 | RSS: Main: 224296960 16:34:16 INFO - PROCESS | 15247 | 16:34:18 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:34:19 INFO - PROCESS | 15247 | RSS: Main: 219017216 16:34:19 INFO - PROCESS | 15247 | 16:34:22 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:34:22 INFO - PROCESS | 15247 | RSS: Main: 221605888 16:34:22 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | Cycle 1(1): loaded http://localhost:49958/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49958/tests/kraken/driver.html?testName=ai-astar) 16:34:24 INFO - PROCESS | 15247 | RSS: Main: 272936960 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | __start_tp_report 16:34:24 INFO - PROCESS | 15247 | _x_x_mozilla_page_load 16:34:24 INFO - PROCESS | 15247 | _x_x_mozilla_page_load_details 16:34:24 INFO - PROCESS | 15247 | |i|pagename|runs| 16:34:24 INFO - PROCESS | 15247 | |0;ai-astar;98;110;107;111;109;110;115;111;111;110 16:34:24 INFO - PROCESS | 15247 | |1;audio-beat-detection;143;118;145;194;120;150;157;120;172;123 16:34:24 INFO - PROCESS | 15247 | |2;audio-dft;160;160;160;160;158;159;160;161;163;161 16:34:24 INFO - PROCESS | 15247 | |3;audio-fft;75;76;76;167;76;77;75;76;157;76 16:34:24 INFO - PROCESS | 15247 | |4;audio-oscillator;85;84;160;85;84;85;147;84;86;145 16:34:24 INFO - PROCESS | 15247 | |5;imaging-gaussian-blur;117;117;118;118;117;118;117;117;118;117 16:34:24 INFO - PROCESS | 15247 | |6;imaging-darkroom;148;146;146;146;145;146;145;146;145;146 16:34:24 INFO - PROCESS | 15247 | |7;imaging-desaturate;95;96;96;95;95;96;97;95;95;96 16:34:24 INFO - PROCESS | 15247 | |8;json-parse-financial;50;50;52;51;52;52;51;52;51;51 16:34:24 INFO - PROCESS | 15247 | |9;json-stringify-tinderbox;62;63;62;62;62;67;63;61;63;61 16:34:24 INFO - PROCESS | 15247 | |10;stanford-crypto-aes;79;79;79;79;81;78;80;80;78;79 16:34:24 INFO - PROCESS | 15247 | |11;stanford-crypto-ccm;143;134;123;162;124;130;127;127;140;126 16:34:24 INFO - PROCESS | 15247 | |12;stanford-crypto-pbkdf2;165;164;162;161;163;165;161;163;164;164 16:34:24 INFO - PROCESS | 15247 | |13;stanford-crypto-sha256-iterative;68;67;64;66;65;65;63;65;65;64 16:34:24 INFO - PROCESS | 15247 | __end_tp_report 16:34:24 INFO - PROCESS | 15247 | __start_cc_report 16:34:24 INFO - PROCESS | 15247 | _x_x_mozilla_cycle_collect,1745 16:34:24 INFO - PROCESS | 15247 | __end_cc_report 16:34:24 INFO - PROCESS | 15247 | __startTimestamp1471822464895__endTimestamp 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | ------- Summary: start ------- 16:34:24 INFO - PROCESS | 15247 | Number of tests: 14 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#0] ai-astar Cycles:10 Average:109.20 Median:111.00 stddev:4.42 (4.0%) stddev-sans-first:2.13 16:34:24 INFO - PROCESS | 15247 | Values: 98.0 110.0 107.0 111.0 109.0 110.0 115.0 111.0 111.0 110.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:147.50 stddev:25.30 (17.2%) stddev-sans-first:26.83 16:34:24 INFO - PROCESS | 15247 | Values: 143.0 118.0 145.0 194.0 120.0 150.0 157.0 120.0 172.0 123.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.32 (0.8%) stddev-sans-first:1.39 16:34:24 INFO - PROCESS | 15247 | Values: 160.0 160.0 160.0 160.0 158.0 159.0 160.0 161.0 163.0 161.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#3] audio-fft Cycles:10 Average:93.10 Median:76.00 stddev:36.39 (47.9%) stddev-sans-first:38.01 16:34:24 INFO - PROCESS | 15247 | Values: 75.0 76.0 76.0 167.0 76.0 77.0 75.0 76.0 157.0 76.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:84.50 stddev:32.09 (38.0%) stddev-sans-first:33.26 16:34:24 INFO - PROCESS | 15247 | Values: 85.0 84.0 160.0 85.0 84.0 85.0 147.0 84.0 86.0 145.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:34:24 INFO - PROCESS | 15247 | Values: 117.0 117.0 118.0 118.0 117.0 118.0 117.0 117.0 118.0 117.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.50 16:34:24 INFO - PROCESS | 15247 | Values: 148.0 146.0 146.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 16:34:24 INFO - PROCESS | 15247 | Values: 95.0 96.0 96.0 95.0 95.0 96.0 97.0 95.0 95.0 96.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:0.79 (1.5%) stddev-sans-first:0.71 16:34:24 INFO - PROCESS | 15247 | Values: 50.0 50.0 52.0 51.0 52.0 52.0 51.0 52.0 51.0 51.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:62.50 stddev:1.71 (2.7%) stddev-sans-first:1.80 16:34:24 INFO - PROCESS | 15247 | Values: 62.0 63.0 62.0 62.0 62.0 67.0 63.0 61.0 63.0 61.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:0.92 (1.2%) stddev-sans-first:0.97 16:34:24 INFO - PROCESS | 15247 | Values: 79.0 79.0 79.0 79.0 81.0 78.0 80.0 80.0 78.0 79.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:132.00 stddev:12.01 (9.1%) stddev-sans-first:12.25 16:34:24 INFO - PROCESS | 15247 | Values: 143.0 134.0 123.0 162.0 124.0 130.0 127.0 127.0 140.0 126.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:164.00 stddev:1.48 (0.9%) stddev-sans-first:1.41 16:34:24 INFO - PROCESS | 15247 | Values: 165.0 164.0 162.0 161.0 163.0 165.0 161.0 163.0 164.0 164.0 16:34:24 INFO - PROCESS | 15247 | 16:34:24 INFO - PROCESS | 15247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.48 (2.3%) stddev-sans-first:1.17 16:34:24 INFO - PROCESS | 15247 | Values: 68.0 67.0 64.0 66.0 65.0 65.0 63.0 65.0 65.0 64.0 16:34:24 INFO - PROCESS | 15247 | -------- Summary: end -------- 16:34:24 INFO - PROCESS | 15247 | 16:34:26 INFO - TEST-INFO | 15247: exit 0 16:34:26 INFO - TEST-OK | kraken | took 61917ms 16:34:26 INFO - SUITE-END | took 1177s 16:34:26 INFO - Completed test suite (00:19:37) 16:34:26 INFO - javascript benchmark 16:34:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2959.9852000739993, 2960.5411161270004, 2976.6874478179775, 2988.0886329669447, 3015.2461884528866, 5248.947510524894, 5283.762230699618, 5302.814401495948, 5386.084365657838, 5414.702191379472, 5328.946710532893, 5343.545798607123, 5377.7580008899695, 5384.5692344612435, 5424.159255315432, 4843.878903027435, 4895.534924182215, 4918.778654960535, 4970.105381031413, 4997.50024997501, 4961.925571116436, 4976.601871850244, 4997.700137991714, 4998.050370429607, 4998.5001499849905, 4745.59662428694, 4815.927761083586, 4822.927656085161, 4889.168841296971, 4932.876678083041, 4793.472718001017, 4834.6374021948495, 4836.298736683197, 4866.7079975201605, 4880.316755654211, 4793.668400461681, 4822.421309442854, 4852.126617208901, 4860.149473842099, 4866.124097662419, 5434.3207099112615, 5437.8368648940595, 5445.50990410861, 5446.183072539111, 5459.836204913859, 5542.140968149944, 5551.250581171531, 5599.272094627672, 5606.21512988182, 5807.506361959238, 5603.80386686464, 5673.773049078073, 5677.347105082886, 5680.801171959003, 5707.086866101446, 5320.574354051667, 5327.813526526553, 5328.76020579071, 5330.0, 5338.75975581092, 5688.11834165705, 5772.04761214395, 5820.185174075633, 5833.737481813244, 5899.911501327483, 5651.660900345993, 5652.802151924663, 5658.63218890779, 5658.74535645897, 5660.915086273709, 5738.569607279471, 5742.310922689304, 5743.195952566644, 5751.051076572318, 5751.827445176651, 5462.890742185134, 5534.335879694462, 5572.693501857437, 5585.0, 5736.1682556029145, 5700.800471983461, 5706.771729130871, 5711.228984087178, 5711.9714401428555, 5713.485786279255, 4401.383806267125, 4423.690341676116, 4433.224185767478, 4438.778061096932, 4443.33349997503, 3827.138893748884, 3846.2307538492414, 3890.688744900401, 3914.080191155076, 3941.467901833273, 5468.781248750085, 5469.781208751685, 5505.779768809283, 5508.55931525477, 5512.366077901012, 5785.768569257267, 5794.420557944172, 5801.0, 5807.767689292381, 5811.360750317462, 5304.734763261821, 5355.892882142413, 5405.78376864921, 5406.162044883049, 5516.227728118067, 4996.7501624918605, 5005.299258103868, 5009.148444764399, 5018.322526458881, 5021.84934451967, 5926.822195334147, 5938.703064846741, 5939.732712027969, 5942.64344139353, 5951.940480595225, 5541.501264886018, 5629.437056294338, 5664.291963504562, 5704.344000439836, 5734.5125664319, 5930.11048342752, 5949.970250148895, 5959.67221802805, 5965.940340596539, 5972.970135149297, 5688.288963879515, 5691.402402747652, 5694.0, 5700.572457065654, 5700.8289751305865, 5547.528460080777, 5654.321481422256, 5682.659040457585, 5700.087985922232, 5710.37185909558, 5629.296337957755, 5631.831045068655, 5633.295838020247, 5635.690037048002, 5638.661680299195, 5619.129034999582, 5631.352394474688, 5632.408597097409, 5635.605507614428, 5638.689872057076, 5367.731613419392, 5433.185022246695, 5471.945280547144, 5527.585431092603, 5550.5282051026115, 5668.886622267614, 5711.086226203787, 5717.37108918027, 5726.141078838041, 5729.684867332337, 5570.136628822539, 5585.69278689676, 5586.776528938797, 5592.356878958972, 5592.664440133605], "value": 5278.817956942784, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.690935349588, 583.5856541855283, 596.5138412194059, 600.339626410948, 610.5542953643838, 32966.670333296686, 33537.0, 36246.275074498524, 42881.0, 44123.117537649225, 45634.7718261409, 46208.768956155254, 46218.76890615542, 46262.7686861566, 46278.305825412644, 8962.775930601738, 9100.044495327995, 9105.590248438803, 9114.817703645924, 9119.316051296146, 9033.0, 9108.908910910906, 9117.863232051524, 9127.087291270886, 9130.954345228265, 9132.817343653123, 9173.816523669522, 9188.540572971358, 9189.816203675957, 9191.95404022979, 9120.589573469177, 9133.132352426508, 9165.862512062324, 9169.037251088674, 9172.8624070639, 8692.782680432976, 9062.682806101755, 9062.909370906273, 9070.047644997247, 9074.0, 43217.78391108056, 43613.56386436127, 43722.0, 43722.0, 43776.0, 3532.4877892705413, 3758.924821503582, 3802.125511132455, 3852.345101332752, 4120.196561670476, 33083.834580827184, 33377.3324533508, 33440.16399590005, 33536.16159596029, 33574.0, 4059.3302105152616, 4097.651699605537, 4108.5275193352845, 4156.0, 4220.683448741357, 372591.0, 381278.0936095329, 381515.0, 381931.0, 382524.0, 56925.0, 57141.0, 57195.14207286894, 57281.71359143178, 57283.42716572781, 60193.69903150456, 60611.69694151501, 60781.69609152014, 60803.39196607978, 60868.39131608716, 14041.297935103205, 14158.07972481806, 14168.22074785876, 14171.078879872775, 14173.645658858612, 11641.47613357401, 11691.824622630667, 11696.0, 11700.06399488039, 11706.70733231676, 14306.85693143076, 15851.524454266391, 15863.444779432895, 15882.682346353009, 16028.11845348517, 6030.366811484819, 6246.531510136758, 6266.624002559861, 6308.0, 6334.144890439732, 907.5825120444538, 1049.7113293844254, 1060.1571750458404, 1065.9626913058005, 1096.6764804382567, 28613.28466788347, 28782.85608571944, 28977.85511072431, 29502.704972949996, 29528.261793455335, 866.0, 1060.8992145746142, 1112.1992165640727, 1161.3902701082, 1168.2639936839737, 14868.553943381716, 14932.0, 14947.281062677714, 15001.249937503082, 15005.849941500444, 593920.0, 598440.0, 598670.0, 598799.0, 600449.0, 58550.41449585535, 59161.0, 59220.11169832456, 59344.70327648334, 59393.40606593965, 1225.0, 1348.8516263211038, 1357.592722183341, 1376.951806686761, 1395.4976208564908, 27419.588706169823, 28870.566941495475, 29442.705572943996, 29592.40815183641, 29972.85013574918, 50670.74664626653, 50966.0, 51089.48910510996, 51155.23267150921, 51161.48838511567, 445639.0, 447087.0, 447123.0, 448864.0, 498555.0, 24652.0, 25933.0, 26079.217623471326, 26281.73718262793, 26329.73670263273, 49720.0, 49796.50203497919, 50860.74569627128, 51402.74298628633, 51556.0, 41592.792036039624, 41789.37315940324, 42332.57667423286, 42362.57637423586, 44544.33183502315, 41295.380569290886, 41475.79262103791, 41815.0, 43390.34914476222, 43979.34030989474, 34278.314433710686, 35667.46498802572, 35878.82060589785, 36344.81827590845, 38341.0, 34005.82997084999, 37478.0, 37619.62380376161, 37892.43161352637, 38692.6130738689, 24931.252062438154, 25149.0, 25270.873645631655, 25292.204239572457, 25586.8720656403], "value": 19692.240379266543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.18745790615867, 369.9038250054985, 377.0554760325384, 389.54228781182115, 412.47821505795173, 17470.563235919093, 19447.708284375745, 19543.70684439731, 19739.802601973977, 20323.288684896033, 19803.504912377197, 19928.9003554982, 20012.599748005032, 20228.08973596188, 20565.177392904305, 5474.534664553498, 5601.019821531236, 5671.7164141792955, 5828.41715828418, 5894.292684877799, 5804.854878628026, 5818.06910894257, 5819.592628516006, 5838.941610583904, 5853.736581853806, 5623.409541998092, 5740.483356497914, 5781.682007489587, 5782.3639399666, 5820.457578741637, 5702.372738998707, 5749.022666146765, 5750.05124154514, 5753.453421924932, 5756.683382413966, 4933.7286449245275, 5103.668261562986, 5134.512221338967, 5139.331886854685, 5140.408852981917, 24259.514809703884, 24342.105263157988, 24443.755562444327, 24512.877435612707, 24783.62824557615, 2410.5215203913926, 2451.5587194305017, 2966.5846781450614, 3094.226443389153, 3123.141136187544, 14101.294935253196, 14424.783628245585, 14443.133411995314, 14800.777988330183, 14906.925465372604, 1860.2187081425834, 1931.6329897319465, 2006.1975209916004, 2019.2326915771912, 2035.1248962945926, 55570.72214638901, 82607.58696206541, 83398.58300708518, 84927.57536212342, 85270.57364713198, 10100.848487272697, 10232.488375581192, 10501.527431265611, 10529.947350263199, 10548.736281593021, 14168.64578385528, 14206.644833879029, 14246.928765356108, 14291.85708142926, 14300.85699143016, 7665.156832748393, 7818.687252509836, 7840.6863725451485, 8028.397870159762, 8081.595920204084, 7573.697052117964, 7657.655405506765, 7838.059432868093, 7869.213078692085, 7884.960575197088, 6366.36336366369, 6453.386928241809, 6460.903086453707, 6527.54307198501, 6567.1462709848365, 1820.517562845847, 1832.7250912362986, 1843.1613615804777, 1877.164661725519, 1887.6130393269348, 889.4707648948994, 1044.12815299226, 1057.9365238085738, 1073.3203233900344, 1094.1848322999417, 17311.48065558035, 18968.715469267972, 19113.139908704143, 19354.419367419, 19468.31860884862, 998.6554638649654, 1019.9592016319413, 1020.9846852297221, 1048.4286064095086, 1080.8108580998294, 9966.80066398668, 10756.19328550362, 10798.946005269923, 10808.621698240524, 10885.673429797118, 475817.0, 479641.0, 481538.0, 484121.0, 492078.0, 83379.0, 84507.0, 86114.0, 86693.56653216694, 87286.0, 994.4928086675703, 1018.8879223285433, 1041.796849614333, 1045.6131231444415, 1046.340805292676, 15975.20123993819, 16642.0, 16875.74686379681, 16882.0, 17307.0, 42369.576304236565, 42531.0, 43316.13367732691, 43680.56319436765, 44439.77780111079, 55069.724651376484, 56649.0, 56924.0, 57647.71176144092, 57946.0, 8332.541710205754, 8383.874241886255, 8446.535440550828, 8503.160228055804, 8809.07504711996, 13637.363516378098, 13753.38109785042, 13845.58463246064, 13976.301184940916, 14099.0, 10992.395418252074, 11071.187626495832, 11480.598179063692, 11649.417529123679, 11655.358955257543, 11864.881351186377, 11899.167058305835, 11954.760904781682, 12263.32551709629, 12333.93833030829, 11026.0, 11100.333979961228, 11319.320840749582, 11581.884181158417, 11724.88275117272, 8290.46112002742, 8341.708040218806, 8475.110113438, 8665.740027799176, 8913.910860891308, 11246.0, 11256.943715281372, 11281.887181128084, 11414.372209528556, 11592.6522204334], "value": 9361.428065524831, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1184.4551506307098, 1501.7897494350789, 1522.2312732070302, 1680.5714542791593, 1808.9547761305973, 8717.389782715212, 9137.269018478522, 9139.543022848857, 9478.81042379152, 9519.714408567754, 9284.350095493319, 9396.71809845704, 9422.057794220575, 9427.48148851813, 9427.858582121273, 2146.1415433826514, 2429.441228517433, 2468.777809997104, 2485.6768620079365, 2507.5987841945293, 2502.1117503286346, 2527.494501099778, 2537.0, 2539.073238268028, 2540.530001949636, 2390.2590197038885, 2414.1188466209796, 2421.225207933462, 2456.6069428891383, 2461.852288862665, 2334.509752951882, 2407.5305315463493, 2413.2036427978724, 2418.4679370538365, 2423.7333893271725, 2457.840240384387, 2473.938151546208, 2479.2438306316567, 2479.4049428137296, 2493.800495960319, 9388.483633400161, 9571.712848614552, 9584.5686944087, 9586.328956973015, 9631.422114673142, 5538.6399884007815, 5622.662640241558, 5649.293838270216, 5719.428057194247, 5725.083986562172, 3701.722370822199, 3741.7006639468777, 3783.659470647655, 3849.903752406184, 3894.7468414552964, 1211.1340391620033, 1214.8542174938975, 1228.4717571444276, 1259.5276771210797, 1266.83531140952, 13090.86909130906, 13129.671758206026, 13161.802572961413, 13236.338183090807, 13248.80126798089, 4676.8129274828625, 4704.647676161919, 4799.088173247142, 4803.255495398219, 4966.428860680996, 10084.69745907624, 10114.393136411692, 10134.898651013396, 10164.237682173867, 10209.489525523693, 5194.506521880416, 5199.454057324002, 5204.843854684365, 5205.6616319939085, 5208.713520756319, 4368.4102646143, 4848.927266091011, 4850.563449289581, 4897.191963326082, 4966.8261610844165, 3940.5074365704286, 4003.2393845169327, 4083.1221287422754, 4104.240715467588, 4117.423560701504, 1197.904167666602, 1214.2714371377103, 1220.829083928233, 1231.7474917641862, 1235.6540168752763, 957.129012598532, 959.4339339789558, 968.5835090910836, 973.3964941736169, 998.6485949362052, 5742.770289188469, 5871.148683440877, 5966.582339236299, 6005.399460054047, 6027.517798576104, 480.3779106057664, 480.526681218999, 485.48296064690993, 491.22386629125896, 497.54226111977067, 4097.836086556564, 4098.426220329156, 4098.5286692030795, 4116.176764647083, 4133.42132101506, 11416.771664566828, 11760.647180584596, 11786.705332366588, 11816.350100744541, 11850.288982661068, 11677.182597218112, 11678.24091434054, 11695.181337306478, 11695.941520292345, 11757.823632645517, 8113.229243221885, 8364.665413383518, 8461.069282378934, 8465.36509761758, 8664.91335086641, 5730.0, 5908.940910590839, 6022.969885150546, 6034.39656034393, 6286.27707813589, 10675.89324106749, 10968.341899486057, 10984.945075274893, 10994.065504432212, 11005.834912476159, 10525.263231574023, 10799.56801727922, 10896.94551527269, 10909.39998300101, 10945.23383363157, 7219.205887352492, 7857.489263197874, 7967.362610991223, 8179.427440079137, 8311.501309921425, 9160.221381182673, 9239.0, 9243.2143267823, 9246.0, 9271.907280927375, 7804.726834560764, 7961.124276329715, 8057.556834373931, 8114.066882308374, 8377.078521362524, 7810.179931107379, 7987.16134805837, 8033.317168040782, 8147.226013528705, 8246.3815213858, 6930.237673855972, 6989.895151572832, 7041.3310735480045, 7047.964760176166, 7091.574505529685, 5082.771275292546, 5237.319148510669, 5248.527632513092, 5347.598930080182, 5431.022415965164, 5005.924911126263, 5221.6344855859725, 5245.213218017328, 5303.310569625924, 5391.245225668331], "value": 4733.107794042117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.12275934282366, 329.9307145499445, 334.1629218806888, 335.0885591191957, 339.32982359839303, 5335.279737235472, 5336.092864213083, 5415.025295446818, 5416.972915135429, 5420.262767302512, 4968.478309777466, 5042.218456139295, 5164.457731938149, 5183.896322073556, 5293.9735301323435, 4101.5488296287385, 4103.1793641271715, 4106.055607210344, 4106.8767936961785, 4132.752034877917, 4090.0, 4094.078832262747, 4102.589741025903, 4123.773162492251, 4126.0, 3834.539855217378, 3851.480050193216, 4009.1981603679237, 4042.8787136385954, 4067.1662309226685, 4056.14820887611, 4067.0, 4074.0629655179177, 4076.61272179144, 4077.694172937027, 3537.435359364466, 3561.982190089059, 3580.5166302549083, 3582.5880023797076, 3587.892363229107, 4869.537393947569, 5091.185410334366, 5144.279800827887, 5146.819861304836, 5152.227165925104, 4670.883227919295, 4676.321933319649, 4678.3216433616935, 4678.69588476748, 4687.20317546018, 5498.285222920995, 5501.0, 5519.834404967817, 5545.223668686387, 5547.667139971614, 3718.8326525306156, 3882.1265215326325, 3968.980155099206, 3981.820818063194, 3998.4002399639994, 5692.402297758758, 5694.658320500783, 5724.542036637059, 5738.569607279471, 5747.08046712524, 3985.461962635065, 3993.5806740292433, 3994.4807175067635, 4018.537868145142, 4039.303492533143, 3857.2092720992127, 4079.2453396121814, 4085.7752823594988, 4092.202020606013, 4093.0, 4610.7694615269775, 4616.399868017136, 4625.768711564476, 4655.3249778782565, 4673.509281525459, 5475.67145971243, 5483.945160548344, 5524.392316845144, 5553.416891226444, 5587.664740115606, 1334.3194970564887, 1374.0793668242302, 1392.5613431769164, 1393.5679939218671, 1393.877928267734, 870.7126648206204, 897.4570384917162, 900.4912224593083, 905.3888625177979, 916.9816603667889, 4736.4713288600215, 4768.642351823558, 4772.665913386099, 4773.663374255214, 4781.928271075936, 4869.902601947942, 4891.853244402673, 4942.5057494251005, 4975.626827987915, 4999.700017998932, 4314.848980285675, 4746.572808447257, 4763.332833508244, 4766.523347665275, 4768.523147685273, 2653.482570898695, 2664.46875468302, 2670.799690023256, 2672.318558767502, 2678.370582913014, 4983.476734942851, 5137.768800403991, 5161.954446876361, 5188.195829646411, 5221.843344699665, 4983.352164218701, 5009.924851127236, 5020.974895125574, 5043.823466178666, 5100.617453690988, 5049.873753156127, 5058.367704036996, 5089.3638295213095, 5123.948760512421, 5143.331366922276, 4770.332153498583, 4858.58702010333, 4938.604911607134, 4976.527229913152, 4999.725015124203, 4926.3842019747535, 4932.778024988956, 4942.4316203637045, 4959.0, 4987.526185012418, 5105.412877519133, 5119.0, 5119.0529751996, 5121.46881368129, 5125.795438072125, 3480.3039392121173, 3582.194006348551, 3604.261126469015, 3654.6893514051603, 3670.3116566379763, 4910.337104490991, 4914.287428322945, 4919.827806026915, 4920.8769780755765, 4951.252055109305, 4767.451743049455, 4780.948191397887, 4794.736289503971, 4796.592289655418, 4866.440359358719, 4548.704334218265, 4864.586510146536, 4866.250568428968, 4875.219964805614, 4882.487338829372, 4762.880927976828, 4777.877198858335, 4783.617310615073, 4786.6410019248, 4802.375691160127, 4791.1854984652, 4837.95162048375, 4842.080004799077, 4857.077155340474, 4881.681945874523, 4454.020115574568, 4768.785404656729, 4803.879903002453, 4823.903521929612, 4853.427683327842], "value": 3945.964373457378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.7347754808268, 758.2947858491603, 766.1801871996962, 817.1215942861426, 889.1775108025075, 14098.295085245734, 15291.541253762378, 15306.387744490221, 15308.46420375287, 15321.846781532195, 15082.472113476031, 15347.693046139098, 15353.769693454578, 15387.461438849643, 15397.84602153978, 810.1817164663673, 813.8819871118684, 820.0323618130603, 826.5990994367727, 829.1873963515758, 660.8050625065594, 683.4942142814332, 685.2907241005536, 685.6880119545594, 821.322409012564, 847.9448835825651, 868.1622234543664, 870.7126648206076, 870.9259712924409, 871.1593312453481, 872.3936863879607, 872.8516152254132, 872.8647059705725, 873.2708188662443, 873.3144481581928, 633.012500499218, 633.3729607688401, 633.5153607490273, 633.6134957675814, 634.2674211285972, 10939.343639381583, 14385.640358991004, 14396.568102956928, 14691.55925322242, 14774.26128693561, 13405.731885362238, 14649.414023439049, 14714.264286785618, 14784.778228326584, 14843.851561484464, 11966.64100076999, 12084.154109212359, 12123.272603643722, 12283.385830708428, 12378.690532736753, 3761.6238376162437, 3782.6406491383273, 3820.1472285941295, 3953.2291203215386, 3975.662068724162, 85515.57242213811, 85787.57106214491, 86911.69632455519, 87440.5627971856, 89477.0, 10748.462576871125, 10803.513841877135, 10879.0, 10967.890321096687, 11140.49867755953, 20873.0, 21030.0, 21085.578288434146, 21157.153713851272, 21196.682049769264, 8223.835523289501, 8263.586820658944, 8315.875261871077, 8385.664573416994, 8683.478991260545, 4537.523560026149, 4769.689970151929, 4796.664233503621, 4889.877753056131, 4937.925931111036, 3480.3387356402204, 3609.873654422083, 3636.1091532574687, 3646.6535679110443, 3653.287608916289, 639.9168108145911, 644.6647743173523, 649.1560970738116, 649.3506493506494, 671.0571646836203, 698.7763915546976, 718.5832217313946, 720.8846584546552, 736.9152547457004, 755.4598462099625, 9531.809363812685, 9678.467684277432, 10026.64906728261, 10444.373337599769, 10503.2122590806, 604.3050491934223, 616.442119881508, 677.50609905159, 699.8705239530703, 715.3275920634587, 4541.886452838639, 4834.0, 5012.083325011461, 5022.20669694098, 5052.368453943257, 74298.25701743021, 74312.62843685747, 75161.0, 75178.62410687911, 99611.0, 84988.0, 96839.51580242053, 98475.0, 99044.50477747565, 99257.50371248242, 58239.0, 88444.5577772107, 88973.0, 89208.280633696, 93970.53014734882, 9515.191208747334, 9728.902710973083, 9836.262280329147, 9921.65274215399, 10001.799964000826, 49620.50379496159, 51253.0, 51522.0, 51741.482585175174, 52778.73610631922, 64054.3594564067, 65895.0, 67378.66310668415, 72278.63860680663, 73853.0, 13746.312684365541, 13815.792763108762, 13980.510682126076, 13992.923027989595, 14270.072445291024, 14618.926905365404, 15465.149416782186, 15507.069575825486, 15968.0, 15968.361265549713, 14180.121566637932, 14800.777988329968, 14871.256437178314, 15057.0, 15062.472813451477, 12293.692657683629, 12444.315562644142, 13154.473821047051, 13189.80215296752, 13195.406206720529, 13307.46770129184, 13864.450636942434, 14006.089604175697, 14474.203918784568, 14518.564443066725, 9964.701058968243, 10029.0, 10217.642382516577, 10227.41375801104, 10524.0, 9767.413955162712, 10356.17150627963, 10733.50776485587, 10906.672799816019, 10957.890421096006], "value": 7484.280582960874, "unit": "score"}], "name": "dromaeo_css", "value": 7171.529497110996, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 110.0, 107.0, 111.0, 109.0, 110.0, 115.0, 111.0, 111.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 145.0, 194.0, 120.0, 150.0, 157.0, 120.0, 172.0, 123.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 158.0, 159.0, 160.0, 161.0, 163.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 167.0, 76.0, 77.0, 75.0, 76.0, 157.0, 76.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 160.0, 85.0, 84.0, 85.0, 147.0, 84.0, 86.0, 145.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 51.0, 52.0, 52.0, 51.0, 52.0, 51.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 62.0, 67.0, 63.0, 61.0, 63.0, 61.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 81.0, 78.0, 80.0, 80.0, 78.0, 79.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 134.0, 123.0, 162.0, 124.0, 130.0, 127.0, 127.0, 140.0, 126.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 162.0, 161.0, 163.0, 165.0, 161.0, 163.0, 164.0, 164.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 64.0, 66.0, 65.0, 65.0, 63.0, 65.0, 65.0, 64.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1525.1, "alertThreshold": 2.0}]} 16:34:26 INFO - Return code: 0 16:34:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:34:26 INFO - # TBPL SUCCESS # 16:34:26 INFO - Running post-action listener: _resource_record_post_action 16:34:26 INFO - [mozharness: 2016-08-21 23:34:26.694031Z] Finished run-tests step (success) 16:34:26 INFO - Running post-run listener: _resource_record_post_run 16:34:26 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 408510464; Read time: 168; Write time: 253692 16:34:26 INFO - TinderboxPrint: CPU usage
13.4% 16:34:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 168 16:34:26 INFO - TinderboxPrint: I/O write bytes / time
408,510,464 / 253,692 16:34:26 INFO - TinderboxPrint: CPU idle
8,257.3 (86.6%) 16:34:26 INFO - TinderboxPrint: CPU user
1,210.8 (12.7%) 16:34:26 INFO - TinderboxPrint: Swap in / out
0 / 0 16:34:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118325248; Read time: 112; Write time: 86152 16:34:26 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290185216; Read time: 56; Write time: 167540 16:34:27 INFO - Running post-run listener: _upload_blobber_files 16:34:27 INFO - Blob upload gear active. 16:34:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:34:27 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 16:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:34:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:34:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:34:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:28 INFO - (blobuploader) - INFO - Done attempting. 16:34:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:34:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:34:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:32 INFO - (blobuploader) - INFO - Done attempting. 16:34:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:34:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:34:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:33 INFO - (blobuploader) - INFO - Done attempting. 16:34:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:34:33 INFO - Return code: 0 16:34:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:34:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:34:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a1e1f0532513863a98901f7437c189fe092dc508feeecad263994e03948e1eb25354bd308c4ffd2436459bd87eed45c78f8e3e82f459db29cbe9076e0dcb037a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51d300e29e614ac6b05600bc65a0594b8d00830dad23466ed98477b0bcb21ac0e96c451af350f46d9db3c4ef3f8da79708ced4e324430d2fd6b4ae494f875f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8adaf10f5cbdb45cca46f1134d5cfa5549cd62dff79f7af22c3ac6d8a519195cd3b93b880e92a614de26ed99f4ddf874c2b291e7e57232f28575f732ffa4e84d"} 16:34:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:34:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:34:33 INFO - Contents: 16:34:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a1e1f0532513863a98901f7437c189fe092dc508feeecad263994e03948e1eb25354bd308c4ffd2436459bd87eed45c78f8e3e82f459db29cbe9076e0dcb037a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51d300e29e614ac6b05600bc65a0594b8d00830dad23466ed98477b0bcb21ac0e96c451af350f46d9db3c4ef3f8da79708ced4e324430d2fd6b4ae494f875f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8adaf10f5cbdb45cca46f1134d5cfa5549cd62dff79f7af22c3ac6d8a519195cd3b93b880e92a614de26ed99f4ddf874c2b291e7e57232f28575f732ffa4e84d"} 16:34:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:34:33 INFO - Copying logs to upload dir... 16:34:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:34:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.861439 ========= master_lag: 0.85 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-21 16:34:33.631281) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 16:34:33.639472) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a1e1f0532513863a98901f7437c189fe092dc508feeecad263994e03948e1eb25354bd308c4ffd2436459bd87eed45c78f8e3e82f459db29cbe9076e0dcb037a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51d300e29e614ac6b05600bc65a0594b8d00830dad23466ed98477b0bcb21ac0e96c451af350f46d9db3c4ef3f8da79708ced4e324430d2fd6b4ae494f875f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8adaf10f5cbdb45cca46f1134d5cfa5549cd62dff79f7af22c3ac6d8a519195cd3b93b880e92a614de26ed99f4ddf874c2b291e7e57232f28575f732ffa4e84d"} build_url:https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011402 build_url: 'https://queue.taskcluster.net/v1/task/PIGLcVlQRyGmnYVjLFXY0w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a1e1f0532513863a98901f7437c189fe092dc508feeecad263994e03948e1eb25354bd308c4ffd2436459bd87eed45c78f8e3e82f459db29cbe9076e0dcb037a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51d300e29e614ac6b05600bc65a0594b8d00830dad23466ed98477b0bcb21ac0e96c451af350f46d9db3c4ef3f8da79708ced4e324430d2fd6b4ae494f875f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8adaf10f5cbdb45cca46f1134d5cfa5549cd62dff79f7af22c3ac6d8a519195cd3b93b880e92a614de26ed99f4ddf874c2b291e7e57232f28575f732ffa4e84d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 16:34:33.672633) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:34:33.672954) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471819832.11043-1708939187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.46 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 16:34:34.140554) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 16:34:34.140863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 16:34:34.141733) ========= ========= Total master_lag: 1.43 =========