builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-026 starttime: 1482287449.23 results: success (0) buildid: 20161220174153 builduid: 06068e8dcc094fa1981d24c4838524df revision: 7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.233571) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.234010) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.234308) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.286189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.286470) ========= 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=41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-20 18:30:49-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-12-20 18:30:49 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.380251) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.380574) ========= 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=41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021949 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.434715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:49.436096) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262 --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=41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-20 18:30:49,815 truncating revision to first 12 chars 2016-12-20 18:30:49,815 Setting DEBUG logging. 2016-12-20 18:30:49,815 attempt 1/10 2016-12-20 18:30:49,815 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/7a4e657e1d42?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 18:30:50,108 unpacking tar archive at: graphics-7a4e657e1d42/testing/mozharness/ program finished with exit code 0 elapsedTime=0.612470 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:50.061451) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:50.061727) ========= script_repo_revision: 7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:50.065144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:50.065434) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 18:30:50.075569) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-20 18:30:50.075887) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:30:50 INFO - MultiFileLogger online at 20161220 18:30:50 in /builds/slave/test 18:30:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 18:30:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:30:50 INFO - {'append_to_log': False, 18:30:50 INFO - 'base_work_dir': '/builds/slave/test', 18:30:50 INFO - 'blob_upload_branch': 'Graphics', 18:30:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:30:50 INFO - 'branch': 'Graphics', 18:30:50 INFO - 'buildbot_json_path': 'buildprops.json', 18:30:50 INFO - 'config_files': ('talos/linux_config.py',), 18:30:50 INFO - 'default_actions': ('clobber', 18:30:50 INFO - 'read-buildbot-config', 18:30:50 INFO - 'download-and-extract', 18:30:50 INFO - 'populate-webroot', 18:30:50 INFO - 'create-virtualenv', 18:30:50 INFO - 'install', 18:30:50 INFO - 'run-tests'), 18:30:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:30:50 INFO - 'download_minidump_stackwalk': True, 18:30:50 INFO - 'download_symbols': 'ondemand', 18:30:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:30:50 INFO - 'tooltool.py': '/tools/tooltool.py', 18:30:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:30:50 INFO - '/tools/misc-python/virtualenv.py')}, 18:30:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:30:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:30:50 INFO - 'installer_path': 'installer.exe', 18:30:50 INFO - 'log_level': 'info', 18:30:50 INFO - 'log_name': 'talos', 18:30:50 INFO - 'log_to_console': True, 18:30:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:30:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:30:50 INFO - 'opt_config_files': (), 18:30:50 INFO - 'pip_index': False, 18:30:50 INFO - 'sps_profile': False, 18:30:50 INFO - 'sps_profile_interval': 0, 18:30:50 INFO - 'suite': 'dromaeojs', 18:30:50 INFO - 'system_bits': '32', 18:30:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:30:50 INFO - 'title': 'talos-linux64-ix-026', 18:30:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:30:50 INFO - 'use_talos_json': True, 18:30:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:30:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:30:50 INFO - 'work_dir': 'build'} 18:30:50 INFO - [mozharness: 2016-12-21 02:30:50.554100Z] Running clobber step. 18:30:50 INFO - Running pre-action listener: _resource_record_pre_action 18:30:50 INFO - Running main action method: clobber 18:30:50 INFO - rmtree: /builds/slave/test/build 18:30:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:30:51 INFO - Running post-action listener: _resource_record_post_action 18:30:51 INFO - [mozharness: 2016-12-21 02:30:51.533452Z] Finished clobber step (success) 18:30:51 INFO - [mozharness: 2016-12-21 02:30:51.533553Z] Running read-buildbot-config step. 18:30:51 INFO - Running pre-action listener: _resource_record_pre_action 18:30:51 INFO - Running main action method: read_buildbot_config 18:30:51 INFO - Using buildbot properties: 18:30:51 INFO - { 18:30:51 INFO - "project": "", 18:30:51 INFO - "product": "firefox", 18:30:51 INFO - "script_repo_revision": "default", 18:30:51 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 18:30:51 INFO - "repository": "", 18:30:51 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 18:30:51 INFO - "buildid": "20161220174153", 18:30:51 INFO - "pgo_build": "False", 18:30:51 INFO - "basedir": "/builds/slave/test", 18:30:51 INFO - "buildnumber": 27, 18:30:51 INFO - "slavename": "talos-linux64-ix-026", 18:30:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:30:51 INFO - "platform": "ubuntu64_hw", 18:30:51 INFO - "scheduler": "tests-graphics-linux64-talos", 18:30:51 INFO - "branch": "graphics", 18:30:51 INFO - "repo_path": "projects/graphics", 18:30:51 INFO - "revision": "7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262", 18:30:51 INFO - "stage_platform": "linux64", 18:30:51 INFO - "builduid": "06068e8dcc094fa1981d24c4838524df", 18:30:51 INFO - "slavebuilddir": "test" 18:30:51 INFO - } 18:30:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:30:51 INFO - Running post-action listener: _resource_record_post_action 18:30:51 INFO - [mozharness: 2016-12-21 02:30:51.535990Z] Finished read-buildbot-config step (success) 18:30:51 INFO - [mozharness: 2016-12-21 02:30:51.536080Z] Running download-and-extract step. 18:30:51 INFO - Running pre-action listener: _resource_record_pre_action 18:30:51 INFO - Running main action method: download_and_extract 18:30:51 INFO - mkdir: /builds/slave/test/build/tests 18:30:51 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')]} 18:30:51 INFO - trying https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:30:51 INFO - Downloading https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:30:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:30:52 INFO - Downloaded 1361 bytes. 18:30:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:30:52 INFO - Using the following test package requirements: 18:30:52 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:30:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:30:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:30:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'jsshell-linux-x86_64.zip'], 18:30:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:30:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:30:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:30:52 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:30:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:30:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:30:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:30:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:30:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:30:53 INFO - Expected file size: 20258275 18:30:53 INFO - Obtained file size: 20258275 18:30:58 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:30:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:30:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:30:58 INFO - Fetch https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:30:59 INFO - Expected file size: 20258275 18:30:59 INFO - Obtained file size: 20258275 18:31:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:31:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:31:03 INFO - Fetch https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:31:06 INFO - Expected file size: 14409734 18:31:06 INFO - Obtained file size: 14409734 18:31: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')]} 18:31:06 INFO - trying https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:31:06 INFO - Downloading https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:31:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:31:09 INFO - Downloaded 55647624 bytes. 18:31:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:31:09 INFO - mkdir: /builds/slave/test/properties 18:31:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:09 INFO - Contents: 18:31:09 INFO - build_url:https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:31:10 INFO - Running post-action listener: _resource_record_post_action 18:31:10 INFO - Running post-action listener: set_extra_try_arguments 18:31:10 INFO - [mozharness: 2016-12-21 02:31:10.346699Z] Finished download-and-extract step (success) 18:31:10 INFO - [mozharness: 2016-12-21 02:31:10.346928Z] Running populate-webroot step. 18:31:10 INFO - Running pre-action listener: _resource_record_pre_action 18:31:10 INFO - Running main action method: populate_webroot 18:31:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:10 INFO - '--tppagecycles', 18:31:10 INFO - '7'], 18:31:10 INFO - 'tests': ['tsvgm']}}, 18:31:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:31:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:31:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['damp', 'tps']}, 18:31:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'tests': ['damp', 'tps']}, 18:31:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['dromaeo_dom']}, 18:31:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['basic_compositor_video']}, 18:31:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:31:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['a11yr', 18:31:10 INFO - 'ts_paint', 18:31:10 INFO - 'tpaint', 18:31:10 INFO - 'sessionrestore', 18:31:10 INFO - 'sessionrestore_no_auto_restore', 18:31:10 INFO - 'tabpaint']}, 18:31:10 INFO - 'other-e10s': {'tests': ['a11yr', 18:31:10 INFO - 'ts_paint', 18:31:10 INFO - 'tpaint', 18:31:10 INFO - 'sessionrestore', 18:31:10 INFO - 'sessionrestore_no_auto_restore', 18:31:10 INFO - 'tabpaint']}, 18:31:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:31:10 INFO - 'ts_paint', 18:31:10 INFO - 'tpaint', 18:31:10 INFO - 'sessionrestore', 18:31:10 INFO - 'sessionrestore_no_auto_restore', 18:31:10 INFO - 'tabpaint']}, 18:31:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:31:10 INFO - 'ts_paint', 18:31:10 INFO - 'tpaint', 18:31:10 INFO - 'sessionrestore', 18:31:10 INFO - 'sessionrestore_no_auto_restore', 18:31:10 INFO - 'tabpaint']}, 18:31:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['a11yr', 18:31:10 INFO - 'ts_paint', 18:31:10 INFO - 'tpaint', 18:31:10 INFO - 'sessionrestore', 18:31:10 INFO - 'sessionrestore_no_auto_restore', 18:31:10 INFO - 'tabpaint']}, 18:31:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['a11yr', 18:31:10 INFO - 'ts_paint', 18:31:10 INFO - 'tpaint', 18:31:10 INFO - 'sessionrestore', 18:31:10 INFO - 'sessionrestore_no_auto_restore', 18:31:10 INFO - 'tabpaint']}, 18:31:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['tsvgx', 18:31:10 INFO - 'tsvgr_opacity', 18:31:10 INFO - 'tart', 18:31:10 INFO - 'tscrollx', 18:31:10 INFO - 'cart', 18:31:10 INFO - 'tsvg_static']}, 18:31:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:31:10 INFO - 'tsvgr_opacity', 18:31:10 INFO - 'tart', 18:31:10 INFO - 'tscrollx', 18:31:10 INFO - 'cart', 18:31:10 INFO - 'tsvg_static']}, 18:31:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'talos_options': ['--disable-e10s'], 18:31:10 INFO - 'tests': ['tp5o']}, 18:31:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:31:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'talos_options': ['--disable-e10s', 18:31:10 INFO - '--xperf_path', 18:31:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:10 INFO - 'tests': ['tp5n']}, 18:31:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:31:10 INFO - 'talos_options': ['--xperf_path', 18:31:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:10 INFO - 'tests': ['tp5n']}}, 18:31:10 INFO - 'talos.zip': {'path': '', 18:31:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:10 INFO - Running post-action listener: _resource_record_post_action 18:31:10 INFO - [mozharness: 2016-12-21 02:31:10.368823Z] Finished populate-webroot step (success) 18:31:10 INFO - [mozharness: 2016-12-21 02:31:10.368912Z] Running create-virtualenv step. 18:31:10 INFO - Running pre-action listener: _resource_record_pre_action 18:31:10 INFO - Running main action method: create_virtualenv 18:31:10 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:31:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:10 INFO - Using real prefix '/usr' 18:31:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:10 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:12 INFO - Installing pip.................done. 18:31:12 INFO - Return code: 0 18:31:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:31:13 INFO - Reading from file tmpfile_stdout 18:31:13 INFO - Output received: 18:31:13 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:31:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:31:13 INFO - Using env: {'DISPLAY': ':0', 18:31:13 INFO - 'HOME': '/home/cltbld', 18:31:13 INFO - 'LANG': 'en_US.UTF-8', 18:31:13 INFO - 'LANGUAGE': 'en_US:en', 18:31:13 INFO - 'LOGNAME': 'cltbld', 18:31:13 INFO - 'MAIL': '/var/mail/cltbld', 18:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:13 INFO - 'MOZ_NO_REMOTE': '1', 18:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:13 INFO - 'NO_EM_RESTART': '1', 18:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:13 INFO - 'PWD': '/builds/slave/test', 18:31:13 INFO - 'SHELL': '/bin/bash', 18:31:13 INFO - 'SHLVL': '1', 18:31:13 INFO - 'TERM': 'linux', 18:31:13 INFO - 'TMOUT': '86400', 18:31:13 INFO - 'USER': 'cltbld', 18:31:13 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:13 INFO - '_': '/tools/buildbot/bin/python'} 18:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:31:13 INFO - Cleaning up... 18:31:13 INFO - Return code: 0 18:31:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:31:13 INFO - Using env: {'DISPLAY': ':0', 18:31:13 INFO - 'HOME': '/home/cltbld', 18:31:13 INFO - 'LANG': 'en_US.UTF-8', 18:31:13 INFO - 'LANGUAGE': 'en_US:en', 18:31:13 INFO - 'LOGNAME': 'cltbld', 18:31:13 INFO - 'MAIL': '/var/mail/cltbld', 18:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:13 INFO - 'MOZ_NO_REMOTE': '1', 18:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:13 INFO - 'NO_EM_RESTART': '1', 18:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:13 INFO - 'PWD': '/builds/slave/test', 18:31:13 INFO - 'SHELL': '/bin/bash', 18:31:13 INFO - 'SHLVL': '1', 18:31:13 INFO - 'TERM': 'linux', 18:31:13 INFO - 'TMOUT': '86400', 18:31:13 INFO - 'USER': 'cltbld', 18:31:13 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:13 INFO - '_': '/tools/buildbot/bin/python'} 18:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:13 INFO - Downloading/unpacking psutil>=3.1.1 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:15 INFO - Installing collected packages: psutil 18:31:15 INFO - Running setup.py install for psutil 18:31:15 INFO - building 'psutil._psutil_linux' extension 18:31:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:31:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:31:15 INFO - building 'psutil._psutil_posix' extension 18:31:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:31:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:16 INFO - Successfully installed psutil 18:31:16 INFO - Cleaning up... 18:31:16 INFO - Return code: 0 18:31:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:31:16 INFO - Using env: {'DISPLAY': ':0', 18:31:16 INFO - 'HOME': '/home/cltbld', 18:31:16 INFO - 'LANG': 'en_US.UTF-8', 18:31:16 INFO - 'LANGUAGE': 'en_US:en', 18:31:16 INFO - 'LOGNAME': 'cltbld', 18:31:16 INFO - 'MAIL': '/var/mail/cltbld', 18:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:16 INFO - 'MOZ_NO_REMOTE': '1', 18:31:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:16 INFO - 'NO_EM_RESTART': '1', 18:31:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:16 INFO - 'PWD': '/builds/slave/test', 18:31:16 INFO - 'SHELL': '/bin/bash', 18:31:16 INFO - 'SHLVL': '1', 18:31:16 INFO - 'TERM': 'linux', 18:31:16 INFO - 'TMOUT': '86400', 18:31:16 INFO - 'USER': 'cltbld', 18:31:16 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:16 INFO - '_': '/tools/buildbot/bin/python'} 18:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:31:18 INFO - Installing collected packages: mozsystemmonitor 18:31:18 INFO - Running setup.py install for mozsystemmonitor 18:31:18 INFO - Successfully installed mozsystemmonitor 18:31:18 INFO - Cleaning up... 18:31:18 INFO - Return code: 0 18:31:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:31:18 INFO - Using env: {'DISPLAY': ':0', 18:31:18 INFO - 'HOME': '/home/cltbld', 18:31:18 INFO - 'LANG': 'en_US.UTF-8', 18:31:18 INFO - 'LANGUAGE': 'en_US:en', 18:31:18 INFO - 'LOGNAME': 'cltbld', 18:31:18 INFO - 'MAIL': '/var/mail/cltbld', 18:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:18 INFO - 'MOZ_NO_REMOTE': '1', 18:31:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:18 INFO - 'NO_EM_RESTART': '1', 18:31:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:18 INFO - 'PWD': '/builds/slave/test', 18:31:18 INFO - 'SHELL': '/bin/bash', 18:31:18 INFO - 'SHLVL': '1', 18:31:18 INFO - 'TERM': 'linux', 18:31:18 INFO - 'TMOUT': '86400', 18:31:18 INFO - 'USER': 'cltbld', 18:31:18 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:18 INFO - '_': '/tools/buildbot/bin/python'} 18:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:18 INFO - Downloading/unpacking jsonschema==2.5.1 18:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:21 INFO - warning: no files found matching '*.txt' 18:31:21 INFO - no previously-included directories found matching 'build' 18:31:21 INFO - no previously-included directories found matching 'dist' 18:31:21 INFO - no previously-included directories found matching '.git*' 18:31:21 INFO - Installing collected packages: jsonschema, functools32 18:31:21 INFO - Running setup.py install for functools32 18:31:21 INFO - warning: no files found matching '*.txt' 18:31:21 INFO - no previously-included directories found matching 'build' 18:31:21 INFO - no previously-included directories found matching 'dist' 18:31:21 INFO - no previously-included directories found matching '.git*' 18:31:21 INFO - Successfully installed jsonschema functools32 18:31:21 INFO - Cleaning up... 18:31:21 INFO - Return code: 0 18:31:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:31:21 INFO - Using env: {'DISPLAY': ':0', 18:31:21 INFO - 'HOME': '/home/cltbld', 18:31:21 INFO - 'LANG': 'en_US.UTF-8', 18:31:21 INFO - 'LANGUAGE': 'en_US:en', 18:31:21 INFO - 'LOGNAME': 'cltbld', 18:31:21 INFO - 'MAIL': '/var/mail/cltbld', 18:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:21 INFO - 'MOZ_NO_REMOTE': '1', 18:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:21 INFO - 'NO_EM_RESTART': '1', 18:31:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:21 INFO - 'PWD': '/builds/slave/test', 18:31:21 INFO - 'SHELL': '/bin/bash', 18:31:21 INFO - 'SHLVL': '1', 18:31:21 INFO - 'TERM': 'linux', 18:31:21 INFO - 'TMOUT': '86400', 18:31:21 INFO - 'USER': 'cltbld', 18:31:21 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:21 INFO - '_': '/tools/buildbot/bin/python'} 18:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:31:21 INFO - Cleaning up... 18:31:21 INFO - Return code: 0 18:31:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:31:21 INFO - Using env: {'DISPLAY': ':0', 18:31:21 INFO - 'HOME': '/home/cltbld', 18:31:21 INFO - 'LANG': 'en_US.UTF-8', 18:31:21 INFO - 'LANGUAGE': 'en_US:en', 18:31:21 INFO - 'LOGNAME': 'cltbld', 18:31:21 INFO - 'MAIL': '/var/mail/cltbld', 18:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:21 INFO - 'MOZ_NO_REMOTE': '1', 18:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:21 INFO - 'NO_EM_RESTART': '1', 18:31:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:21 INFO - 'PWD': '/builds/slave/test', 18:31:21 INFO - 'SHELL': '/bin/bash', 18:31:21 INFO - 'SHLVL': '1', 18:31:21 INFO - 'TERM': 'linux', 18:31:21 INFO - 'TMOUT': '86400', 18:31:21 INFO - 'USER': 'cltbld', 18:31:21 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:21 INFO - '_': '/tools/buildbot/bin/python'} 18:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:21 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:23 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:24 INFO - Downloading docopt-0.6.1.tar.gz 18:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:24 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:24 INFO - Running setup.py install for blobuploader 18:31:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:24 INFO - Running setup.py install for requests 18:31:25 INFO - Running setup.py install for docopt 18:31:25 INFO - Successfully installed blobuploader requests docopt 18:31:25 INFO - Cleaning up... 18:31:25 INFO - Return code: 0 18:31:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:25 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 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:25 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 18:31:25 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 18:31:25 INFO - Using env: {'DISPLAY': ':0', 18:31:25 INFO - 'HOME': '/home/cltbld', 18:31:25 INFO - 'LANG': 'en_US.UTF-8', 18:31:25 INFO - 'LANGUAGE': 'en_US:en', 18:31:25 INFO - 'LOGNAME': 'cltbld', 18:31:25 INFO - 'MAIL': '/var/mail/cltbld', 18:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:25 INFO - 'MOZ_NO_REMOTE': '1', 18:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:25 INFO - 'NO_EM_RESTART': '1', 18:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:25 INFO - 'PWD': '/builds/slave/test', 18:31:25 INFO - 'SHELL': '/bin/bash', 18:31:25 INFO - 'SHLVL': '1', 18:31:25 INFO - 'TERM': 'linux', 18:31:25 INFO - 'TMOUT': '86400', 18:31:25 INFO - 'USER': 'cltbld', 18:31:25 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:25 INFO - '_': '/tools/buildbot/bin/python'} 18:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:25 INFO - Running setup.py (path:/tmp/pip-F87b2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:25 INFO - Running setup.py (path:/tmp/pip-RbjV8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:25 INFO - Running setup.py (path:/tmp/pip-5IKdIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:25 INFO - Running setup.py (path:/tmp/pip-RaJ8Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:25 INFO - Running setup.py (path:/tmp/pip-ZRfUmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:25 INFO - Running setup.py (path:/tmp/pip-Dhi_dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:25 INFO - Running setup.py (path:/tmp/pip-FMoZJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:25 INFO - Running setup.py (path:/tmp/pip-BwUvkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:26 INFO - Running setup.py (path:/tmp/pip-Da9cV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:26 INFO - Running setup.py (path:/tmp/pip-r3jwXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:26 INFO - Running setup.py (path:/tmp/pip-xH9nQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:26 INFO - Running setup.py (path:/tmp/pip-1LHEDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:26 INFO - Running setup.py (path:/tmp/pip-0w2p4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:26 INFO - Running setup.py (path:/tmp/pip-gaosLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:26 INFO - Running setup.py (path:/tmp/pip-gnNzp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:26 INFO - Running setup.py (path:/tmp/pip-kauRtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:26 INFO - Running setup.py (path:/tmp/pip-sv_NqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:26 INFO - Running setup.py install for manifestparser 18:31:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:26 INFO - Running setup.py install for mozcrash 18:31:26 INFO - Running setup.py install for mozdebug 18:31:26 INFO - Running setup.py install for mozdevice 18:31:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Running setup.py install for mozfile 18:31:27 INFO - Running setup.py install for mozhttpd 18:31:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Running setup.py install for mozinfo 18:31:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Running setup.py install for mozInstall 18:31:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Running setup.py install for mozleak 18:31:27 INFO - Running setup.py install for mozlog 18:31:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Running setup.py install for moznetwork 18:31:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:27 INFO - Running setup.py install for mozprocess 18:31:27 INFO - Running setup.py install for mozprofile 18:31:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:28 INFO - Running setup.py install for mozrunner 18:31:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:28 INFO - Running setup.py install for mozscreenshot 18:31:28 INFO - Running setup.py install for moztest 18:31:28 INFO - Running setup.py install for mozversion 18:31:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:28 INFO - Cleaning up... 18:31:28 INFO - Return code: 0 18:31:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:28 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 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:28 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 18:31:28 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 18:31:28 INFO - Using env: {'DISPLAY': ':0', 18:31:28 INFO - 'HOME': '/home/cltbld', 18:31:28 INFO - 'LANG': 'en_US.UTF-8', 18:31:28 INFO - 'LANGUAGE': 'en_US:en', 18:31:28 INFO - 'LOGNAME': 'cltbld', 18:31:28 INFO - 'MAIL': '/var/mail/cltbld', 18:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:28 INFO - 'MOZ_NO_REMOTE': '1', 18:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:28 INFO - 'NO_EM_RESTART': '1', 18:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:28 INFO - 'PWD': '/builds/slave/test', 18:31:28 INFO - 'SHELL': '/bin/bash', 18:31:28 INFO - 'SHLVL': '1', 18:31:28 INFO - 'TERM': 'linux', 18:31:28 INFO - 'TMOUT': '86400', 18:31:28 INFO - 'USER': 'cltbld', 18:31:28 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:28 INFO - '_': '/tools/buildbot/bin/python'} 18:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:28 INFO - Running setup.py (path:/tmp/pip-EyhLQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:28 INFO - Running setup.py (path:/tmp/pip-3gfXnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 18:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:28 INFO - Running setup.py (path:/tmp/pip-dIvOSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:28 INFO - Running setup.py (path:/tmp/pip-VT7S5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:29 INFO - Running setup.py (path:/tmp/pip-rikkaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:29 INFO - Running setup.py (path:/tmp/pip-PBiIJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:29 INFO - Running setup.py (path:/tmp/pip-zvKH_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:29 INFO - Running setup.py (path:/tmp/pip-EyeRza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:29 INFO - Running setup.py (path:/tmp/pip-i2gSQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:29 INFO - Running setup.py (path:/tmp/pip-qlob8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:29 INFO - Running setup.py (path:/tmp/pip-G3_6UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:29 INFO - Running setup.py (path:/tmp/pip-aL0kVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:29 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:29 INFO - Running setup.py (path:/tmp/pip-PsZ2dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:29 INFO - Running setup.py (path:/tmp/pip-HuJv20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:29 INFO - Running setup.py (path:/tmp/pip-wFjWOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:29 INFO - Running setup.py (path:/tmp/pip-Bc2qzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:29 INFO - Running setup.py (path:/tmp/pip-CA8xr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:31 INFO - Downloading blessings-1.6.tar.gz 18:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:32 INFO - Installing collected packages: blessings 18:31:32 INFO - Running setup.py install for blessings 18:31:32 INFO - Successfully installed blessings 18:31:32 INFO - Cleaning up... 18:31:32 INFO - Return code: 0 18:31:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:32 INFO - Reading from file tmpfile_stdout 18:31:32 INFO - Current package versions: 18:31:32 INFO - argparse == 1.2.1 18:31:32 INFO - blessings == 1.6 18:31:32 INFO - blobuploader == 1.2.4 18:31:32 INFO - docopt == 0.6.1 18:31:32 INFO - functools32 == 3.2.3-2 18:31:32 INFO - jsonschema == 2.5.1 18:31:32 INFO - manifestparser == 1.1 18:31:32 INFO - mozInstall == 1.12 18:31:32 INFO - mozcrash == 1.0 18:31:32 INFO - mozdebug == 0.1 18:31:32 INFO - mozdevice == 0.49 18:31:32 INFO - mozfile == 1.2 18:31:32 INFO - mozhttpd == 0.7 18:31:32 INFO - mozinfo == 0.9 18:31:32 INFO - mozleak == 0.1 18:31:32 INFO - mozlog == 3.4 18:31:32 INFO - moznetwork == 0.27 18:31:32 INFO - mozprocess == 0.23 18:31:32 INFO - mozprofile == 0.28 18:31:32 INFO - mozrunner == 6.13 18:31:32 INFO - mozscreenshot == 0.1 18:31:32 INFO - mozsystemmonitor == 0.3 18:31:32 INFO - moztest == 0.7 18:31:32 INFO - mozversion == 1.4 18:31:32 INFO - psutil == 3.1.1 18:31:32 INFO - requests == 1.2.3 18:31:32 INFO - wsgiref == 0.1.2 18:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:32 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')]} 18:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:32 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')]} 18:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:32 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 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:32 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 18:31:32 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 18:31:32 INFO - Using env: {'DISPLAY': ':0', 18:31:32 INFO - 'HOME': '/home/cltbld', 18:31:32 INFO - 'LANG': 'en_US.UTF-8', 18:31:32 INFO - 'LANGUAGE': 'en_US:en', 18:31:32 INFO - 'LOGNAME': 'cltbld', 18:31:32 INFO - 'MAIL': '/var/mail/cltbld', 18:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:32 INFO - 'MOZ_NO_REMOTE': '1', 18:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:32 INFO - 'NO_EM_RESTART': '1', 18:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:32 INFO - 'PWD': '/builds/slave/test', 18:31:32 INFO - 'SHELL': '/bin/bash', 18:31:32 INFO - 'SHLVL': '1', 18:31:32 INFO - 'TERM': 'linux', 18:31:32 INFO - 'TMOUT': '86400', 18:31:32 INFO - 'USER': 'cltbld', 18:31:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:32 INFO - '_': '/tools/buildbot/bin/python'} 18:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 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)) 18:31:32 INFO - Cleaning up... 18:31:32 INFO - Return code: 0 18:31:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31:32 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')]} 18:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:32 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')]} 18:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:32 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 0x9fc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd6dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, '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': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', '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 18:31:32 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 18:31:32 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 18:31:32 INFO - Using env: {'DISPLAY': ':0', 18:31:32 INFO - 'HOME': '/home/cltbld', 18:31:32 INFO - 'LANG': 'en_US.UTF-8', 18:31:32 INFO - 'LANGUAGE': 'en_US:en', 18:31:32 INFO - 'LOGNAME': 'cltbld', 18:31:32 INFO - 'MAIL': '/var/mail/cltbld', 18:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:32 INFO - 'MOZ_NO_REMOTE': '1', 18:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:32 INFO - 'NO_EM_RESTART': '1', 18:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:32 INFO - 'PWD': '/builds/slave/test', 18:31:32 INFO - 'SHELL': '/bin/bash', 18:31:32 INFO - 'SHLVL': '1', 18:31:32 INFO - 'TERM': 'linux', 18:31:32 INFO - 'TMOUT': '86400', 18:31:32 INFO - 'USER': 'cltbld', 18:31:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:32 INFO - '_': '/tools/buildbot/bin/python'} 18:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:31:32 INFO - Cleaning up... 18:31:32 INFO - Return code: 0 18:31:32 INFO - Running post-action listener: _resource_record_post_action 18:31:32 INFO - Running post-action listener: _start_resource_monitoring 18:31:33 INFO - Starting resource monitoring. 18:31:33 INFO - [mozharness: 2016-12-21 02:31:33.007674Z] Finished create-virtualenv step (success) 18:31:33 INFO - [mozharness: 2016-12-21 02:31:33.008152Z] Running install step. 18:31:33 INFO - Running pre-action listener: _resource_record_pre_action 18:31:33 INFO - Running main action method: install 18:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:33 INFO - Reading from file tmpfile_stdout 18:31:33 INFO - Detecting whether we're running mozinstall >=1.0... 18:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:31:33 INFO - Reading from file tmpfile_stdout 18:31:33 INFO - Output received: 18:31:33 INFO - Usage: mozinstall [options] installer 18:31:33 INFO - Options: 18:31:33 INFO - -h, --help show this help message and exit 18:31:33 INFO - -d DEST, --destination=DEST 18:31:33 INFO - Directory to install application into. [default: 18:31:33 INFO - "/builds/slave/test"] 18:31:33 INFO - --app=APP Application being installed. [default: firefox] 18:31:33 INFO - mkdir: /builds/slave/test/build/application 18:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:31:49 INFO - Reading from file tmpfile_stdout 18:31:49 INFO - Output received: 18:31:49 INFO - /builds/slave/test/build/application/firefox/firefox 18:31:49 INFO - Running post-action listener: _resource_record_post_action 18:31:49 INFO - [mozharness: 2016-12-21 02:31:49.396024Z] Finished install step (success) 18:31:49 INFO - [mozharness: 2016-12-21 02:31:49.396272Z] Running run-tests step. 18:31:49 INFO - Running pre-action listener: _resource_record_pre_action 18:31:49 INFO - Running main action method: run_tests 18:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:49 INFO - Python 2.7.3 18:31:49 INFO - Return code: 0 18:31:49 INFO - grabbing minidump binary from tooltool 18:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:49 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 0xd6b3f0>, '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 0xd6bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd6c1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:49 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 18:31:49 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 18:31:49 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 18:31:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:49 INFO - Return code: 0 18:31:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:31:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:31:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 18:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 18:31:49 INFO - Using env: {'DISPLAY': ':0', 18:31:49 INFO - 'HOME': '/home/cltbld', 18:31:49 INFO - 'LANG': 'en_US.UTF-8', 18:31:49 INFO - 'LANGUAGE': 'en_US:en', 18:31:49 INFO - 'LOGNAME': 'cltbld', 18:31:49 INFO - 'MAIL': '/var/mail/cltbld', 18:31:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:49 INFO - 'MOZ_NO_REMOTE': '1', 18:31:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:49 INFO - 'NO_EM_RESTART': '1', 18:31:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:49 INFO - 'PWD': '/builds/slave/test', 18:31:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:49 INFO - 'SHELL': '/bin/bash', 18:31:49 INFO - 'SHLVL': '1', 18:31:49 INFO - 'TERM': 'linux', 18:31:49 INFO - 'TMOUT': '86400', 18:31:49 INFO - 'USER': 'cltbld', 18:31:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959', 18:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:49 INFO - '_': '/tools/buildbot/bin/python'} 18:31:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 18:31:49 INFO - mozversion application_buildid: 20161220174153 18:31:49 INFO - mozversion application_changeset: 7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262 18:31:49 INFO - mozversion application_display_name: Nightly 18:31:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:49 INFO - mozversion application_name: Firefox 18:31:49 INFO - mozversion application_remotingname: firefox 18:31:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 18:31:49 INFO - mozversion application_vendor: Mozilla 18:31:49 INFO - mozversion application_version: 53.0a1 18:31:49 INFO - mozversion platform_buildid: 20161220174153 18:31:49 INFO - mozversion platform_changeset: 7a4e657e1d42e43030e6e5f3672e8e8f5a3d8262 18:31:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 18:31:49 INFO - mozversion platform_version: 53.0a1 18:31:49 INFO - using testdate: 1482287509 18:31:49 INFO - actual date: 1482287509 18:31:49 INFO - starting webserver on 'localhost:55978' 18:31:49 INFO - SUITE-START | Running 2 tests 18:31:49 INFO - TEST-START | dromaeo_css 18:31:49 INFO - operating with platform_type : linux_ 18:31:49 INFO - Initialising browser for dromaeo_css test... 18:31:49 INFO - TEST-INFO | started process 1742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQBeZbw/profile http://localhost:55978/getInfo.html) 18:31:53 INFO - PROCESS | 1742 | __metrics Screen width/height:1600/1200 18:31:53 INFO - PROCESS | 1742 | colorDepth:24 18:31:53 INFO - PROCESS | 1742 | Browser inner width/height: 1024/697 18:31:53 INFO - PROCESS | 1742 | __metrics 18:31:54 INFO - PROCESS | 1742 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:31:54 INFO - PROCESS | 1742 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:54 INFO - PROCESS | 1742 | console.error: PushService: 18:31:54 INFO - PROCESS | 1742 | stateChangeProcessEnqueue: Error transitioning state 18:31:54 INFO - PROCESS | 1742 | UnknownError 18:31:55 INFO - TEST-INFO | 1742: exit 0 18:31:55 INFO - Browser initialized. 18:31:55 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:55 INFO - TEST-INFO | started process 1823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQBeZbw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:07 INFO - PROCESS | 1823 | RSS: Main: 155598848 18:32:07 INFO - PROCESS | 1823 | 18:32:58 INFO - PROCESS | 1823 | 1482287578285 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] 18:35:12 INFO - PROCESS | 1823 | Cycle 1(1): loaded http://localhost:55978/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55978/tests/dromaeo/cssquery-ext.html) 18:35:14 INFO - PROCESS | 1823 | RSS: Main: 242610176 18:35:14 INFO - PROCESS | 1823 | 18:38:17 INFO - PROCESS | 1823 | Cycle 1(1): loaded http://localhost:55978/tests/dromaeo/cssquery-ext.html (next: http://localhost:55978/tests/dromaeo/cssquery-jquery.html) 18:38:17 INFO - PROCESS | 1823 | RSS: Main: 253657088 18:38:17 INFO - PROCESS | 1823 | 18:41:20 INFO - PROCESS | 1823 | Cycle 1(1): loaded http://localhost:55978/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55978/tests/dromaeo/cssquery-mootools.html) 18:41:20 INFO - PROCESS | 1823 | RSS: Main: 189898752 18:41:20 INFO - PROCESS | 1823 | 18:44:23 INFO - PROCESS | 1823 | Cycle 1(1): loaded http://localhost:55978/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55978/tests/dromaeo/cssquery-prototype.html) 18:44:23 INFO - PROCESS | 1823 | RSS: Main: 190164992 18:44:23 INFO - PROCESS | 1823 | 18:47:26 INFO - PROCESS | 1823 | Cycle 1(1): loaded http://localhost:55978/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55978/tests/dromaeo/cssquery-yui.html) 18:47:27 INFO - PROCESS | 1823 | RSS: Main: 193744896 18:47:27 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | Cycle 1(1): loaded http://localhost:55978/tests/dromaeo/cssquery-yui.html (next: http://localhost:55978/tests/dromaeo/cssquery-dojo.html) 18:50:30 INFO - PROCESS | 1823 | RSS: Main: 282652672 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | __start_tp_report 18:50:30 INFO - PROCESS | 1823 | _x_x_mozilla_page_load 18:50:30 INFO - PROCESS | 1823 | _x_x_mozilla_page_load_details 18:50:30 INFO - PROCESS | 1823 | |i|pagename|runs| 18:50:30 INFO - PROCESS | 1823 | |0;dojo.html;2592.1186796489196;2621.3577673470004;2642.180923913587;2644.9206523804282;2650.655414796076;4580.770961451931;4619.053094115707;4746.7863946122425;4797.0405918816205;4798.3282340472315;4687.531246875311;4696.53034696531;4727.739974301412;4750.192467280562;4828.034393121372;4266.935995960063;4280.400743895857;4318.611324980751;4347.347897815337;4427.11457708458;4008.8382370043028;4320.611144996949;4358.455193100863;4415.448068991376;4451.198784218839;4255.085156691326;4295.377170310318;4296.548862369437;4399.494058183318;4416.1609294233995;4333.43665323506;4333.609975102256;4346;4346.391505189276;4346.869593912188;4333.479982402132;4336.306191009423;4336.718113322656;4337.566243375669;4338.783060846945;4659.347691323217;4665.976670116662;4717.410323709536;4725.692829966041;4731;5068.18908974566;5068.746562671889;5118.820841270575;5124.769385377667;5134.512221338967;5089.949100508985;5105.183170692709;5108.080545501808;5114.079465696173;5136.460671629462;4599.885002874955;4708.505606911293;4758.262469317261;4759.143354196243;4818.614510839195;5046.1169295373165;5115;5152.896942061213;5189.299444575009;5191.740412979336;4966.279889416014;5008.6744361616375;5008.824691135793;5009.799608015712;5044.319016932667;5068.949310506848;5069.011542749202;5075.644704870623;5090.77091530882;5100.872478188001;4814.301926220678;4876.171050921281;4879.609631229493;4959.082569724613;5025.120603894304;4976.552110310089;5021.59827213822;5025.095482813129;5025.949740502549;5040.899182016339;4333.783310834446;4335.089631177488;4338.26249537573;4372.737635741865;4380.036391993756;3696.2237930034457;3713.053171441265;3766.548014238309;3777.527809023872;3809.2000679857542;4311.288637374861;4317.827286908551;4320.891977700519;4324.891877703082;4329.675274354436;5010.098182327216;5077.517635824591;5081.441041485434;5081.618878584121;5084.3644544431945;4285.721428107163;4318.3090705486975;4319.308910574293;4324.891877703082;4337.154254920323;4567.2920697291975;4567.383403240587;4571.588557029883;4571.977140114278;4589.5639914208095;4670.276107203389;4683.227267500824;4689.99165179488;4690.554397332248;4691.859244222747;5047.545720885138;5049.31834202378;5083.771230294719;5115.718635475085;5136.717480538607;4767.046590681947;4767.070421267958;4774.925641730583;4778.092162489255;4826.517348265145;5068.138416469283;5068.341115654941;5070.543651071422;5075;5118.846434606968;4884.8046078157895;4943.382077240345;4944.48082951285;5044.621653375938;5091.389033316026;4946.901061978812;4981.178105612534;5005.074061298671;5022.3219865317715;5027.974860125676;4957.925631115464;4969.701817890938;4970.701757894538;4973.577245933991;5036.823711170238;4763.333133361263;4798.688085274446;4908.7300198488;4917;4965.677230979974;4905.754712264444;4925.088858561177;4935.679180853233;4952.653314267966;5012.448630650699;4826.927596086131;4833.9033219334715;4837.612990960644;4842.080004799077;4882.975585122052 18:50:30 INFO - PROCESS | 1823 | |1;ext.html;506.592193284406;535.0449447735792;538.2383926743657;539.3797133296708;556.1268807971481;27864.58203126952;28569;30517.237069073257;36817.63182368175;38454.8077259614;40214.59785402152;40620.18759624813;40630.187396252055;40703;40795.59204407955;7548.207438218977;7716.074071111474;7722.652480638385;7724.304812566867;7727.227277272284;7341;7529.811754706148;7605.657745401442;7743.651535680908;9806.852897206547;9772.31593788435;10155.238357123208;10167.135793457564;10167.542460589291;10182.694519164437;9998;10061.19510439163;10066.69799906004;10078.143357814595;10085.647002354955;9800.460974646392;9885.505724713736;9892.159166470787;9897.257705672104;9900;37002.62997370019;37317;37354.43968340477;37417.81291093554;37466.81266593677;2734.125079974389;2798.2444739920106;2919.8102123361914;3014.954775678367;3320.1367644412626;25007.249782506555;25335.61996570053;25947.22158335253;27026.1892143236;27456.862715686293;3265.232670322472;3406.2846802171575;3447.9655203448146;3459.1459522053733;3501.1947252132154;244243;245085;245250;246343.9999999982;246558;45772;46319.76840115845;46329.30506042412;46411.53588464072;46428.5357146431;47084.293735593994;47313;47372.28941565879;47497;47544.286835697494;13000.609981700563;13543.119697219649;13547;13547.983901207448;13566.321683915961;10355.585776568852;10517.84223236652;10518.263721539419;10525.052745252964;10530.36817790935;11066.944665276784;11329.660110196708;11330.150238732129;11724.824127638092;15138.848611513964;6129.785457508966;6430.549861509743;6465.4827613790785;6483.643399612973;6536.803895883131;755.9546427214385;853.5049671190694;885.106042896679;911.4667919267303;967.5984466446487;25929.22212333633;26878;27160;27257.1822845311;27266.86366568194;802.8956235689323;874.4927941793744;915.1397686174975;933.9066093390607;954.346272803132;12217.266963982012;12904.354782260849;13174.670633234055;13225.73548529024;13239.66900827487;349501.07982723153;354027;355442;357417;358362;37466.62533374686;38374.23251534953;40267.798661006505;42165.367519487234;42662;1155.2894969593472;1158.4960542164185;1171.2796630072444;1174.8413964114907;1176.4764679717443;24663;24955.251342459756;25638.615420768332;26053;26066.60900086538;36419;37206.81396593;37285.62714372821;37716.245675086895;37723.62276377201;325542;327582;327993;328304;328333;23003.88498057499;23395.298141055795;23409.648855267526;23410;23423.41441463977;35698.46452303269;36467;36821;39826.203475930895;40407.19185616212;29529.409411811215;29968.25079373033;30252;30320.5451918217;30894.382112357176;31685.683143168273;31850;31916.042518724476;32455;32892.34215315728;31383;31483;31535.684643154193;32027.519587206676;32137.67862321347;27207.86396068007;28736.856315719124;28775.71224287814;28928.42143157167;28966;22184.556308874056;22390.66414003824;22659.886700566392;22669.319920402413;22792.202272920367 18:50:30 INFO - PROCESS | 1823 | |2;jquery.html;343.0737010072804;363.13573694606833;368.6718820249978;371.8512594962016;373.71224157398785;12376.38118094095;12692.746145077093;15735.291911863966;16013.83986160138;16538.7519187212;16008.679826403464;16101.114438705867;16408.09755463449;16629.750553741673;16694.248758805854;4713.882152946187;5010.924836127452;5228.241904923784;5234.92147617786;5235.450277720842;5979.52163826895;6086.908696369558;6128.908066379008;6133.938660613404;6136.140940268366;5204.34945631796;5457.836264912059;6091.360407157251;6094.60385074971;6099.146119543268;5491.258680078198;5531.834044978657;5537.252470916415;5539.113741801333;5539.362973258067;5163.948360516422;5175.171972484384;5175.896482070375;5182.689038657655;5356.035913535562;11845.466953987092;12045.57840475588;12081.758364832742;12084.758304833855;18187.27250909962;1731.6190438103597;1820.1718218210683;1898.8291053805085;1971.8915459649713;2267.9546409071727;8392.664293428255;9402.670906518308;9426;11897.048236141089;12104;1695.4235559909566;1733.306677329066;1742.0941110622405;1759.5716517879343;1780.9287628494844;56602.43397566054;62010.37989620091;63218.0517292241;63434.36565634331;65517.672411638116;9261.44431334122;9515.619375225051;9576.616935322509;9678.612855485842;9726.221902247804;13291.933540332237;13298.73402531944;13323.800142997863;13323.93338033323;13326.40031198579;6587.934120658828;6692.531522793358;6792.660366981631;6814.216365117975;6815.863682726318;6194.287656919422;6227;6237.438630523275;6379.202599675041;6381.489480841522;4068.8575899843954;4102.958970410257;4152.190322887069;4187.790610469464;4191.91616167675;1781.1806568978416;1799.1993562864661;1799.4961410804995;1818.399928023749;1836.825501577348;846.7502086884384;847.1951645936385;903.6746771162376;932.1238036246043;945.1493655709779;15668.373265069498;15737.68524629501;16015.359385624444;16673.16634168311;17446.47660570185;792.8216151366012;820.6553247636007;848.4273115647037;870.7287360453682;904.841652710773;8389.687654933068;9377.156055954996;9418.717438476857;9474.526273686288;9553.426794392359;275455;326834;330068.0489792655;338150;340086;71092.64453677803;71203;71769;72002.63998679972;74671.62664186645;866.8266346730679;876.0801158783252;877.4252864373846;881.7883707910055;889.5418859287472;14364.127174565338;14532;14538.491152810024;14969.32638031313;15328.080315181125;26808.463830723667;32181.839090805333;33685.49471757877;34584.48123278103;34644;44711.55288447204;45087;46372.53627463817;47146.76426617845;48343.27485087656;4933.227028104474;5054.949450505448;7510.173880873209;7560.618405202344;7738;10326.793464130826;11406.201565890307;11577.768444631229;11642.6507204784;12319.507219711111;10045.598176072876;10164;10226.897731022595;10236.692899213036;10571.524281407508;9042.728718138467;9357.906420935704;9449.43303401798;9905.257105716955;10628.671416072992;6315.463185629089;9659.517024148623;9777.462239576607;9871.309008369633;10180.338278011906;7360.96319518399;7470.589117598584;7489;7825.708233523971;7921.32668723165;8857.291416686521;9844.310898237054;9903.702888913343;9989.650362237577;10021.498925053864 18:50:30 INFO - PROCESS | 1823 | |3;mootools.html;1236.6475554466979;1323.7683405404055;1338.5515852189517;1426.2654732812596;1682.1084825042724;6534.085228068075;7166.462515311353;7380.9630951845165;7402.888956665648;7572.091349038109;7379.114506259257;7451.329380355767;7454.1055073391135;7455.105387353512;7456.440766942474;1830.8443782278523;1906.3423119023914;2122.6285400054967;2161.470439742265;2166.9133234670594;2083.739337700692;2149.161826887515;2153.6984822124914;2158.2446143849684;2166.7941545553226;2078.0960282277183;2096.3501314592527;2102.1906565972135;2107.862988905724;2108.3569511299124;2142.1431427429075;2157.374361435182;2163.621366260898;2167.2956289205913;2170.6852506386485;2072.5336799220204;2102.29573093014;2187.3984654220058;2188.39819049761;2188.4638263625516;7199.784006479814;7412.88880666785;7414.332710056121;7421.406287496988;7441.181470038346;4307.289297265947;4462.397576327219;4471;4474.440694913136;4479.3057076153245;2834.2347566157227;2871.1817132117453;2937.236318557169;3029.3638335949477;3041.406925649499;870.425519157353;891.140049851896;920.1028996728203;932.8647346134841;933.9066093390675;9880.950595246977;9912.256580756472;9939.155171810406;9943.850842237445;9956.203503719686;3991.1219531702977;4153.7300075494995;4243.787810609457;4257.148570285894;4261.382099595603;8464.788380290542;8465.534395608302;8466.788330291793;8477.152284771597;8478.710748098103;4589.678722489461;4591.058832939239;4592.127495775792;4595.908081838412;4597.264437689953;3618.9819050904575;4006.6193711597352;4037.717359784787;4099.344104943195;4111.712180147361;3460.6539346065697;3475.2528206435736;3590.0665826885197;3599.3521166190335;3605.7836529808296;1160.9709757256137;1178.7406770510645;1186.0511590727556;1188.364225139546;1188.9643310700692;718.5796309159095;822.1696086952221;833.329170018129;855.3883972959239;874.6719980007497;4853.247746599282;4950.084234416644;5177.508136727005;5178.404483484447;5242;392.7996721671968;411.868202175301;414.6392638404607;418.03434067304624;419.7963987466077;3464.3590935676475;3470.305938812248;3504.6144924058335;3535.041371747562;3631.0740761106276;8634.827303453896;8848.380613357132;8864.20222179994;8925.687600934067;8947.686830961014;8217.95891020541;8519.872201916976;8855.114488551222;8869.512176830336;8872.556372181365;6788.864222715518;7009.719611215596;7014.964925175342;7016.929830701628;7019.333163349474;4971.801127954841;5082.593392528663;5093.18509038552;5143.92284115731;5225.242339860775;8071.273585377344;8091.433599647969;8195.180481951757;8221.218984196492;8321.875171872305;8120.959395202986;8227;8371.874421883555;8405.285550728013;8489.68409896467;6131.264248290234;6286.874262514632;6463.385978332051;6598.802035938929;6601.207855057424;6987.720491180499;7044.506884518035;7045.154581450259;7049.365557099885;7063.505554611128;6314.968425157845;6400.199975003125;6446.484281257582;6479.060536222316;6679.933200668125;6379.362063793584;6435.066915297162;6491.740330386731;6504.707288171949;6563.1796025496815;5969.343372229138;6031.60794548353;6042.90935635957;6048.334683184934;6048.848778780566;4341.9782901086555;4342.891427714332;4452.777361131995;4490.259107247268;4506.459224893034;4258.531561528292;4387.539308372575;4425.756583387816;4464.687471876938;4596.931046034245 18:50:30 INFO - PROCESS | 1823 | |4;prototype.html;354.8119496666767;357.9767315124517;359.955005624297;360.13387802335393;370.20590832663936;4756.762161891909;4764.475907650164;4765.1899177139885;4777.6177905767545;4781.713097214165;4447.843560674224;4722.763861806913;4736.715797052179;4742.573168414842;4750.168720473912;3686.8340924658387;3729.589745128034;3741.5697194822537;3754.699624030071;3766.642168993941;3796.6711650922234;3797.506324177853;3801.3157631626295;3806.7715937043868;3811.8856434307013;3769.641884021027;3789.658930696237;3794.886153415402;3796.1838204785968;3802.543694756633;3595.694365978895;3604.7476676632646;3706.1661126246513;3782.981085094557;3791.696664266852;3414.1805966567845;3420.555327807394;3423.7945723256685;3431.725461963037;3440.449528075496;4603.447586289667;4608.400907881987;4612.423447069116;4621.861344159681;4635.351050852883;3988.441618173458;4143.9171216575805;4310.849120280775;4336.176126535948;4339.153864996327;4899.142650036265;4899.265110233494;4903.975480122613;4912.680675756064;4913.238448040559;3527.0829584308008;3549.9882533477835;3551.2187318789825;3553.236054248349;3559.893203203908;4970.713471803011;5152.484751524855;5191.402988656278;5191.8182863599595;5203.635745497817;3596.298721749286;3607.8917632471066;3611.7719975208556;3626.3653860574295;3630.110622897407;3647.015305867454;3647.835847386874;3654.086478380405;3654.8538058477893;3686.4101743721412;4152.792360381969;4153.979230103831;4161.9583804161575;4165.708400411942;4166.437530933345;4670.766461676971;4886.120498310267;4957.107720610325;4957.90084198314;4979.2033274676605;1234.826914431276;1236.6722818453118;1243.440451796695;1244.825724398585;1249.4127759952808;833.8832563441123;854.2055888024197;863.8704194370895;865.6796985115548;867.9305655547541;4171.958280417218;4229.830806767694;4267.551907049778;4332.566743325642;4354.216241076637;5068.831980012492;5072.5688316493515;5085.7202853842655;5088.313077734532;5091.116286973844;4330.133973205371;4332.610065094157;4335.263005289108;4336.392904993303;4347.413099231627;2536.7463253674487;2599.583227141213;2613.725558816335;2650.867456627161;2655.522006038893;4602.194615942197;4608.239640459286;4622.590110016477;4650.255959046536;4669.295707066896;5074.467180945948;5095.668781529189;5121.948780512222;5124.871878203075;5128.692278463304;4527.298268768346;4632.698874573142;4643.865390861155;4649.65127615437;4669.206234940137;4986.551210391082;4987.451380348086;5003.599712022957;5012.498750125104;5018.799248030184;4916.213405855046;4919.557239848431;4920.6555541111775;4923.926141107814;4942.357493525818;4485.977570112129;4551.340055691972;4572.88567785808;4605.355250265033;4617.79219935097;3494.668006539375;3531.5408996830247;3539.663731945461;3546.5744110706883;3580.138328069337;4970.310094567864;4980.427250866197;4987.900241995212;4990.030931595695;5005.799768009385;4673.415823022122;4835.323054772264;4869.878253043702;4912.336834527293;4913.508649135058;4848.224284114666;4856.368672072608;4859.562639362474;4866.270059491105;4875.926861097016;4729.408823897013;4776.829676729223;4784.258439941815;4789.640776941674;4828.61371090319;4681.625469962463;4817.108834865561;4828.227483602606;4831.4685384608365;4866.683665561727;4660.627149828073;4710.29345598163;4716.6698331116495;4716.740579268173;4729.172394830822 18:50:30 INFO - PROCESS | 1823 | |5;yui.html;616.343594072313;616.8519555306725;668.3483603486599;719.6689522819506;721.4192574977142;11657.00915422189;11875.70310742232;12025.879741202585;12050.216735912181;12107.334096624692;11346.886531134685;11438.370889601068;12173.391330433466;13065.281409522497;13307.800382994239;778.0313509680442;789.0058526256929;789.9052113746329;790.8260182759784;790.9723159689412;628.2178687534026;646.5118835279382;757.9507332023401;782.4601025292559;788.1212448120361;821.2526600793279;824.1346586084617;824.9092599814015;825.5954582254682;827.896512935883;815.7919730468692;819.4345901328124;819.9955055057536;820.2658620534588;820.3724151024442;481.52810245958807;490.13491188053115;585.166138252992;599.9820005399845;603.6559161278074;13601.183928964294;13605.931970340183;13606.183628982293;13611.931940340235;13620.250886201255;12203.901648851557;12335.629931102081;12556.81164782529;12564.371781410984;12775.23348599087;9934.751631209205;10195.082442580204;10234.48827558619;11951.940240298829;11956.641300760992;4044.5550989391145;4052.371882358239;4077.1234184650148;4079.102597428561;4080.4287399764057;61588;62022.68988655028;62426;62845;63209;9420.387674801115;9539.761505962406;9547.331686781998;9637.084476974676;9737.367071140354;16695.332186712396;16830.57923551921;16926.492205233862;17532.29870805179;17749.645007099785;6603.141591593062;6870.175578930561;6969.895451568231;7109.715611375487;7127.536710113826;3601.7118630509494;3641.9089522761615;3649.1606930406156;3664.3953747631876;3666.8533258669354;2715.7420045095682;2791.6231308773054;2852.6434195725533;2883.163882474101;2887.321479452327;503.7179179659403;505.7875692209281;507.57110241845584;511.66230288009734;520.4587229281505;609.3053918532876;613.2303958532103;633.1104797759156;633.3729607688356;636.2746469025315;7904.802379940547;8283.917160828314;8624.568771561397;8647.308215342757;8749.606267717969;503.4286085293219;506.9797208111708;611.6024584020333;627.2097157581389;636.9936300637027;3730.9440358394645;3797.9430308545393;3852.3836186210074;4015.1768887378603;4058.817353219112;43457.56542434599;44056.559434405885;63675.68162159252;67676;67756.32243677501;43660.78169609132;50287.74856125769;50738.23892641613;51531.22703159456;51870;40508.39237411441;46414;46484.302735458994;47536.52463475321;48575.27137092946;8539.054629601864;8866.73399798007;9052.728418147466;9084.500352480676;9219.953900230455;31452;31900.521492177173;34190;34383.48424773681;34524.101174435644;38051.80974095112;39208.60791392164;39232.607673922896;43069.35395969;44391.11217775562;11254.493547790205;11407.315561066363;11855.466504007169;12250.693732656755;12336.876631233932;12145.939270303592;12228;12670;13272.601821945356;13657.765337019173;11844.170908036354;11892.76214475723;12053;12348.392590172616;12696.619101426972;11835.881641183822;11933.82099268529;11964.401779911144;12015.879841201826;12272.263664180178;11270.323780573191;11453.942730286295;11537.94231028873;11757.353345566076;11800.881991179978;8893.199612034949;9012.459252444874;9120.5439728012;9126.13301736334;9171.816563668823;9640.614375425184;10204.642837500653;10339.689809305733;10348;10352.068313851789 18:50:30 INFO - PROCESS | 1823 | __end_tp_report 18:50:30 INFO - PROCESS | 1823 | __start_cc_report 18:50:30 INFO - PROCESS | 1823 | _x_x_mozilla_cycle_collect,1487 18:50:30 INFO - PROCESS | 1823 | __end_cc_report 18:50:30 INFO - PROCESS | 1823 | __startTimestamp1482288630859__endTimestamp 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | ------- Summary: start ------- 18:50:30 INFO - PROCESS | 1823 | Number of tests: 6 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | [#0] dojo.html Cycles:165 Average:4671.66 Median:4798.33 stddev:495.52 (10.3%) stddev-sans-first:469.42 18:50:30 INFO - PROCESS | 1823 | Values: 2592.1 2621.4 2642.2 2644.9 2650.7 4580.8 4619.1 4746.8 4797.0 4798.3 4687.5 4696.5 4727.7 4750.2 4828.0 4266.9 4280.4 4318.6 4347.3 4427.1 4008.8 4320.6 4358.5 4415.4 4451.2 4255.1 4295.4 4296.5 4399.5 4416.2 4333.4 4333.6 4346.0 4346.4 4346.9 4333.5 4336.3 4336.7 4337.6 4338.8 4659.3 4666.0 4717.4 4725.7 4731.0 5068.2 5068.7 5118.8 5124.8 5134.5 5089.9 5105.2 5108.1 5114.1 5136.5 4599.9 4708.5 4758.3 4759.1 4818.6 5046.1 5115.0 5152.9 5189.3 5191.7 4966.3 5008.7 5008.8 5009.8 5044.3 5068.9 5069.0 5075.6 5090.8 5100.9 4814.3 4876.2 4879.6 4959.1 5025.1 4976.6 5021.6 5025.1 5025.9 5040.9 4333.8 4335.1 4338.3 4372.7 4380.0 3696.2 3713.1 3766.5 3777.5 3809.2 4311.3 4317.8 4320.9 4324.9 4329.7 5010.1 5077.5 5081.4 5081.6 5084.4 4285.7 4318.3 4319.3 4324.9 4337.2 4567.3 4567.4 4571.6 4572.0 4589.6 4670.3 4683.2 4690.0 4690.6 4691.9 5047.5 5049.3 5083.8 5115.7 5136.7 4767.0 4767.1 4774.9 4778.1 4826.5 5068.1 5068.3 5070.5 5075.0 5118.8 4884.8 4943.4 4944.5 5044.6 5091.4 4946.9 4981.2 5005.1 5022.3 5028.0 4957.9 4969.7 4970.7 4973.6 5036.8 4763.3 4798.7 4908.7 4917.0 4965.7 4905.8 4925.1 4935.7 4952.7 5012.4 4826.9 4833.9 4837.6 4842.1 4883.0 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | [#1] ext.html Cycles:180 Average:44589.40 Median:165423.62 stddev:82369.75 (49.8%) stddev-sans-first:82534.32 18:50:30 INFO - PROCESS | 1823 | Values: 506.6 535.0 538.2 539.4 556.1 27864.6 28569.0 30517.2 36817.6 38454.8 40214.6 40620.2 40630.2 40703.0 40795.6 7548.2 7716.1 7722.7 7724.3 7727.2 7341.0 7529.8 7605.7 7743.7 9806.9 9772.3 10155.2 10167.1 10167.5 10182.7 9998.0 10061.2 10066.7 10078.1 10085.6 9800.5 9885.5 9892.2 9897.3 9900.0 37002.6 37317.0 37354.4 37417.8 37466.8 2734.1 2798.2 2919.8 3015.0 3320.1 25007.2 25335.6 25947.2 27026.2 27456.9 3265.2 3406.3 3448.0 3459.1 3501.2 244243.0 245085.0 245250.0 246344.0 246558.0 45772.0 46319.8 46329.3 46411.5 46428.5 47084.3 47313.0 47372.3 47497.0 47544.3 13000.6 13543.1 13547.0 13548.0 13566.3 10355.6 10517.8 10518.3 10525.1 10530.4 11066.9 11329.7 11330.2 11724.8 15138.8 6129.8 6430.5 6465.5 6483.6 6536.8 756.0 853.5 885.1 911.5 967.6 25929.2 26878.0 27160.0 27257.2 27266.9 802.9 874.5 915.1 933.9 954.3 12217.3 12904.4 13174.7 13225.7 13239.7 349501.1 354027.0 355442.0 357417.0 358362.0 37466.6 38374.2 40267.8 42165.4 42662.0 1155.3 1158.5 1171.3 1174.8 1176.5 24663.0 24955.3 25638.6 26053.0 26066.6 36419.0 37206.8 37285.6 37716.2 37723.6 325542.0 327582.0 327993.0 328304.0 328333.0 23003.9 23395.3 23409.6 23410.0 23423.4 35698.5 36467.0 36821.0 39826.2 40407.2 29529.4 29968.3 30252.0 30320.5 30894.4 31685.7 31850.0 31916.0 32455.0 32892.3 31383.0 31483.0 31535.7 32027.5 32137.7 27207.9 28736.9 28775.7 28928.4 28966.0 22184.6 22390.7 22659.9 22669.3 22792.2 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | [#2] jquery.html Cycles:180 Average:21405.89 Median:5175.53 stddev:53538.11 (1034.4%) stddev-sans-first:53664.94 18:50:30 INFO - PROCESS | 1823 | Values: 343.1 363.1 368.7 371.9 373.7 12376.4 12692.7 15735.3 16013.8 16538.8 16008.7 16101.1 16408.1 16629.8 16694.2 4713.9 5010.9 5228.2 5234.9 5235.5 5979.5 6086.9 6128.9 6133.9 6136.1 5204.3 5457.8 6091.4 6094.6 6099.1 5491.3 5531.8 5537.3 5539.1 5539.4 5163.9 5175.2 5175.9 5182.7 5356.0 11845.5 12045.6 12081.8 12084.8 18187.3 1731.6 1820.2 1898.8 1971.9 2268.0 8392.7 9402.7 9426.0 11897.0 12104.0 1695.4 1733.3 1742.1 1759.6 1780.9 56602.4 62010.4 63218.1 63434.4 65517.7 9261.4 9515.6 9576.6 9678.6 9726.2 13291.9 13298.7 13323.8 13323.9 13326.4 6587.9 6692.5 6792.7 6814.2 6815.9 6194.3 6227.0 6237.4 6379.2 6381.5 4068.9 4103.0 4152.2 4187.8 4191.9 1781.2 1799.2 1799.5 1818.4 1836.8 846.8 847.2 903.7 932.1 945.1 15668.4 15737.7 16015.4 16673.2 17446.5 792.8 820.7 848.4 870.7 904.8 8389.7 9377.2 9418.7 9474.5 9553.4 275455.0 326834.0 330068.0 338150.0 340086.0 71092.6 71203.0 71769.0 72002.6 74671.6 866.8 876.1 877.4 881.8 889.5 14364.1 14532.0 14538.5 14969.3 15328.1 26808.5 32181.8 33685.5 34584.5 34644.0 44711.6 45087.0 46372.5 47146.8 48343.3 4933.2 5054.9 7510.2 7560.6 7738.0 10326.8 11406.2 11577.8 11642.7 12319.5 10045.6 10164.0 10226.9 10236.7 10571.5 9042.7 9357.9 9449.4 9905.3 10628.7 6315.5 9659.5 9777.5 9871.3 10180.3 7361.0 7470.6 7489.0 7825.7 7921.3 8857.3 9844.3 9903.7 9989.7 10021.5 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | [#3] mootools.html Cycles:180 Average:4871.60 Median:5160.72 stddev:2659.64 (51.5%) stddev-sans-first:2653.07 18:50:30 INFO - PROCESS | 1823 | Values: 1236.6 1323.8 1338.6 1426.3 1682.1 6534.1 7166.5 7381.0 7402.9 7572.1 7379.1 7451.3 7454.1 7455.1 7456.4 1830.8 1906.3 2122.6 2161.5 2166.9 2083.7 2149.2 2153.7 2158.2 2166.8 2078.1 2096.4 2102.2 2107.9 2108.4 2142.1 2157.4 2163.6 2167.3 2170.7 2072.5 2102.3 2187.4 2188.4 2188.5 7199.8 7412.9 7414.3 7421.4 7441.2 4307.3 4462.4 4471.0 4474.4 4479.3 2834.2 2871.2 2937.2 3029.4 3041.4 870.4 891.1 920.1 932.9 933.9 9881.0 9912.3 9939.2 9943.9 9956.2 3991.1 4153.7 4243.8 4257.1 4261.4 8464.8 8465.5 8466.8 8477.2 8478.7 4589.7 4591.1 4592.1 4595.9 4597.3 3619.0 4006.6 4037.7 4099.3 4111.7 3460.7 3475.3 3590.1 3599.4 3605.8 1161.0 1178.7 1186.1 1188.4 1189.0 718.6 822.2 833.3 855.4 874.7 4853.2 4950.1 5177.5 5178.4 5242.0 392.8 411.9 414.6 418.0 419.8 3464.4 3470.3 3504.6 3535.0 3631.1 8634.8 8848.4 8864.2 8925.7 8947.7 8218.0 8519.9 8855.1 8869.5 8872.6 6788.9 7009.7 7015.0 7016.9 7019.3 4971.8 5082.6 5093.2 5143.9 5225.2 8071.3 8091.4 8195.2 8221.2 8321.9 8121.0 8227.0 8371.9 8405.3 8489.7 6131.3 6286.9 6463.4 6598.8 6601.2 6987.7 7044.5 7045.2 7049.4 7063.5 6315.0 6400.2 6446.5 6479.1 6679.9 6379.4 6435.1 6491.7 6504.7 6563.2 5969.3 6031.6 6042.9 6048.3 6048.8 4342.0 4342.9 4452.8 4490.3 4506.5 4258.5 4387.5 4425.8 4464.7 4596.9 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | [#4] prototype.html Cycles:180 Average:4077.71 Median:4615.11 stddev:1157.30 (25.1%) stddev-sans-first:1126.31 18:50:30 INFO - PROCESS | 1823 | Values: 354.8 358.0 360.0 360.1 370.2 4756.8 4764.5 4765.2 4777.6 4781.7 4447.8 4722.8 4736.7 4742.6 4750.2 3686.8 3729.6 3741.6 3754.7 3766.6 3796.7 3797.5 3801.3 3806.8 3811.9 3769.6 3789.7 3794.9 3796.2 3802.5 3595.7 3604.7 3706.2 3783.0 3791.7 3414.2 3420.6 3423.8 3431.7 3440.4 4603.4 4608.4 4612.4 4621.9 4635.4 3988.4 4143.9 4310.8 4336.2 4339.2 4899.1 4899.3 4904.0 4912.7 4913.2 3527.1 3550.0 3551.2 3553.2 3559.9 4970.7 5152.5 5191.4 5191.8 5203.6 3596.3 3607.9 3611.8 3626.4 3630.1 3647.0 3647.8 3654.1 3654.9 3686.4 4152.8 4154.0 4162.0 4165.7 4166.4 4670.8 4886.1 4957.1 4957.9 4979.2 1234.8 1236.7 1243.4 1244.8 1249.4 833.9 854.2 863.9 865.7 867.9 4172.0 4229.8 4267.6 4332.6 4354.2 5068.8 5072.6 5085.7 5088.3 5091.1 4330.1 4332.6 4335.3 4336.4 4347.4 2536.7 2599.6 2613.7 2650.9 2655.5 4602.2 4608.2 4622.6 4650.3 4669.3 5074.5 5095.7 5121.9 5124.9 5128.7 4527.3 4632.7 4643.9 4649.7 4669.2 4986.6 4987.5 5003.6 5012.5 5018.8 4916.2 4919.6 4920.7 4923.9 4942.4 4486.0 4551.3 4572.9 4605.4 4617.8 3494.7 3531.5 3539.7 3546.6 3580.1 4970.3 4980.4 4987.9 4990.0 5005.8 4673.4 4835.3 4869.9 4912.3 4913.5 4848.2 4856.4 4859.6 4866.3 4875.9 4729.4 4776.8 4784.3 4789.6 4828.6 4681.6 4817.1 4828.2 4831.5 4866.7 4660.6 4710.3 4716.7 4716.7 4729.2 18:50:30 INFO - PROCESS | 1823 | 18:50:30 INFO - PROCESS | 1823 | [#5] yui.html Cycles:180 Average:13935.17 Median:44223.84 stddev:16708.96 (37.8%) stddev-sans-first:16725.90 18:50:30 INFO - PROCESS | 1823 | Values: 616.3 616.9 668.3 719.7 721.4 11657.0 11875.7 12025.9 12050.2 12107.3 11346.9 11438.4 12173.4 13065.3 13307.8 778.0 789.0 789.9 790.8 791.0 628.2 646.5 758.0 782.5 788.1 821.3 824.1 824.9 825.6 827.9 815.8 819.4 820.0 820.3 820.4 481.5 490.1 585.2 600.0 603.7 13601.2 13605.9 13606.2 13611.9 13620.3 12203.9 12335.6 12556.8 12564.4 12775.2 9934.8 10195.1 10234.5 11951.9 11956.6 4044.6 4052.4 4077.1 4079.1 4080.4 61588.0 62022.7 62426.0 62845.0 63209.0 9420.4 9539.8 9547.3 9637.1 9737.4 16695.3 16830.6 16926.5 17532.3 17749.6 6603.1 6870.2 6969.9 7109.7 7127.5 3601.7 3641.9 3649.2 3664.4 3666.9 2715.7 2791.6 2852.6 2883.2 2887.3 503.7 505.8 507.6 511.7 520.5 609.3 613.2 633.1 633.4 636.3 7904.8 8283.9 8624.6 8647.3 8749.6 503.4 507.0 611.6 627.2 637.0 3730.9 3797.9 3852.4 4015.2 4058.8 43457.6 44056.6 63675.7 67676.0 67756.3 43660.8 50287.7 50738.2 51531.2 51870.0 40508.4 46414.0 46484.3 47536.5 48575.3 8539.1 8866.7 9052.7 9084.5 9220.0 31452.0 31900.5 34190.0 34383.5 34524.1 38051.8 39208.6 39232.6 43069.4 44391.1 11254.5 11407.3 11855.5 12250.7 12336.9 12145.9 12228.0 12670.0 13272.6 13657.8 11844.2 11892.8 12053.0 12348.4 12696.6 11835.9 11933.8 11964.4 12015.9 12272.3 11270.3 11453.9 11537.9 11757.4 11800.9 8893.2 9012.5 9120.5 9126.1 9171.8 9640.6 10204.6 10339.7 10348.0 10352.1 18:50:30 INFO - PROCESS | 1823 | -------- Summary: end -------- 18:50:30 INFO - PROCESS | 1823 | 18:50:31 INFO - TEST-INFO | 1823: exit 0 18:50:31 INFO - TEST-OK | dromaeo_css | took 1122049ms 18:50:31 INFO - TEST-START | kraken 18:50:31 INFO - operating with platform_type : linux_ 18:50:31 INFO - Initialising browser for kraken test... 18:50:31 INFO - TEST-INFO | started process 2124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXmCmjY/profile http://localhost:55978/getInfo.html) 18:50:35 INFO - PROCESS | 2124 | __metrics Screen width/height:1600/1200 18:50:35 INFO - PROCESS | 2124 | colorDepth:24 18:50:35 INFO - PROCESS | 2124 | Browser inner width/height: 1024/697 18:50:35 INFO - PROCESS | 2124 | __metrics 18:50:36 INFO - PROCESS | 2124 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:50:36 INFO - PROCESS | 2124 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:50:36 INFO - PROCESS | 2124 | console.error: PushService: 18:50:36 INFO - PROCESS | 2124 | stateChangeProcessEnqueue: Error transitioning state 18:50:36 INFO - PROCESS | 2124 | UnknownError 18:50:37 INFO - TEST-INFO | 2124: exit 0 18:50:37 INFO - Browser initialized. 18:50:37 INFO - Running cycle 1/1 for kraken test... 18:50:37 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXmCmjY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:49 INFO - PROCESS | 2206 | RSS: Main: 155418624 18:50:49 INFO - PROCESS | 2206 | 18:50:52 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55978/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:53 INFO - PROCESS | 2206 | RSS: Main: 209408000 18:50:53 INFO - PROCESS | 2206 | 18:50:55 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55978/tests/kraken/driver.html?testName=audio-dft) 18:50:56 INFO - PROCESS | 2206 | RSS: Main: 188375040 18:50:56 INFO - PROCESS | 2206 | 18:50:59 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55978/tests/kraken/driver.html?testName=audio-fft) 18:51:00 INFO - PROCESS | 2206 | RSS: Main: 270213120 18:51:00 INFO - PROCESS | 2206 | 18:51:02 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55978/tests/kraken/driver.html?testName=audio-oscillator) 18:51:03 INFO - PROCESS | 2206 | RSS: Main: 181547008 18:51:03 INFO - PROCESS | 2206 | 18:51:05 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55978/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:51:05 INFO - PROCESS | 2206 | RSS: Main: 185012224 18:51:05 INFO - PROCESS | 2206 | 18:51:09 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55978/tests/kraken/driver.html?testName=imaging-darkroom) 18:51:09 INFO - PROCESS | 2206 | RSS: Main: 223813632 18:51:09 INFO - PROCESS | 2206 | 18:51:13 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55978/tests/kraken/driver.html?testName=imaging-desaturate) 18:51:13 INFO - PROCESS | 2206 | RSS: Main: 224219136 18:51:13 INFO - PROCESS | 2206 | 18:51:16 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55978/tests/kraken/driver.html?testName=json-parse-financial) 18:51:17 INFO - PROCESS | 2206 | RSS: Main: 222158848 18:51:17 INFO - PROCESS | 2206 | 18:51:19 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55978/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:19 INFO - PROCESS | 2206 | RSS: Main: 184782848 18:51:19 INFO - PROCESS | 2206 | 18:51:21 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:51:22 INFO - PROCESS | 2206 | RSS: Main: 192491520 18:51:22 INFO - PROCESS | 2206 | 18:51:24 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:51:25 INFO - PROCESS | 2206 | RSS: Main: 199950336 18:51:25 INFO - PROCESS | 2206 | 18:51:27 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:51:28 INFO - PROCESS | 2206 | RSS: Main: 209698816 18:51:28 INFO - PROCESS | 2206 | 18:51:31 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:32 INFO - PROCESS | 2206 | RSS: Main: 205914112 18:51:32 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:55978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55978/tests/kraken/driver.html?testName=ai-astar) 18:51:34 INFO - PROCESS | 2206 | RSS: Main: 256585728 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | __start_tp_report 18:51:34 INFO - PROCESS | 2206 | _x_x_mozilla_page_load 18:51:34 INFO - PROCESS | 2206 | _x_x_mozilla_page_load_details 18:51:34 INFO - PROCESS | 2206 | |i|pagename|runs| 18:51:34 INFO - PROCESS | 2206 | |0;ai-astar;107;116;114;115;117;114;115;116;114;115 18:51:34 INFO - PROCESS | 2206 | |1;audio-beat-detection;149;125;128;182;134;127;124;129;150;125 18:51:34 INFO - PROCESS | 2206 | |2;audio-dft;161;163;161;162;162;162;162;162;161;161 18:51:34 INFO - PROCESS | 2206 | |3;audio-fft;75;76;76;108;80;77;77;77;95;76 18:51:34 INFO - PROCESS | 2206 | |4;audio-oscillator;84;85;119;85;85;84;105;85;86;104 18:51:34 INFO - PROCESS | 2206 | |5;imaging-gaussian-blur;128;127;127;126;127;127;127;128;127;128 18:51:34 INFO - PROCESS | 2206 | |6;imaging-darkroom;147;147;148;148;147;148;148;148;148;147 18:51:34 INFO - PROCESS | 2206 | |7;imaging-desaturate;95;95;95;95;95;96;96;96;96;95 18:51:34 INFO - PROCESS | 2206 | |8;json-parse-financial;63;63;64;63;64;64;64;64;64;65 18:51:34 INFO - PROCESS | 2206 | |9;json-stringify-tinderbox;66;65;65;65;67;66;66;66;66;66 18:51:34 INFO - PROCESS | 2206 | |10;stanford-crypto-aes;86;95;85;84;88;86;86;86;87;86 18:51:34 INFO - PROCESS | 2206 | |11;stanford-crypto-ccm;137;135;137;145;136;136;138;141;180;146 18:51:34 INFO - PROCESS | 2206 | |12;stanford-crypto-pbkdf2;178;181;180;180;178;184;181;185;184;181 18:51:34 INFO - PROCESS | 2206 | |13;stanford-crypto-sha256-iterative;70;69;69;72;71;68;68;73;67;67 18:51:34 INFO - PROCESS | 2206 | __end_tp_report 18:51:34 INFO - PROCESS | 2206 | __start_cc_report 18:51:34 INFO - PROCESS | 2206 | _x_x_mozilla_cycle_collect,1939 18:51:34 INFO - PROCESS | 2206 | __end_cc_report 18:51:34 INFO - PROCESS | 2206 | __startTimestamp1482288694388__endTimestamp 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | ------- Summary: start ------- 18:51:34 INFO - PROCESS | 2206 | Number of tests: 14 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:2.75 (2.4%) stddev-sans-first:1.05 18:51:34 INFO - PROCESS | 2206 | Values: 107.0 116.0 114.0 115.0 117.0 114.0 115.0 116.0 114.0 115.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:131.50 stddev:18.40 (14.0%) stddev-sans-first:19.03 18:51:34 INFO - PROCESS | 2206 | Values: 149.0 125.0 128.0 182.0 134.0 127.0 124.0 129.0 150.0 125.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 18:51:34 INFO - PROCESS | 2206 | Values: 161.0 163.0 161.0 162.0 162.0 162.0 162.0 162.0 161.0 161.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#3] audio-fft Cycles:10 Average:81.70 Median:77.00 stddev:10.95 (14.2%) stddev-sans-first:11.35 18:51:34 INFO - PROCESS | 2206 | Values: 75.0 76.0 76.0 108.0 80.0 77.0 77.0 77.0 95.0 76.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:85.00 stddev:12.48 (14.7%) stddev-sans-first:12.88 18:51:34 INFO - PROCESS | 2206 | Values: 84.0 85.0 119.0 85.0 85.0 84.0 105.0 85.0 86.0 104.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#5] imaging-gaussian-blur Cycles:10 Average:127.20 Median:127.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 18:51:34 INFO - PROCESS | 2206 | Values: 128.0 127.0 127.0 126.0 127.0 127.0 127.0 128.0 127.0 128.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.52 (0.3%) stddev-sans-first:0.50 18:51:34 INFO - PROCESS | 2206 | Values: 147.0 147.0 148.0 148.0 147.0 148.0 148.0 148.0 148.0 147.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:51:34 INFO - PROCESS | 2206 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 18:51:34 INFO - PROCESS | 2206 | Values: 63.0 63.0 64.0 63.0 64.0 64.0 64.0 64.0 64.0 65.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 18:51:34 INFO - PROCESS | 2206 | Values: 66.0 65.0 65.0 65.0 67.0 66.0 66.0 66.0 66.0 66.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.00 stddev:3.03 (3.5%) stddev-sans-first:3.20 18:51:34 INFO - PROCESS | 2206 | Values: 86.0 95.0 85.0 84.0 88.0 86.0 86.0 86.0 87.0 86.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:139.50 stddev:13.52 (9.7%) stddev-sans-first:14.16 18:51:34 INFO - PROCESS | 2206 | Values: 137.0 135.0 137.0 145.0 136.0 136.0 138.0 141.0 180.0 146.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.20 Median:181.00 stddev:2.44 (1.3%) stddev-sans-first:2.30 18:51:34 INFO - PROCESS | 2206 | Values: 178.0 181.0 180.0 180.0 178.0 184.0 181.0 185.0 184.0 181.0 18:51:34 INFO - PROCESS | 2206 | 18:51:34 INFO - PROCESS | 2206 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:2.07 (3.0%) stddev-sans-first:2.18 18:51:34 INFO - PROCESS | 2206 | Values: 70.0 69.0 69.0 72.0 71.0 68.0 68.0 73.0 67.0 67.0 18:51:34 INFO - PROCESS | 2206 | -------- Summary: end -------- 18:51:34 INFO - PROCESS | 2206 | 18:51:35 INFO - TEST-INFO | 2206: exit 0 18:51:35 INFO - TEST-OK | kraken | took 63838ms 18:51:35 INFO - SUITE-END | took 1185s 18:51:36 INFO - Completed test suite (00:19:46) 18:51:36 INFO - javascript benchmark 18:51:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2592.1186796489196, 2621.3577673470004, 2642.180923913587, 2644.9206523804282, 2650.655414796076, 4580.770961451931, 4619.053094115707, 4746.7863946122425, 4797.0405918816205, 4798.3282340472315, 4687.531246875311, 4696.53034696531, 4727.739974301412, 4750.192467280562, 4828.034393121372, 4266.935995960063, 4280.400743895857, 4318.611324980751, 4347.347897815337, 4427.11457708458, 4008.8382370043028, 4320.611144996949, 4358.455193100863, 4415.448068991376, 4451.198784218839, 4255.085156691326, 4295.377170310318, 4296.548862369437, 4399.494058183318, 4416.1609294233995, 4333.43665323506, 4333.609975102256, 4346.0, 4346.391505189276, 4346.869593912188, 4333.479982402132, 4336.306191009423, 4336.718113322656, 4337.566243375669, 4338.783060846945, 4659.347691323217, 4665.976670116662, 4717.410323709536, 4725.692829966041, 4731.0, 5068.18908974566, 5068.746562671889, 5118.820841270575, 5124.769385377667, 5134.512221338967, 5089.949100508985, 5105.183170692709, 5108.080545501808, 5114.079465696173, 5136.460671629462, 4599.885002874955, 4708.505606911293, 4758.262469317261, 4759.143354196243, 4818.614510839195, 5046.1169295373165, 5115.0, 5152.896942061213, 5189.299444575009, 5191.740412979336, 4966.279889416014, 5008.6744361616375, 5008.824691135793, 5009.799608015712, 5044.319016932667, 5068.949310506848, 5069.011542749202, 5075.644704870623, 5090.77091530882, 5100.872478188001, 4814.301926220678, 4876.171050921281, 4879.609631229493, 4959.082569724613, 5025.120603894304, 4976.552110310089, 5021.59827213822, 5025.095482813129, 5025.949740502549, 5040.899182016339, 4333.783310834446, 4335.089631177488, 4338.26249537573, 4372.737635741865, 4380.036391993756, 3696.2237930034457, 3713.053171441265, 3766.548014238309, 3777.527809023872, 3809.2000679857542, 4311.288637374861, 4317.827286908551, 4320.891977700519, 4324.891877703082, 4329.675274354436, 5010.098182327216, 5077.517635824591, 5081.441041485434, 5081.618878584121, 5084.3644544431945, 4285.721428107163, 4318.3090705486975, 4319.308910574293, 4324.891877703082, 4337.154254920323, 4567.2920697291975, 4567.383403240587, 4571.588557029883, 4571.977140114278, 4589.5639914208095, 4670.276107203389, 4683.227267500824, 4689.99165179488, 4690.554397332248, 4691.859244222747, 5047.545720885138, 5049.31834202378, 5083.771230294719, 5115.718635475085, 5136.717480538607, 4767.046590681947, 4767.070421267958, 4774.925641730583, 4778.092162489255, 4826.517348265145, 5068.138416469283, 5068.341115654941, 5070.543651071422, 5075.0, 5118.846434606968, 4884.8046078157895, 4943.382077240345, 4944.48082951285, 5044.621653375938, 5091.389033316026, 4946.901061978812, 4981.178105612534, 5005.074061298671, 5022.3219865317715, 5027.974860125676, 4957.925631115464, 4969.701817890938, 4970.701757894538, 4973.577245933991, 5036.823711170238, 4763.333133361263, 4798.688085274446, 4908.7300198488, 4917.0, 4965.677230979974, 4905.754712264444, 4925.088858561177, 4935.679180853233, 4952.653314267966, 5012.448630650699, 4826.927596086131, 4833.9033219334715, 4837.612990960644, 4842.080004799077, 4882.975585122052], "value": 4639.724603255678, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.592193284406, 535.0449447735792, 538.2383926743657, 539.3797133296708, 556.1268807971481, 27864.58203126952, 28569.0, 30517.237069073257, 36817.63182368175, 38454.8077259614, 40214.59785402152, 40620.18759624813, 40630.187396252055, 40703.0, 40795.59204407955, 7548.207438218977, 7716.074071111474, 7722.652480638385, 7724.304812566867, 7727.227277272284, 7341.0, 7529.811754706148, 7605.657745401442, 7743.651535680908, 9806.852897206547, 9772.31593788435, 10155.238357123208, 10167.135793457564, 10167.542460589291, 10182.694519164437, 9998.0, 10061.19510439163, 10066.69799906004, 10078.143357814595, 10085.647002354955, 9800.460974646392, 9885.505724713736, 9892.159166470787, 9897.257705672104, 9900.0, 37002.62997370019, 37317.0, 37354.43968340477, 37417.81291093554, 37466.81266593677, 2734.125079974389, 2798.2444739920106, 2919.8102123361914, 3014.954775678367, 3320.1367644412626, 25007.249782506555, 25335.61996570053, 25947.22158335253, 27026.1892143236, 27456.862715686293, 3265.232670322472, 3406.2846802171575, 3447.9655203448146, 3459.1459522053733, 3501.1947252132154, 244243.0, 245085.0, 245250.0, 246343.9999999982, 246558.0, 45772.0, 46319.76840115845, 46329.30506042412, 46411.53588464072, 46428.5357146431, 47084.293735593994, 47313.0, 47372.28941565879, 47497.0, 47544.286835697494, 13000.609981700563, 13543.119697219649, 13547.0, 13547.983901207448, 13566.321683915961, 10355.585776568852, 10517.84223236652, 10518.263721539419, 10525.052745252964, 10530.36817790935, 11066.944665276784, 11329.660110196708, 11330.150238732129, 11724.824127638092, 15138.848611513964, 6129.785457508966, 6430.549861509743, 6465.4827613790785, 6483.643399612973, 6536.803895883131, 755.9546427214385, 853.5049671190694, 885.106042896679, 911.4667919267303, 967.5984466446487, 25929.22212333633, 26878.0, 27160.0, 27257.1822845311, 27266.86366568194, 802.8956235689323, 874.4927941793744, 915.1397686174975, 933.9066093390607, 954.346272803132, 12217.266963982012, 12904.354782260849, 13174.670633234055, 13225.73548529024, 13239.66900827487, 349501.07982723153, 354027.0, 355442.0, 357417.0, 358362.0, 37466.62533374686, 38374.23251534953, 40267.798661006505, 42165.367519487234, 42662.0, 1155.2894969593472, 1158.4960542164185, 1171.2796630072444, 1174.8413964114907, 1176.4764679717443, 24663.0, 24955.251342459756, 25638.615420768332, 26053.0, 26066.60900086538, 36419.0, 37206.81396593, 37285.62714372821, 37716.245675086895, 37723.62276377201, 325542.0, 327582.0, 327993.0, 328304.0, 328333.0, 23003.88498057499, 23395.298141055795, 23409.648855267526, 23410.0, 23423.41441463977, 35698.46452303269, 36467.0, 36821.0, 39826.203475930895, 40407.19185616212, 29529.409411811215, 29968.25079373033, 30252.0, 30320.5451918217, 30894.382112357176, 31685.683143168273, 31850.0, 31916.042518724476, 32455.0, 32892.34215315728, 31383.0, 31483.0, 31535.684643154193, 32027.519587206676, 32137.67862321347, 27207.86396068007, 28736.856315719124, 28775.71224287814, 28928.42143157167, 28966.0, 22184.556308874056, 22390.66414003824, 22659.886700566392, 22669.319920402413, 22792.202272920367], "value": 16465.939597409695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.0737010072804, 363.13573694606833, 368.6718820249978, 371.8512594962016, 373.71224157398785, 12376.38118094095, 12692.746145077093, 15735.291911863966, 16013.83986160138, 16538.7519187212, 16008.679826403464, 16101.114438705867, 16408.09755463449, 16629.750553741673, 16694.248758805854, 4713.882152946187, 5010.924836127452, 5228.241904923784, 5234.92147617786, 5235.450277720842, 5979.52163826895, 6086.908696369558, 6128.908066379008, 6133.938660613404, 6136.140940268366, 5204.34945631796, 5457.836264912059, 6091.360407157251, 6094.60385074971, 6099.146119543268, 5491.258680078198, 5531.834044978657, 5537.252470916415, 5539.113741801333, 5539.362973258067, 5163.948360516422, 5175.171972484384, 5175.896482070375, 5182.689038657655, 5356.035913535562, 11845.466953987092, 12045.57840475588, 12081.758364832742, 12084.758304833855, 18187.27250909962, 1731.6190438103597, 1820.1718218210683, 1898.8291053805085, 1971.8915459649713, 2267.9546409071727, 8392.664293428255, 9402.670906518308, 9426.0, 11897.048236141089, 12104.0, 1695.4235559909566, 1733.306677329066, 1742.0941110622405, 1759.5716517879343, 1780.9287628494844, 56602.43397566054, 62010.37989620091, 63218.0517292241, 63434.36565634331, 65517.672411638116, 9261.44431334122, 9515.619375225051, 9576.616935322509, 9678.612855485842, 9726.221902247804, 13291.933540332237, 13298.73402531944, 13323.800142997863, 13323.93338033323, 13326.40031198579, 6587.934120658828, 6692.531522793358, 6792.660366981631, 6814.216365117975, 6815.863682726318, 6194.287656919422, 6227.0, 6237.438630523275, 6379.202599675041, 6381.489480841522, 4068.8575899843954, 4102.958970410257, 4152.190322887069, 4187.790610469464, 4191.91616167675, 1781.1806568978416, 1799.1993562864661, 1799.4961410804995, 1818.399928023749, 1836.825501577348, 846.7502086884384, 847.1951645936385, 903.6746771162376, 932.1238036246043, 945.1493655709779, 15668.373265069498, 15737.68524629501, 16015.359385624444, 16673.16634168311, 17446.47660570185, 792.8216151366012, 820.6553247636007, 848.4273115647037, 870.7287360453682, 904.841652710773, 8389.687654933068, 9377.156055954996, 9418.717438476857, 9474.526273686288, 9553.426794392359, 275455.0, 326834.0, 330068.0489792655, 338150.0, 340086.0, 71092.64453677803, 71203.0, 71769.0, 72002.63998679972, 74671.62664186645, 866.8266346730679, 876.0801158783252, 877.4252864373846, 881.7883707910055, 889.5418859287472, 14364.127174565338, 14532.0, 14538.491152810024, 14969.32638031313, 15328.080315181125, 26808.463830723667, 32181.839090805333, 33685.49471757877, 34584.48123278103, 34644.0, 44711.55288447204, 45087.0, 46372.53627463817, 47146.76426617845, 48343.27485087656, 4933.227028104474, 5054.949450505448, 7510.173880873209, 7560.618405202344, 7738.0, 10326.793464130826, 11406.201565890307, 11577.768444631229, 11642.6507204784, 12319.507219711111, 10045.598176072876, 10164.0, 10226.897731022595, 10236.692899213036, 10571.524281407508, 9042.728718138467, 9357.906420935704, 9449.43303401798, 9905.257105716955, 10628.671416072992, 6315.463185629089, 9659.517024148623, 9777.462239576607, 9871.309008369633, 10180.338278011906, 7360.96319518399, 7470.589117598584, 7489.0, 7825.708233523971, 7921.32668723165, 8857.291416686521, 9844.310898237054, 9903.702888913343, 9989.650362237577, 10021.498925053864], "value": 7809.312419038439, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.6475554466979, 1323.7683405404055, 1338.5515852189517, 1426.2654732812596, 1682.1084825042724, 6534.085228068075, 7166.462515311353, 7380.9630951845165, 7402.888956665648, 7572.091349038109, 7379.114506259257, 7451.329380355767, 7454.1055073391135, 7455.105387353512, 7456.440766942474, 1830.8443782278523, 1906.3423119023914, 2122.6285400054967, 2161.470439742265, 2166.9133234670594, 2083.739337700692, 2149.161826887515, 2153.6984822124914, 2158.2446143849684, 2166.7941545553226, 2078.0960282277183, 2096.3501314592527, 2102.1906565972135, 2107.862988905724, 2108.3569511299124, 2142.1431427429075, 2157.374361435182, 2163.621366260898, 2167.2956289205913, 2170.6852506386485, 2072.5336799220204, 2102.29573093014, 2187.3984654220058, 2188.39819049761, 2188.4638263625516, 7199.784006479814, 7412.88880666785, 7414.332710056121, 7421.406287496988, 7441.181470038346, 4307.289297265947, 4462.397576327219, 4471.0, 4474.440694913136, 4479.3057076153245, 2834.2347566157227, 2871.1817132117453, 2937.236318557169, 3029.3638335949477, 3041.406925649499, 870.425519157353, 891.140049851896, 920.1028996728203, 932.8647346134841, 933.9066093390675, 9880.950595246977, 9912.256580756472, 9939.155171810406, 9943.850842237445, 9956.203503719686, 3991.1219531702977, 4153.7300075494995, 4243.787810609457, 4257.148570285894, 4261.382099595603, 8464.788380290542, 8465.534395608302, 8466.788330291793, 8477.152284771597, 8478.710748098103, 4589.678722489461, 4591.058832939239, 4592.127495775792, 4595.908081838412, 4597.264437689953, 3618.9819050904575, 4006.6193711597352, 4037.717359784787, 4099.344104943195, 4111.712180147361, 3460.6539346065697, 3475.2528206435736, 3590.0665826885197, 3599.3521166190335, 3605.7836529808296, 1160.9709757256137, 1178.7406770510645, 1186.0511590727556, 1188.364225139546, 1188.9643310700692, 718.5796309159095, 822.1696086952221, 833.329170018129, 855.3883972959239, 874.6719980007497, 4853.247746599282, 4950.084234416644, 5177.508136727005, 5178.404483484447, 5242.0, 392.7996721671968, 411.868202175301, 414.6392638404607, 418.03434067304624, 419.7963987466077, 3464.3590935676475, 3470.305938812248, 3504.6144924058335, 3535.041371747562, 3631.0740761106276, 8634.827303453896, 8848.380613357132, 8864.20222179994, 8925.687600934067, 8947.686830961014, 8217.95891020541, 8519.872201916976, 8855.114488551222, 8869.512176830336, 8872.556372181365, 6788.864222715518, 7009.719611215596, 7014.964925175342, 7016.929830701628, 7019.333163349474, 4971.801127954841, 5082.593392528663, 5093.18509038552, 5143.92284115731, 5225.242339860775, 8071.273585377344, 8091.433599647969, 8195.180481951757, 8221.218984196492, 8321.875171872305, 8120.959395202986, 8227.0, 8371.874421883555, 8405.285550728013, 8489.68409896467, 6131.264248290234, 6286.874262514632, 6463.385978332051, 6598.802035938929, 6601.207855057424, 6987.720491180499, 7044.506884518035, 7045.154581450259, 7049.365557099885, 7063.505554611128, 6314.968425157845, 6400.199975003125, 6446.484281257582, 6479.060536222316, 6679.933200668125, 6379.362063793584, 6435.066915297162, 6491.740330386731, 6504.707288171949, 6563.1796025496815, 5969.343372229138, 6031.60794548353, 6042.90935635957, 6048.334683184934, 6048.848778780566, 4341.9782901086555, 4342.891427714332, 4452.777361131995, 4490.259107247268, 4506.459224893034, 4258.531561528292, 4387.539308372575, 4425.756583387816, 4464.687471876938, 4596.931046034245], "value": 3899.7052543157647, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8119496666767, 357.9767315124517, 359.955005624297, 360.13387802335393, 370.20590832663936, 4756.762161891909, 4764.475907650164, 4765.1899177139885, 4777.6177905767545, 4781.713097214165, 4447.843560674224, 4722.763861806913, 4736.715797052179, 4742.573168414842, 4750.168720473912, 3686.8340924658387, 3729.589745128034, 3741.5697194822537, 3754.699624030071, 3766.642168993941, 3796.6711650922234, 3797.506324177853, 3801.3157631626295, 3806.7715937043868, 3811.8856434307013, 3769.641884021027, 3789.658930696237, 3794.886153415402, 3796.1838204785968, 3802.543694756633, 3595.694365978895, 3604.7476676632646, 3706.1661126246513, 3782.981085094557, 3791.696664266852, 3414.1805966567845, 3420.555327807394, 3423.7945723256685, 3431.725461963037, 3440.449528075496, 4603.447586289667, 4608.400907881987, 4612.423447069116, 4621.861344159681, 4635.351050852883, 3988.441618173458, 4143.9171216575805, 4310.849120280775, 4336.176126535948, 4339.153864996327, 4899.142650036265, 4899.265110233494, 4903.975480122613, 4912.680675756064, 4913.238448040559, 3527.0829584308008, 3549.9882533477835, 3551.2187318789825, 3553.236054248349, 3559.893203203908, 4970.713471803011, 5152.484751524855, 5191.402988656278, 5191.8182863599595, 5203.635745497817, 3596.298721749286, 3607.8917632471066, 3611.7719975208556, 3626.3653860574295, 3630.110622897407, 3647.015305867454, 3647.835847386874, 3654.086478380405, 3654.8538058477893, 3686.4101743721412, 4152.792360381969, 4153.979230103831, 4161.9583804161575, 4165.708400411942, 4166.437530933345, 4670.766461676971, 4886.120498310267, 4957.107720610325, 4957.90084198314, 4979.2033274676605, 1234.826914431276, 1236.6722818453118, 1243.440451796695, 1244.825724398585, 1249.4127759952808, 833.8832563441123, 854.2055888024197, 863.8704194370895, 865.6796985115548, 867.9305655547541, 4171.958280417218, 4229.830806767694, 4267.551907049778, 4332.566743325642, 4354.216241076637, 5068.831980012492, 5072.5688316493515, 5085.7202853842655, 5088.313077734532, 5091.116286973844, 4330.133973205371, 4332.610065094157, 4335.263005289108, 4336.392904993303, 4347.413099231627, 2536.7463253674487, 2599.583227141213, 2613.725558816335, 2650.867456627161, 2655.522006038893, 4602.194615942197, 4608.239640459286, 4622.590110016477, 4650.255959046536, 4669.295707066896, 5074.467180945948, 5095.668781529189, 5121.948780512222, 5124.871878203075, 5128.692278463304, 4527.298268768346, 4632.698874573142, 4643.865390861155, 4649.65127615437, 4669.206234940137, 4986.551210391082, 4987.451380348086, 5003.599712022957, 5012.498750125104, 5018.799248030184, 4916.213405855046, 4919.557239848431, 4920.6555541111775, 4923.926141107814, 4942.357493525818, 4485.977570112129, 4551.340055691972, 4572.88567785808, 4605.355250265033, 4617.79219935097, 3494.668006539375, 3531.5408996830247, 3539.663731945461, 3546.5744110706883, 3580.138328069337, 4970.310094567864, 4980.427250866197, 4987.900241995212, 4990.030931595695, 5005.799768009385, 4673.415823022122, 4835.323054772264, 4869.878253043702, 4912.336834527293, 4913.508649135058, 4848.224284114666, 4856.368672072608, 4859.562639362474, 4866.270059491105, 4875.926861097016, 4729.408823897013, 4776.829676729223, 4784.258439941815, 4789.640776941674, 4828.61371090319, 4681.625469962463, 4817.108834865561, 4828.227483602606, 4831.4685384608365, 4866.683665561727, 4660.627149828073, 4710.29345598163, 4716.6698331116495, 4716.740579268173, 4729.172394830822], "value": 3727.8183221642653, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.343594072313, 616.8519555306725, 668.3483603486599, 719.6689522819506, 721.4192574977142, 11657.00915422189, 11875.70310742232, 12025.879741202585, 12050.216735912181, 12107.334096624692, 11346.886531134685, 11438.370889601068, 12173.391330433466, 13065.281409522497, 13307.800382994239, 778.0313509680442, 789.0058526256929, 789.9052113746329, 790.8260182759784, 790.9723159689412, 628.2178687534026, 646.5118835279382, 757.9507332023401, 782.4601025292559, 788.1212448120361, 821.2526600793279, 824.1346586084617, 824.9092599814015, 825.5954582254682, 827.896512935883, 815.7919730468692, 819.4345901328124, 819.9955055057536, 820.2658620534588, 820.3724151024442, 481.52810245958807, 490.13491188053115, 585.166138252992, 599.9820005399845, 603.6559161278074, 13601.183928964294, 13605.931970340183, 13606.183628982293, 13611.931940340235, 13620.250886201255, 12203.901648851557, 12335.629931102081, 12556.81164782529, 12564.371781410984, 12775.23348599087, 9934.751631209205, 10195.082442580204, 10234.48827558619, 11951.940240298829, 11956.641300760992, 4044.5550989391145, 4052.371882358239, 4077.1234184650148, 4079.102597428561, 4080.4287399764057, 61588.0, 62022.68988655028, 62426.0, 62845.0, 63209.0, 9420.387674801115, 9539.761505962406, 9547.331686781998, 9637.084476974676, 9737.367071140354, 16695.332186712396, 16830.57923551921, 16926.492205233862, 17532.29870805179, 17749.645007099785, 6603.141591593062, 6870.175578930561, 6969.895451568231, 7109.715611375487, 7127.536710113826, 3601.7118630509494, 3641.9089522761615, 3649.1606930406156, 3664.3953747631876, 3666.8533258669354, 2715.7420045095682, 2791.6231308773054, 2852.6434195725533, 2883.163882474101, 2887.321479452327, 503.7179179659403, 505.7875692209281, 507.57110241845584, 511.66230288009734, 520.4587229281505, 609.3053918532876, 613.2303958532103, 633.1104797759156, 633.3729607688356, 636.2746469025315, 7904.802379940547, 8283.917160828314, 8624.568771561397, 8647.308215342757, 8749.606267717969, 503.4286085293219, 506.9797208111708, 611.6024584020333, 627.2097157581389, 636.9936300637027, 3730.9440358394645, 3797.9430308545393, 3852.3836186210074, 4015.1768887378603, 4058.817353219112, 43457.56542434599, 44056.559434405885, 63675.68162159252, 67676.0, 67756.32243677501, 43660.78169609132, 50287.74856125769, 50738.23892641613, 51531.22703159456, 51870.0, 40508.39237411441, 46414.0, 46484.302735458994, 47536.52463475321, 48575.27137092946, 8539.054629601864, 8866.73399798007, 9052.728418147466, 9084.500352480676, 9219.953900230455, 31452.0, 31900.521492177173, 34190.0, 34383.48424773681, 34524.101174435644, 38051.80974095112, 39208.60791392164, 39232.607673922896, 43069.35395969, 44391.11217775562, 11254.493547790205, 11407.315561066363, 11855.466504007169, 12250.693732656755, 12336.876631233932, 12145.939270303592, 12228.0, 12670.0, 13272.601821945356, 13657.765337019173, 11844.170908036354, 11892.76214475723, 12053.0, 12348.392590172616, 12696.619101426972, 11835.881641183822, 11933.82099268529, 11964.401779911144, 12015.879841201826, 12272.263664180178, 11270.323780573191, 11453.942730286295, 11537.94231028873, 11757.353345566076, 11800.881991179978, 8893.199612034949, 9012.459252444874, 9120.5439728012, 9126.13301736334, 9171.816563668823, 9640.614375425184, 10204.642837500653, 10339.689809305733, 10348.0, 10352.068313851789], "value": 6156.263569597457, "unit": "score"}], "name": "dromaeo_css", "value": 6136.547503973038, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 114.0, 115.0, 117.0, 114.0, 115.0, 116.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 125.0, 128.0, 182.0, 134.0, 127.0, 124.0, 129.0, 150.0, 125.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 161.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 108.0, 80.0, 77.0, 77.0, 77.0, 95.0, 76.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 119.0, 85.0, 85.0, 84.0, 105.0, 85.0, 86.0, 104.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 127.0, 126.0, 127.0, 127.0, 127.0, 128.0, 127.0, 128.0], "value": 127.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 147.0, 148.0, 148.0, 148.0, 148.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 95.0, 85.0, 84.0, 88.0, 86.0, 86.0, 86.0, 87.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 137.0, 145.0, 136.0, 136.0, 138.0, 141.0, 180.0, 146.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 181.0, 180.0, 180.0, 178.0, 184.0, 181.0, 185.0, 184.0, 181.0], "value": 181.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 72.0, 71.0, 68.0, 68.0, 73.0, 67.0, 67.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1567.6000000000001, "alertThreshold": 2.0}]} 18:51:36 INFO - Return code: 0 18:51:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:51:36 INFO - # TBPL SUCCESS # 18:51:36 INFO - Running post-action listener: _resource_record_post_action 18:51:36 INFO - [mozharness: 2016-12-21 02:51:36.199612Z] Finished run-tests step (success) 18:51:36 INFO - Running post-run listener: _resource_record_post_run 18:51:36 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 382042112; Read time: 20; Write time: 243244 18:51:36 INFO - TinderboxPrint: CPU usage
13.1% 18:51:36 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 18:51:36 INFO - TinderboxPrint: I/O write bytes / time
382,042,112 / 243,244 18:51:36 INFO - TinderboxPrint: CPU idle
8,340.1 (86.8%) 18:51:36 INFO - TinderboxPrint: CPU user
1,218.0 (12.7%) 18:51:36 INFO - TinderboxPrint: Swap in / out
0 / 0 18:51:36 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99004416; Read time: 0; Write time: 60760 18:51:36 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282025984; Read time: 12; Write time: 182392 18:51:36 INFO - Running post-run listener: _upload_blobber_files 18:51:36 INFO - Blob upload gear active. 18:51:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:51:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:51:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:51:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:51:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:51:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:51:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:51:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:37 INFO - (blobuploader) - INFO - Done attempting. 18:51:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:51:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:51:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:38 INFO - (blobuploader) - INFO - Done attempting. 18:51:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:51:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:51:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:42 INFO - (blobuploader) - INFO - Done attempting. 18:51:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:51:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:51:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:43 INFO - (blobuploader) - INFO - Done attempting. 18:51:43 INFO - (blobuploader) - INFO - Iteration through files over. 18:51:43 INFO - Return code: 0 18:51:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:51:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:51:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2ca9d5e2765ca7672c9eb33b997384532bc82fdfe91213d21d961b26291e58e27058922f7a8cc2584238386235ce55101f66746a20f1eeba8e2fd025e19c04b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9330a61ac88a177114873bd44ee130bb7e327cff4c7a7916a6e4d9137758d7b01d6e1e906b6b7eed368d0761a076ace129580343eee6f0e184faa135052d8767", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4530e76bce0a7e57d97a42b08f5925503e2afa776a1d0295f6ca45cdc87f2a3488b10a28baf4b39453750adffcb24a570e7b577626d7af89ac48cf09ffd454c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75b3b8a5afa812b506d724f4f4e399aa097556aa0f8974322c6ce583f3efc300fb67c3e0f1a677c87347bf572d3a1045b5eda2f537fe2dd06978ec6f3226718e"} 18:51:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:51:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:51:43 INFO - Contents: 18:51:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2ca9d5e2765ca7672c9eb33b997384532bc82fdfe91213d21d961b26291e58e27058922f7a8cc2584238386235ce55101f66746a20f1eeba8e2fd025e19c04b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9330a61ac88a177114873bd44ee130bb7e327cff4c7a7916a6e4d9137758d7b01d6e1e906b6b7eed368d0761a076ace129580343eee6f0e184faa135052d8767", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4530e76bce0a7e57d97a42b08f5925503e2afa776a1d0295f6ca45cdc87f2a3488b10a28baf4b39453750adffcb24a570e7b577626d7af89ac48cf09ffd454c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75b3b8a5afa812b506d724f4f4e399aa097556aa0f8974322c6ce583f3efc300fb67c3e0f1a677c87347bf572d3a1045b5eda2f537fe2dd06978ec6f3226718e"} 18:51:43 INFO - Running post-run listener: copy_logs_to_upload_dir 18:51:43 INFO - Copying logs to upload dir... 18:51:43 INFO - mkdir: /builds/slave/test/build/upload/logs 18:51:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.794157 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-20 18:51:42.884564) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 18:51:42.890528) ========= 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=41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2ca9d5e2765ca7672c9eb33b997384532bc82fdfe91213d21d961b26291e58e27058922f7a8cc2584238386235ce55101f66746a20f1eeba8e2fd025e19c04b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9330a61ac88a177114873bd44ee130bb7e327cff4c7a7916a6e4d9137758d7b01d6e1e906b6b7eed368d0761a076ace129580343eee6f0e184faa135052d8767", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4530e76bce0a7e57d97a42b08f5925503e2afa776a1d0295f6ca45cdc87f2a3488b10a28baf4b39453750adffcb24a570e7b577626d7af89ac48cf09ffd454c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75b3b8a5afa812b506d724f4f4e399aa097556aa0f8974322c6ce583f3efc300fb67c3e0f1a677c87347bf572d3a1045b5eda2f537fe2dd06978ec6f3226718e"} build_url:https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011366 build_url: 'https://queue.taskcluster.net/v1/task/EZYNCxm3RBqjhOK7k9WBzw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2ca9d5e2765ca7672c9eb33b997384532bc82fdfe91213d21d961b26291e58e27058922f7a8cc2584238386235ce55101f66746a20f1eeba8e2fd025e19c04b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9330a61ac88a177114873bd44ee130bb7e327cff4c7a7916a6e4d9137758d7b01d6e1e906b6b7eed368d0761a076ace129580343eee6f0e184faa135052d8767", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4530e76bce0a7e57d97a42b08f5925503e2afa776a1d0295f6ca45cdc87f2a3488b10a28baf4b39453750adffcb24a570e7b577626d7af89ac48cf09ffd454c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75b3b8a5afa812b506d724f4f4e399aa097556aa0f8974322c6ce583f3efc300fb67c3e0f1a677c87347bf572d3a1045b5eda2f537fe2dd06978ec6f3226718e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 18:51:42.916176) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:51:42.916503) ========= 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=41bf3e9aed84707956354da8000001c5-1482286522.291767-1128051959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:51:43.001878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 18:51:43.002200) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 18:51:43.002488) ========= ========= Total master_lag: 0.17 =========