builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1476354380.96 results: success (0) buildid: 20161013001024 builduid: e0ee6b336e5247f79873e28078a9afb7 revision: 2cebb2efe185a3a7593a738af84b7d70e327de77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:20.963301) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:20.963803) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:20.964107) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:20.979048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:20.979434) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-13 03:26:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 30.9M=0s 2016-10-13 03:26:21 (30.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128188 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:21.121369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:21.121704) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015400 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:21.171560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 03:26:21.171931) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2cebb2efe185a3a7593a738af84b7d70e327de77 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2cebb2efe185a3a7593a738af84b7d70e327de77 --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=a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-13 03:26:22,019 truncating revision to first 12 chars 2016-10-13 03:26:22,019 Setting DEBUG logging. 2016-10-13 03:26:22,019 attempt 1/10 2016-10-13 03:26:22,019 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2cebb2efe185?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 03:26:23,336 unpacking tar archive at: mozilla-aurora-2cebb2efe185/testing/mozharness/ program finished with exit code 0 elapsedTime=1.683790 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 03:26:22.869104) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:22.869411) ========= script_repo_revision: 2cebb2efe185a3a7593a738af84b7d70e327de77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:22.869828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:22.870099) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 03:26:22.880567) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-13 03:26:22.880875) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:26:23 INFO - MultiFileLogger online at 20161013 03:26:23 in /builds/slave/test 03:26:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:26:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:26:23 INFO - {'append_to_log': False, 03:26:23 INFO - 'base_work_dir': '/builds/slave/test', 03:26:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:26:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:26:23 INFO - 'branch': 'Mozilla-Aurora', 03:26:23 INFO - 'buildbot_json_path': 'buildprops.json', 03:26:23 INFO - 'config_files': ('talos/linux_config.py',), 03:26:23 INFO - 'default_actions': ('clobber', 03:26:23 INFO - 'read-buildbot-config', 03:26:23 INFO - 'download-and-extract', 03:26:23 INFO - 'populate-webroot', 03:26:23 INFO - 'create-virtualenv', 03:26:23 INFO - 'install', 03:26:23 INFO - 'run-tests'), 03:26:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:26:23 INFO - 'download_minidump_stackwalk': True, 03:26:23 INFO - 'download_symbols': 'ondemand', 03:26:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:26:23 INFO - 'tooltool.py': '/tools/tooltool.py', 03:26:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:26:23 INFO - '/tools/misc-python/virtualenv.py')}, 03:26:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:26:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:26:23 INFO - 'installer_path': 'installer.exe', 03:26:23 INFO - 'log_level': 'info', 03:26:23 INFO - 'log_name': 'talos', 03:26:23 INFO - 'log_to_console': True, 03:26:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:26:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:26:23 INFO - 'opt_config_files': (), 03:26:23 INFO - 'pip_index': False, 03:26:23 INFO - 'sps_profile': False, 03:26:23 INFO - 'sps_profile_interval': 0, 03:26:23 INFO - 'suite': 'dromaeojs', 03:26:23 INFO - 'system_bits': '32', 03:26:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:26:23 INFO - 'title': 'talos-linux64-ix-064', 03:26:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:26:23 INFO - 'use_talos_json': True, 03:26:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:26:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:26:23 INFO - 'work_dir': 'build'} 03:26:23 INFO - [mozharness: 2016-10-13 10:26:23.823130Z] Running clobber step. 03:26:23 INFO - Running pre-action listener: _resource_record_pre_action 03:26:23 INFO - Running main action method: clobber 03:26:23 INFO - rmtree: /builds/slave/test/build 03:26:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:26:24 INFO - Running post-action listener: _resource_record_post_action 03:26:24 INFO - [mozharness: 2016-10-13 10:26:24.285495Z] Finished clobber step (success) 03:26:24 INFO - [mozharness: 2016-10-13 10:26:24.285595Z] Running read-buildbot-config step. 03:26:24 INFO - Running pre-action listener: _resource_record_pre_action 03:26:24 INFO - Running main action method: read_buildbot_config 03:26:24 INFO - Using buildbot properties: 03:26:24 INFO - { 03:26:24 INFO - "project": "", 03:26:24 INFO - "product": "firefox", 03:26:24 INFO - "script_repo_revision": "production", 03:26:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:26:24 INFO - "repository": "", 03:26:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:26:24 INFO - "buildid": "20161013001024", 03:26:24 INFO - "pgo_build": "True", 03:26:24 INFO - "basedir": "/builds/slave/test", 03:26:24 INFO - "buildnumber": 562, 03:26:24 INFO - "slavename": "talos-linux64-ix-064", 03:26:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:26:24 INFO - "platform": "ubuntu64_hw", 03:26:24 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:26:24 INFO - "branch": "mozilla-aurora", 03:26:24 INFO - "repo_path": "releases/mozilla-aurora", 03:26:24 INFO - "revision": "2cebb2efe185a3a7593a738af84b7d70e327de77", 03:26:24 INFO - "stage_platform": "linux64-pgo", 03:26:24 INFO - "builduid": "e0ee6b336e5247f79873e28078a9afb7", 03:26:24 INFO - "slavebuilddir": "test" 03:26:24 INFO - } 03:26:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:26:24 INFO - Running post-action listener: _resource_record_post_action 03:26:24 INFO - [mozharness: 2016-10-13 10:26:24.288046Z] Finished read-buildbot-config step (success) 03:26:24 INFO - [mozharness: 2016-10-13 10:26:24.288135Z] Running download-and-extract step. 03:26:24 INFO - Running pre-action listener: _resource_record_pre_action 03:26:24 INFO - Running main action method: download_and_extract 03:26:24 INFO - mkdir: /builds/slave/test/build/tests 03:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:24 INFO - trying https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:26:24 INFO - Downloading https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:26:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:26:25 INFO - Downloaded 1361 bytes. 03:26:25 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:26:25 INFO - Using the following test package requirements: 03:26:25 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:26:25 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:26:25 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:26:25 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'jsshell-linux-x86_64.zip'], 03:26:25 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:26:25 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:26:25 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:26:25 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:26:25 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:26:25 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:26:25 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:26:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:26:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:26:25 INFO - Fetch https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:26:25 INFO - Expected file size: 21906776 03:26:30 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:26:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:26:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:26:30 INFO - Fetch https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:26:30 INFO - Expected file size: 21906776 03:26:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:26:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:26:35 INFO - Fetch https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:26:36 INFO - Expected file size: 14414059 03:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:36 INFO - trying https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:36 INFO - Downloading https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:26:37 INFO - Downloaded 63992339 bytes. 03:26:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:37 INFO - mkdir: /builds/slave/test/properties 03:26:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:26:37 INFO - Writing to file /builds/slave/test/properties/build_url 03:26:37 INFO - Contents: 03:26:37 INFO - build_url:https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:38 INFO - Running post-action listener: _resource_record_post_action 03:26:38 INFO - Running post-action listener: set_extra_try_arguments 03:26:38 INFO - [mozharness: 2016-10-13 10:26:38.611790Z] Finished download-and-extract step (success) 03:26:38 INFO - [mozharness: 2016-10-13 10:26:38.612017Z] Running populate-webroot step. 03:26:38 INFO - Running pre-action listener: _resource_record_pre_action 03:26:38 INFO - Running main action method: populate_webroot 03:26:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:26:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:26:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:26:38 INFO - '--tppagecycles', 03:26:38 INFO - '7'], 03:26:38 INFO - 'tests': ['tsvgm']}}, 03:26:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:26:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:26:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:26:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:26:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['damp', 'tps']}, 03:26:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'tests': ['damp', 'tps']}, 03:26:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['dromaeo_dom']}, 03:26:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:26:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['basic_compositor_video']}, 03:26:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:26:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['a11yr', 03:26:38 INFO - 'ts_paint', 03:26:38 INFO - 'tpaint', 03:26:38 INFO - 'sessionrestore', 03:26:38 INFO - 'sessionrestore_no_auto_restore', 03:26:38 INFO - 'tabpaint']}, 03:26:38 INFO - 'other-e10s': {'tests': ['a11yr', 03:26:38 INFO - 'ts_paint', 03:26:38 INFO - 'tpaint', 03:26:38 INFO - 'sessionrestore', 03:26:38 INFO - 'sessionrestore_no_auto_restore', 03:26:38 INFO - 'tabpaint']}, 03:26:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:26:38 INFO - 'ts_paint', 03:26:38 INFO - 'tpaint', 03:26:38 INFO - 'sessionrestore', 03:26:38 INFO - 'sessionrestore_no_auto_restore', 03:26:38 INFO - 'tabpaint']}, 03:26:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:26:38 INFO - 'ts_paint', 03:26:38 INFO - 'tpaint', 03:26:38 INFO - 'sessionrestore', 03:26:38 INFO - 'sessionrestore_no_auto_restore', 03:26:38 INFO - 'tabpaint']}, 03:26:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['a11yr', 03:26:38 INFO - 'ts_paint', 03:26:38 INFO - 'tpaint', 03:26:38 INFO - 'sessionrestore', 03:26:38 INFO - 'sessionrestore_no_auto_restore', 03:26:38 INFO - 'tabpaint']}, 03:26:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['a11yr', 03:26:38 INFO - 'ts_paint', 03:26:38 INFO - 'tpaint', 03:26:38 INFO - 'sessionrestore', 03:26:38 INFO - 'sessionrestore_no_auto_restore', 03:26:38 INFO - 'tabpaint']}, 03:26:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['tsvgx', 03:26:38 INFO - 'tsvgr_opacity', 03:26:38 INFO - 'tart', 03:26:38 INFO - 'tscrollx', 03:26:38 INFO - 'cart']}, 03:26:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:26:38 INFO - 'tsvgr_opacity', 03:26:38 INFO - 'tart', 03:26:38 INFO - 'tscrollx', 03:26:38 INFO - 'cart']}, 03:26:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'talos_options': ['--disable-e10s'], 03:26:38 INFO - 'tests': ['tp5o']}, 03:26:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'tests': ['tp5o']}, 03:26:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'talos_options': ['--disable-e10s', 03:26:38 INFO - '--xperf_path', 03:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:38 INFO - 'tests': ['tp5n']}, 03:26:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:38 INFO - 'talos_options': ['--xperf_path', 03:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:38 INFO - 'tests': ['tp5n']}}, 03:26:38 INFO - 'talos.zip': {'path': '', 03:26:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:26:38 INFO - Running post-action listener: _resource_record_post_action 03:26:38 INFO - [mozharness: 2016-10-13 10:26:38.628475Z] Finished populate-webroot step (success) 03:26:38 INFO - [mozharness: 2016-10-13 10:26:38.628562Z] Running create-virtualenv step. 03:26:38 INFO - Running pre-action listener: _resource_record_pre_action 03:26:38 INFO - Running main action method: create_virtualenv 03:26:38 INFO - Creating virtualenv /builds/slave/test/build/venv 03:26:38 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 03:26:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:26:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:26:38 INFO - Using real prefix '/usr' 03:26:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:26:39 INFO - Installing distribute.............................................................................................................................................................................................done. 03:26:41 INFO - Installing pip.................done. 03:26:41 INFO - Return code: 0 03:26:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:26:41 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')]} 03:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:41 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')]} 03:26:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:41 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:41 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 03:26:41 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 03:26:41 INFO - Using env: {'DISPLAY': ':0', 03:26:41 INFO - 'HOME': '/home/cltbld', 03:26:41 INFO - 'LANG': 'en_US.UTF-8', 03:26:41 INFO - 'LANGUAGE': 'en_US:en', 03:26:41 INFO - 'LOGNAME': 'cltbld', 03:26:41 INFO - 'MAIL': '/var/mail/cltbld', 03:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:41 INFO - 'MOZ_NO_REMOTE': '1', 03:26:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:41 INFO - 'NO_EM_RESTART': '1', 03:26:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:41 INFO - 'PWD': '/builds/slave/test', 03:26:41 INFO - 'SHELL': '/bin/bash', 03:26:41 INFO - 'SHLVL': '1', 03:26:41 INFO - 'TERM': 'linux', 03:26:41 INFO - 'TMOUT': '86400', 03:26:41 INFO - 'USER': 'cltbld', 03:26:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:41 INFO - '_': '/tools/buildbot/bin/python'} 03:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:41 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 03:26:41 INFO - Cleaning up... 03:26:41 INFO - Return code: 0 03:26:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:26:41 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')]} 03:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:41 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')]} 03:26:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:41 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:41 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 03:26:41 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 03:26:41 INFO - Using env: {'DISPLAY': ':0', 03:26:41 INFO - 'HOME': '/home/cltbld', 03:26:41 INFO - 'LANG': 'en_US.UTF-8', 03:26:41 INFO - 'LANGUAGE': 'en_US:en', 03:26:41 INFO - 'LOGNAME': 'cltbld', 03:26:41 INFO - 'MAIL': '/var/mail/cltbld', 03:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:41 INFO - 'MOZ_NO_REMOTE': '1', 03:26:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:41 INFO - 'NO_EM_RESTART': '1', 03:26:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:41 INFO - 'PWD': '/builds/slave/test', 03:26:41 INFO - 'SHELL': '/bin/bash', 03:26:41 INFO - 'SHLVL': '1', 03:26:41 INFO - 'TERM': 'linux', 03:26:41 INFO - 'TMOUT': '86400', 03:26:41 INFO - 'USER': 'cltbld', 03:26:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:41 INFO - '_': '/tools/buildbot/bin/python'} 03:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:41 INFO - Downloading/unpacking psutil>=3.1.1 03:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:26:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:43 INFO - Installing collected packages: psutil 03:26:43 INFO - Running setup.py install for psutil 03:26:43 INFO - building 'psutil._psutil_linux' extension 03:26:43 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 03:26:43 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 03:26:43 INFO - building 'psutil._psutil_posix' extension 03:26:43 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 03:26:44 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 03:26:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:44 INFO - Successfully installed psutil 03:26:44 INFO - Cleaning up... 03:26:44 INFO - Return code: 0 03:26:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:26:44 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')]} 03:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:44 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')]} 03:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:44 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:44 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 03:26:44 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 03:26:44 INFO - Using env: {'DISPLAY': ':0', 03:26:44 INFO - 'HOME': '/home/cltbld', 03:26:44 INFO - 'LANG': 'en_US.UTF-8', 03:26:44 INFO - 'LANGUAGE': 'en_US:en', 03:26:44 INFO - 'LOGNAME': 'cltbld', 03:26:44 INFO - 'MAIL': '/var/mail/cltbld', 03:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:44 INFO - 'MOZ_NO_REMOTE': '1', 03:26:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:44 INFO - 'NO_EM_RESTART': '1', 03:26:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:44 INFO - 'PWD': '/builds/slave/test', 03:26:44 INFO - 'SHELL': '/bin/bash', 03:26:44 INFO - 'SHLVL': '1', 03:26:44 INFO - 'TERM': 'linux', 03:26:44 INFO - 'TMOUT': '86400', 03:26:44 INFO - 'USER': 'cltbld', 03:26:44 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:44 INFO - '_': '/tools/buildbot/bin/python'} 03:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:26:44 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 03:26:44 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 03:26:44 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 03:26:44 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 03:26:44 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 03:26:44 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 03:26:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:26:46 INFO - Installing collected packages: mozsystemmonitor 03:26:46 INFO - Running setup.py install for mozsystemmonitor 03:26:46 INFO - Successfully installed mozsystemmonitor 03:26:46 INFO - Cleaning up... 03:26:46 INFO - Return code: 0 03:26:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:46 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:46 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 03:26:46 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 03:26:46 INFO - Using env: {'DISPLAY': ':0', 03:26:46 INFO - 'HOME': '/home/cltbld', 03:26:46 INFO - 'LANG': 'en_US.UTF-8', 03:26:46 INFO - 'LANGUAGE': 'en_US:en', 03:26:46 INFO - 'LOGNAME': 'cltbld', 03:26:46 INFO - 'MAIL': '/var/mail/cltbld', 03:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:46 INFO - 'MOZ_NO_REMOTE': '1', 03:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:46 INFO - 'NO_EM_RESTART': '1', 03:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:46 INFO - 'PWD': '/builds/slave/test', 03:26:46 INFO - 'SHELL': '/bin/bash', 03:26:46 INFO - 'SHLVL': '1', 03:26:46 INFO - 'TERM': 'linux', 03:26:46 INFO - 'TMOUT': '86400', 03:26:46 INFO - 'USER': 'cltbld', 03:26:46 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:46 INFO - '_': '/tools/buildbot/bin/python'} 03:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:46 INFO - Downloading/unpacking jsonschema==2.5.1 03:26:46 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 03:26:46 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 03:26:46 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 03:26:46 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 03:26:46 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 03:26:46 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 03:26:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:26:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:26:48 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 03:26:48 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 03:26:48 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 03:26:48 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 03:26:48 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 03:26:48 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 03:26:48 INFO - Downloading functools32-3.2.3-2.tar.gz 03:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:26:48 INFO - warning: no files found matching '*.txt' 03:26:48 INFO - no previously-included directories found matching 'build' 03:26:48 INFO - no previously-included directories found matching 'dist' 03:26:48 INFO - no previously-included directories found matching '.git*' 03:26:48 INFO - Installing collected packages: jsonschema, functools32 03:26:48 INFO - Running setup.py install for functools32 03:26:49 INFO - warning: no files found matching '*.txt' 03:26:49 INFO - no previously-included directories found matching 'build' 03:26:49 INFO - no previously-included directories found matching 'dist' 03:26:49 INFO - no previously-included directories found matching '.git*' 03:26:49 INFO - Successfully installed jsonschema functools32 03:26:49 INFO - Cleaning up... 03:26:49 INFO - Return code: 0 03:26:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:49 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:49 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 03:26:49 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 03:26:49 INFO - Using env: {'DISPLAY': ':0', 03:26:49 INFO - 'HOME': '/home/cltbld', 03:26:49 INFO - 'LANG': 'en_US.UTF-8', 03:26:49 INFO - 'LANGUAGE': 'en_US:en', 03:26:49 INFO - 'LOGNAME': 'cltbld', 03:26:49 INFO - 'MAIL': '/var/mail/cltbld', 03:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:49 INFO - 'MOZ_NO_REMOTE': '1', 03:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:49 INFO - 'NO_EM_RESTART': '1', 03:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:49 INFO - 'PWD': '/builds/slave/test', 03:26:49 INFO - 'SHELL': '/bin/bash', 03:26:49 INFO - 'SHLVL': '1', 03:26:49 INFO - 'TERM': 'linux', 03:26:49 INFO - 'TMOUT': '86400', 03:26:49 INFO - 'USER': 'cltbld', 03:26:49 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:49 INFO - '_': '/tools/buildbot/bin/python'} 03:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:26:49 INFO - Cleaning up... 03:26:49 INFO - Return code: 0 03:26:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:49 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:49 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 03:26:49 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 03:26:49 INFO - Using env: {'DISPLAY': ':0', 03:26:49 INFO - 'HOME': '/home/cltbld', 03:26:49 INFO - 'LANG': 'en_US.UTF-8', 03:26:49 INFO - 'LANGUAGE': 'en_US:en', 03:26:49 INFO - 'LOGNAME': 'cltbld', 03:26:49 INFO - 'MAIL': '/var/mail/cltbld', 03:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:49 INFO - 'MOZ_NO_REMOTE': '1', 03:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:49 INFO - 'NO_EM_RESTART': '1', 03:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:49 INFO - 'PWD': '/builds/slave/test', 03:26:49 INFO - 'SHELL': '/bin/bash', 03:26:49 INFO - 'SHLVL': '1', 03:26:49 INFO - 'TERM': 'linux', 03:26:49 INFO - 'TMOUT': '86400', 03:26:49 INFO - 'USER': 'cltbld', 03:26:49 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:49 INFO - '_': '/tools/buildbot/bin/python'} 03:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:26:49 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 03:26:49 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 03:26:49 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 03:26:49 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 03:26:49 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 03:26:49 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 03:26:51 INFO - Downloading blobuploader-1.2.4.tar.gz 03:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:26:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:26:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 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 03:26:51 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 03:26:52 INFO - Downloading docopt-0.6.1.tar.gz 03:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:26:52 INFO - Installing collected packages: blobuploader, requests, docopt 03:26:52 INFO - Running setup.py install for blobuploader 03:26:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:26:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:26:52 INFO - Running setup.py install for requests 03:26:52 INFO - Running setup.py install for docopt 03:26:52 INFO - Successfully installed blobuploader requests docopt 03:26:52 INFO - Cleaning up... 03:26:52 INFO - Return code: 0 03:26:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:52 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')]} 03:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:52 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')]} 03:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:52 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:52 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 03:26:52 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 03:26:52 INFO - Using env: {'DISPLAY': ':0', 03:26:52 INFO - 'HOME': '/home/cltbld', 03:26:52 INFO - 'LANG': 'en_US.UTF-8', 03:26:52 INFO - 'LANGUAGE': 'en_US:en', 03:26:52 INFO - 'LOGNAME': 'cltbld', 03:26:52 INFO - 'MAIL': '/var/mail/cltbld', 03:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:52 INFO - 'MOZ_NO_REMOTE': '1', 03:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:52 INFO - 'NO_EM_RESTART': '1', 03:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:52 INFO - 'PWD': '/builds/slave/test', 03:26:52 INFO - 'SHELL': '/bin/bash', 03:26:52 INFO - 'SHLVL': '1', 03:26:52 INFO - 'TERM': 'linux', 03:26:52 INFO - 'TMOUT': '86400', 03:26:52 INFO - 'USER': 'cltbld', 03:26:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:52 INFO - '_': '/tools/buildbot/bin/python'} 03:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:53 INFO - Running setup.py (path:/tmp/pip-BXl2sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:53 INFO - Running setup.py (path:/tmp/pip-CqSF3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:53 INFO - Running setup.py (path:/tmp/pip-PwPV6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:53 INFO - Running setup.py (path:/tmp/pip-rX2lmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:53 INFO - Running setup.py (path:/tmp/pip-Hb4ETo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:53 INFO - Running setup.py (path:/tmp/pip-VjTVRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:53 INFO - Running setup.py (path:/tmp/pip-Ez99Lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:53 INFO - Running setup.py (path:/tmp/pip-YmkqjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:53 INFO - Running setup.py (path:/tmp/pip-Khjr1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:53 INFO - Running setup.py (path:/tmp/pip-XUjtvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:53 INFO - Running setup.py (path:/tmp/pip-2PARmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:53 INFO - Running setup.py (path:/tmp/pip-QiM9t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:53 INFO - Running setup.py (path:/tmp/pip-62mY9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:53 INFO - Running setup.py (path:/tmp/pip-z5iqcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:54 INFO - Running setup.py (path:/tmp/pip-HeVAVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:54 INFO - Running setup.py (path:/tmp/pip-A223Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:54 INFO - Running setup.py (path:/tmp/pip-J29LxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:26:54 INFO - Running setup.py install for manifestparser 03:26:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:26:54 INFO - Running setup.py install for mozcrash 03:26:54 INFO - Running setup.py install for mozdebug 03:26:54 INFO - Running setup.py install for mozdevice 03:26:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:26:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:26:54 INFO - Running setup.py install for mozfile 03:26:54 INFO - Running setup.py install for mozhttpd 03:26:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:26:54 INFO - Running setup.py install for mozinfo 03:26:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:26:54 INFO - Running setup.py install for mozInstall 03:26:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Running setup.py install for mozleak 03:26:55 INFO - Running setup.py install for mozlog 03:26:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Running setup.py install for moznetwork 03:26:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Running setup.py install for mozprocess 03:26:55 INFO - Running setup.py install for mozprofile 03:26:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Running setup.py install for mozrunner 03:26:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:26:55 INFO - Running setup.py install for mozscreenshot 03:26:55 INFO - Running setup.py install for moztest 03:26:55 INFO - Running setup.py install for mozversion 03:26:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:26:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:26:56 INFO - Cleaning up... 03:26:56 INFO - Return code: 0 03:26:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:56 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')]} 03:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:56 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')]} 03:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:56 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:56 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 03:26:56 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 03:26:56 INFO - Using env: {'DISPLAY': ':0', 03:26:56 INFO - 'HOME': '/home/cltbld', 03:26:56 INFO - 'LANG': 'en_US.UTF-8', 03:26:56 INFO - 'LANGUAGE': 'en_US:en', 03:26:56 INFO - 'LOGNAME': 'cltbld', 03:26:56 INFO - 'MAIL': '/var/mail/cltbld', 03:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:56 INFO - 'MOZ_NO_REMOTE': '1', 03:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:56 INFO - 'NO_EM_RESTART': '1', 03:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:56 INFO - 'PWD': '/builds/slave/test', 03:26:56 INFO - 'SHELL': '/bin/bash', 03:26:56 INFO - 'SHLVL': '1', 03:26:56 INFO - 'TERM': 'linux', 03:26:56 INFO - 'TMOUT': '86400', 03:26:56 INFO - 'USER': 'cltbld', 03:26:56 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:56 INFO - '_': '/tools/buildbot/bin/python'} 03:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:56 INFO - Running setup.py (path:/tmp/pip-3hBYFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:56 INFO - Running setup.py (path:/tmp/pip-AekJ6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:56 INFO - Running setup.py (path:/tmp/pip-FywxBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:56 INFO - Running setup.py (path:/tmp/pip-oLGfqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:56 INFO - Running setup.py (path:/tmp/pip-IAsVVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:56 INFO - Running setup.py (path:/tmp/pip-jdB_rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:56 INFO - Running setup.py (path:/tmp/pip-nVaXex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:56 INFO - Running setup.py (path:/tmp/pip-QCliHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:56 INFO - Running setup.py (path:/tmp/pip-TEQt5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:56 INFO - Running setup.py (path:/tmp/pip-_iZvzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:56 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)) 03:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:56 INFO - Running setup.py (path:/tmp/pip-FnxLkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:57 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)) 03:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:57 INFO - Running setup.py (path:/tmp/pip-1LKhsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:57 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)) 03:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:57 INFO - Running setup.py (path:/tmp/pip-OKpHCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:57 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)) 03:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:57 INFO - Running setup.py (path:/tmp/pip-bJoK9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:57 INFO - Running setup.py (path:/tmp/pip-s9_3kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:57 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)) 03:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:57 INFO - Running setup.py (path:/tmp/pip-UFMBux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:57 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)) 03:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:57 INFO - Running setup.py (path:/tmp/pip-gzVLTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:57 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)) 03:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:57 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)) 03:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:57 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 03:26:57 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 03:26:57 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 03:26:57 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 03:26:57 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 03:26:57 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 03:26:59 INFO - Downloading blessings-1.6.tar.gz 03:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:26:59 INFO - Installing collected packages: blessings 03:26:59 INFO - Running setup.py install for blessings 03:26:59 INFO - Successfully installed blessings 03:26:59 INFO - Cleaning up... 03:26:59 INFO - Return code: 0 03:26:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:26:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:59 INFO - Reading from file tmpfile_stdout 03:26:59 INFO - Current package versions: 03:26:59 INFO - argparse == 1.2.1 03:26:59 INFO - blessings == 1.6 03:26:59 INFO - blobuploader == 1.2.4 03:26:59 INFO - docopt == 0.6.1 03:26:59 INFO - functools32 == 3.2.3-2 03:26:59 INFO - jsonschema == 2.5.1 03:26:59 INFO - manifestparser == 1.1 03:26:59 INFO - mozInstall == 1.12 03:26:59 INFO - mozcrash == 0.17 03:26:59 INFO - mozdebug == 0.1 03:26:59 INFO - mozdevice == 0.48 03:26:59 INFO - mozfile == 1.2 03:26:59 INFO - mozhttpd == 0.7 03:26:59 INFO - mozinfo == 0.9 03:26:59 INFO - mozleak == 0.1 03:26:59 INFO - mozlog == 3.4 03:26:59 INFO - moznetwork == 0.27 03:26:59 INFO - mozprocess == 0.23 03:26:59 INFO - mozprofile == 0.28 03:26:59 INFO - mozrunner == 6.12 03:26:59 INFO - mozscreenshot == 0.1 03:26:59 INFO - mozsystemmonitor == 0.3 03:26:59 INFO - moztest == 0.7 03:26:59 INFO - mozversion == 1.4 03:26:59 INFO - psutil == 3.1.1 03:26:59 INFO - requests == 1.2.3 03:26:59 INFO - wsgiref == 0.1.2 03:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:59 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:26:59 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 03:26:59 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 03:26:59 INFO - Using env: {'DISPLAY': ':0', 03:26:59 INFO - 'HOME': '/home/cltbld', 03:26:59 INFO - 'LANG': 'en_US.UTF-8', 03:26:59 INFO - 'LANGUAGE': 'en_US:en', 03:26:59 INFO - 'LOGNAME': 'cltbld', 03:26:59 INFO - 'MAIL': '/var/mail/cltbld', 03:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:59 INFO - 'MOZ_NO_REMOTE': '1', 03:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:59 INFO - 'NO_EM_RESTART': '1', 03:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:59 INFO - 'PWD': '/builds/slave/test', 03:26:59 INFO - 'SHELL': '/bin/bash', 03:26:59 INFO - 'SHLVL': '1', 03:26:59 INFO - 'TERM': 'linux', 03:26:59 INFO - 'TMOUT': '86400', 03:26:59 INFO - 'USER': 'cltbld', 03:26:59 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:59 INFO - '_': '/tools/buildbot/bin/python'} 03:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 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)) 03:27:00 INFO - Cleaning up... 03:27:00 INFO - Return code: 0 03:27:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:00 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 0x7f2ef0b2af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ffca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', '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 03:27:00 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 03:27:00 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 03:27:00 INFO - Using env: {'DISPLAY': ':0', 03:27:00 INFO - 'HOME': '/home/cltbld', 03:27:00 INFO - 'LANG': 'en_US.UTF-8', 03:27:00 INFO - 'LANGUAGE': 'en_US:en', 03:27:00 INFO - 'LOGNAME': 'cltbld', 03:27:00 INFO - 'MAIL': '/var/mail/cltbld', 03:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:00 INFO - 'MOZ_NO_REMOTE': '1', 03:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:00 INFO - 'NO_EM_RESTART': '1', 03:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:00 INFO - 'PWD': '/builds/slave/test', 03:27:00 INFO - 'SHELL': '/bin/bash', 03:27:00 INFO - 'SHLVL': '1', 03:27:00 INFO - 'TERM': 'linux', 03:27:00 INFO - 'TMOUT': '86400', 03:27:00 INFO - 'USER': 'cltbld', 03:27:00 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:00 INFO - '_': '/tools/buildbot/bin/python'} 03:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:27:00 INFO - Cleaning up... 03:27:00 INFO - Return code: 0 03:27:00 INFO - Running post-action listener: _resource_record_post_action 03:27:00 INFO - Running post-action listener: _start_resource_monitoring 03:27:00 INFO - Starting resource monitoring. 03:27:00 INFO - [mozharness: 2016-10-13 10:27:00.369196Z] Finished create-virtualenv step (success) 03:27:00 INFO - [mozharness: 2016-10-13 10:27:00.369645Z] Running install step. 03:27:00 INFO - Running pre-action listener: _resource_record_pre_action 03:27:00 INFO - Running main action method: install 03:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:27:00 INFO - Reading from file tmpfile_stdout 03:27:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:27:00 INFO - Reading from file tmpfile_stdout 03:27:00 INFO - Output received: 03:27:00 INFO - Usage: mozinstall [options] installer 03:27:00 INFO - Options: 03:27:00 INFO - -h, --help show this help message and exit 03:27:00 INFO - -d DEST, --destination=DEST 03:27:00 INFO - Directory to install application into. [default: 03:27:00 INFO - "/builds/slave/test"] 03:27:00 INFO - --app=APP Application being installed. [default: firefox] 03:27:00 INFO - mkdir: /builds/slave/test/build/application 03:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:27:16 INFO - Reading from file tmpfile_stdout 03:27:16 INFO - Output received: 03:27:16 INFO - /builds/slave/test/build/application/firefox/firefox 03:27:16 INFO - Running post-action listener: _resource_record_post_action 03:27:16 INFO - [mozharness: 2016-10-13 10:27:16.626378Z] Finished install step (success) 03:27:16 INFO - [mozharness: 2016-10-13 10:27:16.626490Z] Running run-tests step. 03:27:16 INFO - Running pre-action listener: _resource_record_pre_action 03:27:16 INFO - Running main action method: run_tests 03:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:27:16 INFO - Python 2.7.3 03:27:16 INFO - Return code: 0 03:27:16 INFO - grabbing minidump binary from tooltool 03:27: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')]} 03:27:16 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 0x12fcf60>, '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 0x12fdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fe1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:27:16 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 03:27:16 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 03:27:16 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 03:27:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:27:16 INFO - Return code: 0 03:27:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:27:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:27:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:27:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:27:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 03:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 03:27:16 INFO - Using env: {'DISPLAY': ':0', 03:27:16 INFO - 'HOME': '/home/cltbld', 03:27:16 INFO - 'LANG': 'en_US.UTF-8', 03:27:16 INFO - 'LANGUAGE': 'en_US:en', 03:27:16 INFO - 'LOGNAME': 'cltbld', 03:27:16 INFO - 'MAIL': '/var/mail/cltbld', 03:27:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:27:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:16 INFO - 'MOZ_NO_REMOTE': '1', 03:27:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:27:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:16 INFO - 'NO_EM_RESTART': '1', 03:27:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:16 INFO - 'PWD': '/builds/slave/test', 03:27:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:27:16 INFO - 'SHELL': '/bin/bash', 03:27:16 INFO - 'SHLVL': '1', 03:27:16 INFO - 'TERM': 'linux', 03:27:16 INFO - 'TMOUT': '86400', 03:27:16 INFO - 'USER': 'cltbld', 03:27:16 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491', 03:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:16 INFO - '_': '/tools/buildbot/bin/python'} 03:27:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 03:27:16 INFO - mozversion application_buildid: 20161013001024 03:27:16 INFO - mozversion application_changeset: 2cebb2efe185a3a7593a738af84b7d70e327de77 03:27:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:27:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:27:16 INFO - mozversion application_name: Firefox 03:27:16 INFO - mozversion application_remotingname: firefox-dev 03:27:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:27:16 INFO - mozversion application_vendor: Mozilla 03:27:16 INFO - mozversion application_version: 51.0a2 03:27:16 INFO - mozversion platform_buildid: 20161013001024 03:27:16 INFO - mozversion platform_changeset: 2cebb2efe185a3a7593a738af84b7d70e327de77 03:27:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:27:16 INFO - mozversion platform_version: 51.0a2 03:27:16 INFO - using testdate: 1476354436 03:27:16 INFO - actual date: 1476354436 03:27:16 INFO - starting webserver on 'localhost:52614' 03:27:16 INFO - SUITE-START | Running 2 tests 03:27:16 INFO - TEST-START | dromaeo_css 03:27:16 INFO - operating with platform_type : linux_ 03:27:16 INFO - Initialising browser for dromaeo_css test... 03:27:16 INFO - TEST-INFO | started process 9708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwLUIdh/profile http://localhost:52614/getInfo.html) 03:27:21 INFO - PROCESS | 9708 | __metrics Screen width/height:1600/1200 03:27:21 INFO - PROCESS | 9708 | colorDepth:24 03:27:21 INFO - PROCESS | 9708 | Browser inner width/height: 1024/697 03:27:21 INFO - PROCESS | 9708 | __metrics 03:27:22 INFO - PROCESS | 9708 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:27:22 INFO - PROCESS | 9708 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:27:22 INFO - PROCESS | 9708 | console.error: PushService: 03:27:22 INFO - PROCESS | 9708 | stateChangeProcessEnqueue: Error transitioning state 03:27:22 INFO - PROCESS | 9708 | UnknownError 03:27:23 INFO - TEST-INFO | 9708: exit 0 03:27:23 INFO - Browser initialized. 03:27:23 INFO - Running cycle 1/1 for dromaeo_css test... 03:27:23 INFO - TEST-INFO | started process 9797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwLUIdh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:35 INFO - PROCESS | 9797 | RSS: Main: 144039936 03:27:35 INFO - PROCESS | 9797 | 03:28:26 INFO - PROCESS | 9797 | 1476354506387 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] 03:30:35 INFO - PROCESS | 9797 | Cycle 1(1): loaded http://localhost:52614/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52614/tests/dromaeo/cssquery-ext.html) 03:30:37 INFO - PROCESS | 9797 | RSS: Main: 306900992 03:30:37 INFO - PROCESS | 9797 | 03:33:40 INFO - PROCESS | 9797 | Cycle 1(1): loaded http://localhost:52614/tests/dromaeo/cssquery-ext.html (next: http://localhost:52614/tests/dromaeo/cssquery-jquery.html) 03:33:40 INFO - PROCESS | 9797 | RSS: Main: 229568512 03:33:40 INFO - PROCESS | 9797 | 03:36:42 INFO - PROCESS | 9797 | Cycle 1(1): loaded http://localhost:52614/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52614/tests/dromaeo/cssquery-mootools.html) 03:36:43 INFO - PROCESS | 9797 | RSS: Main: 194580480 03:36:43 INFO - PROCESS | 9797 | 03:39:45 INFO - PROCESS | 9797 | Cycle 1(1): loaded http://localhost:52614/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52614/tests/dromaeo/cssquery-prototype.html) 03:39:45 INFO - PROCESS | 9797 | RSS: Main: 209981440 03:39:45 INFO - PROCESS | 9797 | 03:42:48 INFO - PROCESS | 9797 | Cycle 1(1): loaded http://localhost:52614/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52614/tests/dromaeo/cssquery-yui.html) 03:42:48 INFO - PROCESS | 9797 | RSS: Main: 177098752 03:42:48 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | Cycle 1(1): loaded http://localhost:52614/tests/dromaeo/cssquery-yui.html (next: http://localhost:52614/tests/dromaeo/cssquery-dojo.html) 03:45:51 INFO - PROCESS | 9797 | RSS: Main: 260407296 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | __start_tp_report 03:45:51 INFO - PROCESS | 9797 | _x_x_mozilla_page_load 03:45:51 INFO - PROCESS | 9797 | _x_x_mozilla_page_load_details 03:45:51 INFO - PROCESS | 9797 | |i|pagename|runs| 03:45:51 INFO - PROCESS | 9797 | |0;dojo.html;3068.6777888321726;3199.280161963558;3208.4064448077124;3222.7099561039504;3257.706806387425;5595.972020139899;5620.943790562093;5671.801486947952;5690.800821971232;5703.144528320754;5604.355499117602;5687.090065589497;5703.40114288;5720.914186287209;5750.079987202049;5145.3311069560905;5146.356705411824;5169.974150129263;5184.740762961856;5249.501297376743;5074.340335756365;5074.974625126888;5266.894662106755;5268.420473747885;5291.179867120583;5037.395512538501;5084.79660813567;5089.592832573385;5105.285260063594;5157.664751791141;5088.287639730441;5097.108006098918;5157.53582177602;5157.974210128926;5187.48125187482;4934.481879402683;5055.74721263939;5070.366204224472;5104.821331253425;5122.28288039671;5405.351357837085;5426.403095659474;5430.56555475561;5448.564114870801;5503.587230957694;5848.561357898175;5856.502197313233;5966.761329546813;5968.432998865101;5970.6716130612795;5937.495312898409;5989.3112292086535;6004.399560044004;6004.489618382443;6010.8497287567725;5551.333839939233;5585.664860108406;5633.746481408305;5639.943600563942;5650.971745141248;6164.722587483574;6190.628562286187;6197.690115494297;6201.472874805602;6203.100550420164;5711.628744131618;5963.5825492216;5964.671943043088;5981.5214782817275;5984.820455386345;5824.88350232993;5962.165296858443;6027.005544085177;6029.698515074228;6142.78500252489;6067.362926892701;6077.787277445268;6081.969590152021;6083.239595050619;6100.938990610037;6054.273487181478;6102.298235702951;6110.572259941761;6119.663418512025;6131.754729810847;4448.310511870665;4475.149721552894;4576.4737055239075;4601.608863246594;4611.469680986663;4100.487439070116;4107.815148318333;4135.462389889295;4147.834086636561;4166.250074986471;5746.626469279567;5747.39652336507;5748.942510574924;5757.107648314518;5763.423657634287;5878.735456904449;5919.289685237713;5926.970365148147;5958.1062840573395;6129.3870612939245;5684.09054551278;5685.914711279334;5690.146478028246;5701.087825947911;5722.513586345207;5057.671251368649;5063.06333328328;5064.569511591482;5065.772040258197;5096.210087436453;6089.330173680892;6112.083187521729;6124.472479235491;6151.630902145886;6202.472789812826;5967.433093856077;6033.487153591994;6036.879262414815;6069.514438844793;6073.48375388097;6148.78479253224;6158.199434073542;6162.969185154046;6165.5375846810775;6236.189295391751;5733.971330143323;5930.673812940329;5951.553633477593;5959.165716799738;5961.940380596312;5951.166836642787;5972.104184372379;5998.670073146019;6025.8192254232445;6134.815955521163;5873.823785286449;5945.851353716192;5956.344802071855;5969.641821490724;5973.940260597338;5882.323532793784;5938.792142275;5947.1079338099635;5949.702514874326;5949.940500594938;5715.542756579547;5786.768529258951;5803.303603567599;5845.795397161079;5892.970535147297;5887.7056147193325;5906.350301466921;5907.616004959664;5922.763089476372;5928.644281343133;5595.5523558116065;5702;5702.230198923092;5739;5765.048766953573 03:45:51 INFO - PROCESS | 9797 | |1;ext.html;542.1666187232757;592.0527156549521;603.2519675602251;612.2928018139046;617.1421723803912;35327.823360883194;44850;45692.771536142354;45716.771416142954;45759.77120114395;48332;48741.26888096681;48788.26817597739;48842;48850;9291.72124836256;9452.905470945305;9458.432494050345;9463.574139163755;9464.337496375256;8441.746747597583;8637.093105223956;10921.726956826064;11133;11148;11294.717632059223;11388.601398950997;11394.088472922187;11408.087353011739;11409.942950285236;11198.496067676933;11274.267172633752;11278.943605281962;11283;11288.266262692981;10915.890841091647;11050.668479945614;11066.833997490045;11072.169587280987;11074.833877491845;45443;45704.542954570694;45778.31332530015;45794;45806.31290530645;3562.9109272268142;3685.981570092132;3713.294474049949;3751.7936513491745;3927.136030073379;34190.48714269263;34202.31595368079;34245.486317705254;34362.14094647629;34595.30809383823;4603.491097351951;4619.307103934403;4679.555442232982;4717.976410117927;4727.22000869856;340179;342263;342537;343177;344088;62574.37425625777;62675;63075.36924630787;63084.05373919395;63264.68367658132;40256.396154057715;41405.37891931624;41824.58175418268;44181.77909110434;44194.558054419045;19763.308284209983;20136.093875775627;20162.29431969874;20162.495937601678;20166.596668066555;13569.52506662262;13788.862111378958;15371.308291126927;15435;15465;19818.50453738668;20812.58374832495;20832.27087051946;21612.4596885076;21633.783662163492;7369.22623124575;7532.43506736986;7671.578063206577;7697.461177717506;7704.807379815549;1043.5408420295046;1153.6481373181093;1160.6634076118;1164.7204670878928;1176.2942234659135;34205;34889.82555087208;34985.82507087499;35001.64998349984;35006.47490287648;1186.5372504723166;1189.149757923089;1193.8746431624727;1203.602811072344;1253.6865783554113;18052.277908883498;18150.18324175389;18399.72400413995;18511;18579.628407431777;548773;556100;556794;557141;559866.2006689941;62876;63438.682806585675;63448.36551634517;63505;63550;1316.4470922212693;1372.0052961602757;1436.7198396312829;1454.8326942401548;1460.1968917095555;33062.33875322528;35748.821255894596;38189.42715859209;38214;38410.42384364181;59775.402245976984;60436.697816512395;60499.69750151221;60507;60511;479156;479157;480067;481327;484188;27378.452430951667;28045;29342.41315173642;30761;31480.2129946744;52444;52777.736111319195;53309.466905332;53386;53837;43528.56471435245;43666;43908;43992.56007439885;45868.77065614651;43512.564874352116;44796.32805507855;44833.10333793371;46185.30722039105;46540.30189547227;44137.779311104525;44503.10993780171;45028.09943801171;45514;45750.77124614356;37206;38856.80571597124;39059.60940390673;39408.2118357637;40040.3993940097;28984.710152898202;29042.709572904;29629.5555566671;29720;29761.404771904006 03:45:51 INFO - PROCESS | 9797 | |2;jquery.html;406.16330359459533;411.709744630036;421.4310680581215;425.77008415455646;444.5088177563792;19261.229550817967;20588.279410220628;20865.37403877886;21012.264570740026;21058.473538161554;19238.13428395722;19911.800881991177;19914.203431862745;20339.694904576445;20575.794242057575;5900.144479050525;6176.938230617681;6179.443850053494;6185.072239164139;6191.566590338678;5697.572682048863;5787.623804452697;5937;5963.612365196248;6081.331053584102;5984.072468767348;6034.396560343975;6042.184305118797;6042.758289668408;6044.818655440344;5872.031114866042;5912.438318359749;5979.431953964367;5980.55145864062;5985.221921150222;5672.376038635747;5817.360090390053;5819.418058194189;5821.883562328772;5826.592138550303;13595.728085438335;13615.31923403826;13928.721425571533;13944.860551394459;13945.16329020252;2522.1046528482493;2977.136630377188;3072.7080927311868;3091.633466135458;3144.7326977206967;10451.425171615558;10619.362838229654;10668;10855.40295283759;10886.29239099456;1969.1138987455702;2003.91984320627;2004.7494063242095;2039.6838490034067;2050.7231523744254;89343.55328223383;89826.40694372218;90638.54680726553;91748.08251917463;92462;11592.246503977214;11626.941865290788;11684.532618695326;11765.529378824922;11927.463264153133;17196.31214751396;17199.828001719823;17211.827881721274;17214.569635759206;17215.56961075983;8191.262786349257;8203.30271926893;8247.092819789817;8301.543415112106;8560.871586926076;7595.392368610498;7614.428917831185;7790.143084260727;7811.336036436966;7866.960665196638;4965.4041515018425;5023.924641130386;5084.313617661568;5103.362079740033;5278.392984806719;1949.4931317857458;1968.4586738646701;1991.571812060385;2040.7653119891402;2058.2487392101775;949.9145076943108;950.458238803889;985.6303886042734;988.2538683294067;1048.6696690542456;20697.172113115306;20764.480887977923;20765.79234207669;20868.89565552193;21032.158713651283;1031.489412740698;1041.8645576075062;1042.338115296792;1062.134360496193;1062.4953147255146;10606.098481628993;11668.70828229284;11681.532738690526;11725.765484690259;11729.4135293235;475048;475497;489966;494758;500254;96513.0348696518;98263;100027.49986250167;100387;101639.49180254052;986.413084214891;1031.7162780235344;1040.0535512683573;1053.7418332508585;1062.3148069495132;17727.556811079827;18037.909810450863;18449.446516604523;18534.25862965466;18746.156422961274;41793.79103104465;44351.2259509616;44772.55227447814;44772.77613611911;46178.76910615426;57304;58618;59655.70172149112;61390.69304653627;62148.37851621426;8597.18326758957;8989.640414383352;9014.864777028219;9148.130927561871;9159.908400916172;13193.538226162422;13989.440422382992;14030.789538156732;14105.576832695035;14351.354189061725;11563.826542602035;11730.76538469243;11978.76042479163;12698.809517857055;13203;11706.590269340531;12277.447514861675;12543.623691289276;12629.93685031569;12693;12342.938285308875;12452.626421207378;12737.108402411743;12799.29603871796;13116.606501804961;8699.13008699125;8723;9119.863202052107;9177.908220917705;9542.284328675238;12155.513779449077;12488.687782805502;12558.874411256136;12604.747905042032;12812 03:45:51 INFO - PROCESS | 9797 | |3;mootools.html;1396.7206558688265;1488.8213414390275;1532.501936870517;1662.6757782232462;1797.4631689905132;8147.6333564989645;8477.15228477152;8874.467531948072;8918.509481978488;8919.554022298891;8541.487510749359;8746.169113934182;8746.431481953685;8983.056779038188;8990.460572365646;2468.345888339587;2522.987385063072;2524.9747502525015;2526.570483017882;2530.8354956927833;2438.51229754049;2502.1117503286346;2513.158092039169;2514.974850251492;2515.182565666166;2483.7640424159677;2514.409113858251;2549.1332946798084;2549.2734570647367;2550.349660836484;2409.144753612469;2409.385606670288;2415.251272105644;2459.6433517140094;2471.6416119662726;2415.855048697066;2445.4619983603757;2449.64480150379;2452.0559584560074;2454.1901172612993;8680.479171249683;9008.549572521348;9094.86357704635;9161.404508706913;9162.908370916271;5503.807366742145;5624.578156638268;5639.718014099279;5668.68822214778;5672;3351.1287065362935;3367.579052618423;3418.8803391881165;3422.401079811048;3432.1248081739245;1161.3206274329527;1187.5724739094005;1188.5958774016876;1192.3799624195456;1206.5234232478122;12484.625461236177;12632.936835315857;12709.110362274738;12714.74570508594;12717.30054846983;4700.106979673919;4723.881902952454;4859.1010663027455;4875.561199491992;5022.397312322545;10843.295185812896;10871.130309575216;10903.890961090447;10908.836367454494;10917.39954302521;5589.0219211637805;5602;5606.243157173811;5613.045782217033;5622.437756224426;4534.478534968521;4659.394278743742;4985.875353116201;4995;5081.771320290596;4036.495438070241;4102.50769907613;4166;4193.287141186006;4194.119234960632;1356.925369104689;1374.9175049497062;1386.4869998100767;1399.923004234777;1426.5577670922048;889.4663202078701;892.7768057985504;901.4771432569095;914.8216097860986;939.4879790514186;5860.70696465175;5900.291964964232;5905.055191169478;5910.615809972338;6194.101855231056;1001.6544292219173;1015.192921627308;1063.4948399510172;1070.7537266428765;1091.159806948661;3705.6294370562728;3771.9434208486896;3786.734928554975;3827.4641550182996;3838.731288809812;11118.22172447937;11481.36852473122;11518.94240528792;11524.711882203012;11545.595904143483;11365.602203922823;11393.373364465035;11406.486708098155;11409.42952852354;11420.42897855104;8060.274575288252;8398.454100483572;8430.494170349799;8623;8632.611532481053;5808.825735227781;5932.406759324033;5951.018082016419;5966.075258334965;6083.543734219863;10171.135453486537;10308.896911031094;10334.018268264503;10453.006964338376;10465.9476702616;10356.637517686844;10462.895371046496;10634.255602107778;10733.19501037441;10757.46212689378;7280.490365674352;7458.142313633784;7707.845843083219;7713.344365728976;7860.174681658344;8286.212809783061;8339.666413343399;8561.657533698582;8562.143785621638;8574;7738.264864837832;7892.960535197287;7933.404994625542;8125.106238313662;8303.00363956329;7868.370530357673;7876.72431464896;7889.921100788919;7906.802329941567;7967.163447837894;6553.5084868635995;6648.601083934979;6653.467722582085;6672.833179170365;6706.329367063255;5036.924446133237;5081.745912704424;5104.285400043923;5141.460146684544;5260.316158899319;5032.547070763502;5045.772940217773;5073.568746656576;5117.078925793393;5261.973690131525 03:45:51 INFO - PROCESS | 9797 | |4;prototype.html;320.53522392530823;324.4241471388286;332.82194026195975;333.0358611818785;335.2942056970076;5303.893922121555;5486.725663716818;5537.197106419567;5634.63374880633;5649.689267090308;5268.341457317835;5374.7043912584795;5394.730263486829;5409.404965453797;5458.890822183554;4278.871633850989;4279.2083464559;4286.249906266391;4288.914221715564;4304.31131019037;4163.833446662145;4274.615284624383;4276.401303817468;4294.806733696975;4310.935335969947;4209.726367786099;4219.831206751726;4222.134462435209;4222.134462435209;4226.387173859788;4214.36784482327;4217.093324935123;4218.936715949263;4230.123516009113;4234.089670720779;3651.835667394974;3744.962550374516;3745.3445647011936;3746.8572085513906;3752.4746535485056;5277.812492189227;5280.788768449257;5292.824305416758;5300.284461597712;5314.388845282765;4860.708357498526;4868.367112275417;4870.14772414826;4870.245112007645;4871.196252618349;5554.305711786027;5707.600467967244;5707.971460142714;5709.857253568694;5712.685802280873;4089.1821635672686;4089.8159582818844;4118.835246590133;4119.691023173274;4121.1345617420375;5869.44459718175;5898.498627616657;5921.891007221243;5947.10793380992;5960.523158147338;4087.8773636790943;4185.246655602021;4187.183499217624;4196.622303992655;4234.513031001413;4236.661067114624;4242.151569686075;4252.4471818664;4253.659707223414;4294.291441912112;4783.736894470838;4801.855944321676;4808.422989241313;4820.204666230033;4829.710217386968;5513.070425351175;5720.628159169641;5736.168255602997;5753.532849123447;5758.683272420057;1437.7843323501559;1458.3218803256325;1459.1391079263285;1459.1682740837832;1467.2810322941682;890.670451932776;915.4598786715869;924.3945215883607;930.3859452761145;931.4923366765129;4876.439209490883;4898.510148985144;4935.975320123376;4969;4982.427020892644;5177.747679759317;5193.948060519422;5207.635465517453;5259.316288882421;5266.868328291824;4799.544043315949;4990.3762029746285;4991.975040124848;4995.951659819457;5020.34735484392;2777.750002499785;2788.7211278872355;2795.916122516328;2799.9440011199663;2802.691703912609;5272.472752724697;5321.988822123784;5340.759665815048;5380.704061276589;5390.946090539044;5280.683159010395;5316.867078323073;5601.80393686219;6020.76574302267;6088.173547935601;6102.206713127265;6131.110988906584;6137.606333529927;6151.323354431009;6157.53818463626;5613.859653508695;5934.495567876779;5964.807038592386;5968.910466343095;5976.282846058501;5848.853778655568;5854.443827836349;5862.856742935087;5880.26496687914;5905.852353691193;6113;6163.657810032159;6175.475084617858;6186.164867742978;6199.380061993765;3893.929169478416;3906.8632597858937;4135.958640413557;4145.97927010363;4165.291900376883;5856.682246494504;5896.764129434775;5914.822555323347;5938.881222375442;5943.465088142088;5674.801381951612;5808.589435384268;5813.6221145625395;5817.650940943557;5830.970845145747;5767.625104368202;5789.710514474175;5802.4197580241635;5807.709614519173;5810.94189058104;5708.942910570841;5720.456556627114;5729.1119876419225;5734.856628584152;5754.53963682896;5559.972200138974;5746.706990927008;5779.942200577941;5787.247657804459;5791.652500849794;5642.71786410686;5654.85862853432;5655.971720141373;5664.394378585732;5669.291338582677 03:45:51 INFO - PROCESS | 9797 | |5;yui.html;747.89155572442;760.8440269744702;769.3537428560012;787.5707739282088;847.9533625650587;14231.217283049427;14279.357428915699;14280.785788213185;15075.095494270337;15836.445724399648;15091.169985650813;15128.924355378207;15222.314995825187;15245.39018439261;15288;707.3245050976305;729.4565548666251;843.1989609870617;850.8298340331927;873.4671850171391;865.1478293880523;876.2157868707503;876.5047748022379;876.5135349880813;876.9035406105355;919.4115765909823;919.6275508419101;919.659725901414;919.8114386550775;920.3787443475627;744.5607091815828;763.1338430880935;869.0657543141149;918.8943271523793;919.462114662923;662.7978466567695;664.9634270115142;665.5108495255982;666.1340257665016;666.6133642487346;15017;15039.248037598076;15045.322960466805;15048.699026019527;15061.397544098223;14609.853901460956;14734.484293049692;14735.484258051023;14878.181700006604;15078.321475533518;13237.271950042743;13602.863971360259;13616.523421680293;13654.249016304098;14006.369713362923;4520.3219517072375;4531.660125490568;4605.861824145281;4638.628909687251;4694.24892017279;88179.55910220408;91008.99999999933;91693.54153229258;93087.53456232675;93167.53416232875;11490.770184596262;11515.884841151481;11606.651800446;11650.184487085822;12207.755844883053;21096.78903210979;21802;21914.561708765734;21915.232966846084;21926.45183870384;9598.856017159747;9620.326577139673;9676.56455459506;9759.951200243953;9925.702228933143;5215.713135777492;5357.987443928338;5428;5432.592555558349;5435.239066530688;3537.7169826413824;3672.375696131664;3672.485851980725;3672.889813305605;3673.1368128489785;634.2990994950605;645.3320812958565;648.8896887529131;651.840299126717;657.5495785387026;637.7070316374077;750.6847124207845;756.9848603027908;762.4091329219788;796.5181065455432;10137.64518241858;10278.17774578032;10410.947945260225;10462.843057354146;10464.739999502184;734.7832389445126;740.7703611880227;749.1833901047884;758.7192738686721;764.8776195808643;4530.728156310566;4844.32179494871;4869.099216644932;4877.804887804448;4990.575801056951;103413.48293258486;117070.41464792621;121485;121714;121874;104569.47715261375;106177.46911265394;106353.46823265834;107381;109669.45165274122;89665;90360.5481972586;91563.54218228866;92468.53765731129;92723;9493;9628.71113866585;9690.46702431373;9734.610615575297;9790;40062;53409;54252.10117645864;54381.18428223501;56930.430695692514;78733.60633196797;82094;83764;84323.15676843152;84699.5765021171;12705.42825572833;12711.36443177819;12803.48786048585;13016.088873778745;13025.869741302844;14095.506657266946;14435.855641443452;14473.203973781543;14473.348699308346;14625.707485850437;13118.344082795631;13283.530056277934;13716.725665486834;14113.576592702235;14276.357563909856;12905.483780648668;13190.406431710788;13335.799963000756;13450.86549134496;14223.928880355532;11630.418479076181;12618;12731.299778512272;12758.808617870925;13697.109687870257;10593.947030264799;10857.808511165764;10941.726456838644;11044.337339759319;11065.889341106807;11039.282446640942;11134.832977505506;11364.602238921598;11416.714582135513;11417 03:45:51 INFO - PROCESS | 9797 | __end_tp_report 03:45:51 INFO - PROCESS | 9797 | __start_cc_report 03:45:51 INFO - PROCESS | 9797 | _x_x_mozilla_cycle_collect,1507 03:45:51 INFO - PROCESS | 9797 | __end_cc_report 03:45:51 INFO - PROCESS | 9797 | __startTimestamp1476355551599__endTimestamp 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | ------- Summary: start ------- 03:45:51 INFO - PROCESS | 9797 | Number of tests: 6 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | [#0] dojo.html Cycles:165 Average:5581.58 Median:5765.05 stddev:642.81 (11.2%) stddev-sans-first:613.81 03:45:51 INFO - PROCESS | 9797 | Values: 3068.7 3199.3 3208.4 3222.7 3257.7 5596.0 5620.9 5671.8 5690.8 5703.1 5604.4 5687.1 5703.4 5720.9 5750.1 5145.3 5146.4 5170.0 5184.7 5249.5 5074.3 5075.0 5266.9 5268.4 5291.2 5037.4 5084.8 5089.6 5105.3 5157.7 5088.3 5097.1 5157.5 5158.0 5187.5 4934.5 5055.7 5070.4 5104.8 5122.3 5405.4 5426.4 5430.6 5448.6 5503.6 5848.6 5856.5 5966.8 5968.4 5970.7 5937.5 5989.3 6004.4 6004.5 6010.8 5551.3 5585.7 5633.7 5639.9 5651.0 6164.7 6190.6 6197.7 6201.5 6203.1 5711.6 5963.6 5964.7 5981.5 5984.8 5824.9 5962.2 6027.0 6029.7 6142.8 6067.4 6077.8 6082.0 6083.2 6100.9 6054.3 6102.3 6110.6 6119.7 6131.8 4448.3 4475.1 4576.5 4601.6 4611.5 4100.5 4107.8 4135.5 4147.8 4166.3 5746.6 5747.4 5748.9 5757.1 5763.4 5878.7 5919.3 5927.0 5958.1 6129.4 5684.1 5685.9 5690.1 5701.1 5722.5 5057.7 5063.1 5064.6 5065.8 5096.2 6089.3 6112.1 6124.5 6151.6 6202.5 5967.4 6033.5 6036.9 6069.5 6073.5 6148.8 6158.2 6163.0 6165.5 6236.2 5734.0 5930.7 5951.6 5959.2 5961.9 5951.2 5972.1 5998.7 6025.8 6134.8 5873.8 5945.9 5956.3 5969.6 5973.9 5882.3 5938.8 5947.1 5949.7 5949.9 5715.5 5786.8 5803.3 5845.8 5893.0 5887.7 5906.4 5907.6 5922.8 5928.6 5595.6 5702.0 5702.2 5739.0 5765.0 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | [#1] ext.html Cycles:180 Average:63460.00 Median:21493.37 stddev:123946.49 (576.7%) stddev-sans-first:124204.17 03:45:51 INFO - PROCESS | 9797 | Values: 542.2 592.1 603.3 612.3 617.1 35327.8 44850.0 45692.8 45716.8 45759.8 48332.0 48741.3 48788.3 48842.0 48850.0 9291.7 9452.9 9458.4 9463.6 9464.3 8441.7 8637.1 10921.7 11133.0 11148.0 11294.7 11388.6 11394.1 11408.1 11409.9 11198.5 11274.3 11278.9 11283.0 11288.3 10915.9 11050.7 11066.8 11072.2 11074.8 45443.0 45704.5 45778.3 45794.0 45806.3 3562.9 3686.0 3713.3 3751.8 3927.1 34190.5 34202.3 34245.5 34362.1 34595.3 4603.5 4619.3 4679.6 4718.0 4727.2 340179.0 342263.0 342537.0 343177.0 344088.0 62574.4 62675.0 63075.4 63084.1 63264.7 40256.4 41405.4 41824.6 44181.8 44194.6 19763.3 20136.1 20162.3 20162.5 20166.6 13569.5 13788.9 15371.3 15435.0 15465.0 19818.5 20812.6 20832.3 21612.5 21633.8 7369.2 7532.4 7671.6 7697.5 7704.8 1043.5 1153.6 1160.7 1164.7 1176.3 34205.0 34889.8 34985.8 35001.6 35006.5 1186.5 1189.1 1193.9 1203.6 1253.7 18052.3 18150.2 18399.7 18511.0 18579.6 548773.0 556100.0 556794.0 557141.0 559866.2 62876.0 63438.7 63448.4 63505.0 63550.0 1316.4 1372.0 1436.7 1454.8 1460.2 33062.3 35748.8 38189.4 38214.0 38410.4 59775.4 60436.7 60499.7 60507.0 60511.0 479156.0 479157.0 480067.0 481327.0 484188.0 27378.5 28045.0 29342.4 30761.0 31480.2 52444.0 52777.7 53309.5 53386.0 53837.0 43528.6 43666.0 43908.0 43992.6 45868.8 43512.6 44796.3 44833.1 46185.3 46540.3 44137.8 44503.1 45028.1 45514.0 45750.8 37206.0 38856.8 39059.6 39408.2 40040.4 28984.7 29042.7 29629.6 29720.0 29761.4 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | [#2] jquery.html Cycles:180 Average:29700.81 Median:21022.21 stddev:80689.68 (383.8%) stddev-sans-first:80886.06 03:45:51 INFO - PROCESS | 9797 | Values: 406.2 411.7 421.4 425.8 444.5 19261.2 20588.3 20865.4 21012.3 21058.5 19238.1 19911.8 19914.2 20339.7 20575.8 5900.1 6176.9 6179.4 6185.1 6191.6 5697.6 5787.6 5937.0 5963.6 6081.3 5984.1 6034.4 6042.2 6042.8 6044.8 5872.0 5912.4 5979.4 5980.6 5985.2 5672.4 5817.4 5819.4 5821.9 5826.6 13595.7 13615.3 13928.7 13944.9 13945.2 2522.1 2977.1 3072.7 3091.6 3144.7 10451.4 10619.4 10668.0 10855.4 10886.3 1969.1 2003.9 2004.7 2039.7 2050.7 89343.6 89826.4 90638.5 91748.1 92462.0 11592.2 11626.9 11684.5 11765.5 11927.5 17196.3 17199.8 17211.8 17214.6 17215.6 8191.3 8203.3 8247.1 8301.5 8560.9 7595.4 7614.4 7790.1 7811.3 7867.0 4965.4 5023.9 5084.3 5103.4 5278.4 1949.5 1968.5 1991.6 2040.8 2058.2 949.9 950.5 985.6 988.3 1048.7 20697.2 20764.5 20765.8 20868.9 21032.2 1031.5 1041.9 1042.3 1062.1 1062.5 10606.1 11668.7 11681.5 11725.8 11729.4 475048.0 475497.0 489966.0 494758.0 500254.0 96513.0 98263.0 100027.5 100387.0 101639.5 986.4 1031.7 1040.1 1053.7 1062.3 17727.6 18037.9 18449.4 18534.3 18746.2 41793.8 44351.2 44772.6 44772.8 46178.8 57304.0 58618.0 59655.7 61390.7 62148.4 8597.2 8989.6 9014.9 9148.1 9159.9 13193.5 13989.4 14030.8 14105.6 14351.4 11563.8 11730.8 11978.8 12698.8 13203.0 11706.6 12277.4 12543.6 12629.9 12693.0 12342.9 12452.6 12737.1 12799.3 13116.6 8699.1 8723.0 9119.9 9177.9 9542.3 12155.5 12488.7 12558.9 12604.7 12812.0 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | [#3] mootools.html Cycles:180 Average:5937.40 Median:4596.94 stddev:3383.38 (73.6%) stddev-sans-first:3375.66 03:45:51 INFO - PROCESS | 9797 | Values: 1396.7 1488.8 1532.5 1662.7 1797.5 8147.6 8477.2 8874.5 8918.5 8919.6 8541.5 8746.2 8746.4 8983.1 8990.5 2468.3 2523.0 2525.0 2526.6 2530.8 2438.5 2502.1 2513.2 2515.0 2515.2 2483.8 2514.4 2549.1 2549.3 2550.3 2409.1 2409.4 2415.3 2459.6 2471.6 2415.9 2445.5 2449.6 2452.1 2454.2 8680.5 9008.5 9094.9 9161.4 9162.9 5503.8 5624.6 5639.7 5668.7 5672.0 3351.1 3367.6 3418.9 3422.4 3432.1 1161.3 1187.6 1188.6 1192.4 1206.5 12484.6 12632.9 12709.1 12714.7 12717.3 4700.1 4723.9 4859.1 4875.6 5022.4 10843.3 10871.1 10903.9 10908.8 10917.4 5589.0 5602.0 5606.2 5613.0 5622.4 4534.5 4659.4 4985.9 4995.0 5081.8 4036.5 4102.5 4166.0 4193.3 4194.1 1356.9 1374.9 1386.5 1399.9 1426.6 889.5 892.8 901.5 914.8 939.5 5860.7 5900.3 5905.1 5910.6 6194.1 1001.7 1015.2 1063.5 1070.8 1091.2 3705.6 3771.9 3786.7 3827.5 3838.7 11118.2 11481.4 11518.9 11524.7 11545.6 11365.6 11393.4 11406.5 11409.4 11420.4 8060.3 8398.5 8430.5 8623.0 8632.6 5808.8 5932.4 5951.0 5966.1 6083.5 10171.1 10308.9 10334.0 10453.0 10465.9 10356.6 10462.9 10634.3 10733.2 10757.5 7280.5 7458.1 7707.8 7713.3 7860.2 8286.2 8339.7 8561.7 8562.1 8574.0 7738.3 7893.0 7933.4 8125.1 8303.0 7868.4 7876.7 7889.9 7906.8 7967.2 6553.5 6648.6 6653.5 6672.8 6706.3 5036.9 5081.7 5104.3 5141.5 5260.3 5032.5 5045.8 5073.6 5117.1 5262.0 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | [#4] prototype.html Cycles:180 Average:4743.79 Median:5296.55 stddev:1411.40 (26.6%) stddev-sans-first:1375.75 03:45:51 INFO - PROCESS | 9797 | Values: 320.5 324.4 332.8 333.0 335.3 5303.9 5486.7 5537.2 5634.6 5649.7 5268.3 5374.7 5394.7 5409.4 5458.9 4278.9 4279.2 4286.2 4288.9 4304.3 4163.8 4274.6 4276.4 4294.8 4310.9 4209.7 4219.8 4222.1 4222.1 4226.4 4214.4 4217.1 4218.9 4230.1 4234.1 3651.8 3745.0 3745.3 3746.9 3752.5 5277.8 5280.8 5292.8 5300.3 5314.4 4860.7 4868.4 4870.1 4870.2 4871.2 5554.3 5707.6 5708.0 5709.9 5712.7 4089.2 4089.8 4118.8 4119.7 4121.1 5869.4 5898.5 5921.9 5947.1 5960.5 4087.9 4185.2 4187.2 4196.6 4234.5 4236.7 4242.2 4252.4 4253.7 4294.3 4783.7 4801.9 4808.4 4820.2 4829.7 5513.1 5720.6 5736.2 5753.5 5758.7 1437.8 1458.3 1459.1 1459.2 1467.3 890.7 915.5 924.4 930.4 931.5 4876.4 4898.5 4936.0 4969.0 4982.4 5177.7 5193.9 5207.6 5259.3 5266.9 4799.5 4990.4 4992.0 4996.0 5020.3 2777.8 2788.7 2795.9 2799.9 2802.7 5272.5 5322.0 5340.8 5380.7 5390.9 5280.7 5316.9 5601.8 6020.8 6088.2 6102.2 6131.1 6137.6 6151.3 6157.5 5613.9 5934.5 5964.8 5968.9 5976.3 5848.9 5854.4 5862.9 5880.3 5905.9 6113.0 6163.7 6175.5 6186.2 6199.4 3893.9 3906.9 4136.0 4146.0 4165.3 5856.7 5896.8 5914.8 5938.9 5943.5 5674.8 5808.6 5813.6 5817.7 5831.0 5767.6 5789.7 5802.4 5807.7 5810.9 5708.9 5720.5 5729.1 5734.9 5754.5 5560.0 5746.7 5779.9 5787.2 5791.7 5642.7 5654.9 5656.0 5664.4 5669.3 03:45:51 INFO - PROCESS | 9797 | 03:45:51 INFO - PROCESS | 9797 | [#5] yui.html Cycles:180 Average:22015.17 Median:22291.16 stddev:32250.33 (144.7%) stddev-sans-first:32301.27 03:45:51 INFO - PROCESS | 9797 | Values: 747.9 760.8 769.4 787.6 848.0 14231.2 14279.4 14280.8 15075.1 15836.4 15091.2 15128.9 15222.3 15245.4 15288.0 707.3 729.5 843.2 850.8 873.5 865.1 876.2 876.5 876.5 876.9 919.4 919.6 919.7 919.8 920.4 744.6 763.1 869.1 918.9 919.5 662.8 665.0 665.5 666.1 666.6 15017.0 15039.2 15045.3 15048.7 15061.4 14609.9 14734.5 14735.5 14878.2 15078.3 13237.3 13602.9 13616.5 13654.2 14006.4 4520.3 4531.7 4605.9 4638.6 4694.2 88179.6 91009.0 91693.5 93087.5 93167.5 11490.8 11515.9 11606.7 11650.2 12207.8 21096.8 21802.0 21914.6 21915.2 21926.5 9598.9 9620.3 9676.6 9760.0 9925.7 5215.7 5358.0 5428.0 5432.6 5435.2 3537.7 3672.4 3672.5 3672.9 3673.1 634.3 645.3 648.9 651.8 657.5 637.7 750.7 757.0 762.4 796.5 10137.6 10278.2 10410.9 10462.8 10464.7 734.8 740.8 749.2 758.7 764.9 4530.7 4844.3 4869.1 4877.8 4990.6 103413.5 117070.4 121485.0 121714.0 121874.0 104569.5 106177.5 106353.5 107381.0 109669.5 89665.0 90360.5 91563.5 92468.5 92723.0 9493.0 9628.7 9690.5 9734.6 9790.0 40062.0 53409.0 54252.1 54381.2 56930.4 78733.6 82094.0 83764.0 84323.2 84699.6 12705.4 12711.4 12803.5 13016.1 13025.9 14095.5 14435.9 14473.2 14473.3 14625.7 13118.3 13283.5 13716.7 14113.6 14276.4 12905.5 13190.4 13335.8 13450.9 14223.9 11630.4 12618.0 12731.3 12758.8 13697.1 10593.9 10857.8 10941.7 11044.3 11065.9 11039.3 11134.8 11364.6 11416.7 11417.0 03:45:51 INFO - PROCESS | 9797 | -------- Summary: end -------- 03:45:51 INFO - PROCESS | 9797 | 03:45:52 INFO - TEST-INFO | 9797: exit 0 03:45:52 INFO - TEST-OK | dromaeo_css | took 1115368ms 03:45:52 INFO - TEST-START | kraken 03:45:52 INFO - operating with platform_type : linux_ 03:45:52 INFO - Initialising browser for kraken test... 03:45:52 INFO - TEST-INFO | started process 10113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0_QMjQ/profile http://localhost:52614/getInfo.html) 03:45:56 INFO - PROCESS | 10113 | __metrics Screen width/height:1600/1200 03:45:56 INFO - PROCESS | 10113 | colorDepth:24 03:45:56 INFO - PROCESS | 10113 | Browser inner width/height: 1024/697 03:45:56 INFO - PROCESS | 10113 | __metrics 03:45:57 INFO - PROCESS | 10113 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:45:57 INFO - PROCESS | 10113 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:45:57 INFO - PROCESS | 10113 | console.error: PushService: 03:45:57 INFO - PROCESS | 10113 | stateChangeProcessEnqueue: Error transitioning state 03:45:57 INFO - PROCESS | 10113 | UnknownError 03:45:58 INFO - TEST-INFO | 10113: exit 0 03:45:58 INFO - Browser initialized. 03:45:58 INFO - Running cycle 1/1 for kraken test... 03:45:58 INFO - TEST-INFO | started process 10203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0_QMjQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:46:10 INFO - PROCESS | 10203 | RSS: Main: 144486400 03:46:10 INFO - PROCESS | 10203 | 03:46:12 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52614/tests/kraken/driver.html?testName=audio-beat-detection) 03:46:13 INFO - PROCESS | 10203 | RSS: Main: 186310656 03:46:13 INFO - PROCESS | 10203 | 03:46:16 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52614/tests/kraken/driver.html?testName=audio-dft) 03:46:16 INFO - PROCESS | 10203 | RSS: Main: 175460352 03:46:16 INFO - PROCESS | 10203 | 03:46:19 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52614/tests/kraken/driver.html?testName=audio-fft) 03:46:20 INFO - PROCESS | 10203 | RSS: Main: 267563008 03:46:20 INFO - PROCESS | 10203 | 03:46:22 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52614/tests/kraken/driver.html?testName=audio-oscillator) 03:46:22 INFO - PROCESS | 10203 | RSS: Main: 173301760 03:46:22 INFO - PROCESS | 10203 | 03:46:24 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52614/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:46:25 INFO - PROCESS | 10203 | RSS: Main: 175366144 03:46:25 INFO - PROCESS | 10203 | 03:46:28 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52614/tests/kraken/driver.html?testName=imaging-darkroom) 03:46:29 INFO - PROCESS | 10203 | RSS: Main: 211062784 03:46:29 INFO - PROCESS | 10203 | 03:46:32 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52614/tests/kraken/driver.html?testName=imaging-desaturate) 03:46:32 INFO - PROCESS | 10203 | RSS: Main: 209715200 03:46:32 INFO - PROCESS | 10203 | 03:46:35 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52614/tests/kraken/driver.html?testName=json-parse-financial) 03:46:36 INFO - PROCESS | 10203 | RSS: Main: 209399808 03:46:36 INFO - PROCESS | 10203 | 03:46:37 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52614/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:46:38 INFO - PROCESS | 10203 | RSS: Main: 171085824 03:46:38 INFO - PROCESS | 10203 | 03:46:40 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:46:40 INFO - PROCESS | 10203 | RSS: Main: 179138560 03:46:40 INFO - PROCESS | 10203 | 03:46:42 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:46:43 INFO - PROCESS | 10203 | RSS: Main: 188538880 03:46:43 INFO - PROCESS | 10203 | 03:46:45 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:46:46 INFO - PROCESS | 10203 | RSS: Main: 196907008 03:46:46 INFO - PROCESS | 10203 | 03:46:49 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:46:49 INFO - PROCESS | 10203 | RSS: Main: 193327104 03:46:49 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | Cycle 1(1): loaded http://localhost:52614/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52614/tests/kraken/driver.html?testName=ai-astar) 03:46:51 INFO - PROCESS | 10203 | RSS: Main: 243773440 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | __start_tp_report 03:46:51 INFO - PROCESS | 10203 | _x_x_mozilla_page_load 03:46:51 INFO - PROCESS | 10203 | _x_x_mozilla_page_load_details 03:46:51 INFO - PROCESS | 10203 | |i|pagename|runs| 03:46:51 INFO - PROCESS | 10203 | |0;ai-astar;111;112;112;111;111;112;111;111;111;111 03:46:51 INFO - PROCESS | 10203 | |1;audio-beat-detection;144;116;117;155;121;118;119;119;147;116 03:46:51 INFO - PROCESS | 10203 | |2;audio-dft;158;159;160;159;159;162;162;160;161;160 03:46:51 INFO - PROCESS | 10203 | |3;audio-fft;75;75;75;117;76;77;77;76;107;75 03:46:51 INFO - PROCESS | 10203 | |4;audio-oscillator;83;82;116;82;83;82;111;82;82;111 03:46:51 INFO - PROCESS | 10203 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;117;117;117 03:46:51 INFO - PROCESS | 10203 | |6;imaging-darkroom;145;145;145;146;146;145;146;146;145;145 03:46:51 INFO - PROCESS | 10203 | |7;imaging-desaturate;94;95;94;95;95;95;95;95;96;95 03:46:51 INFO - PROCESS | 10203 | |8;json-parse-financial;48;50;50;50;50;50;49;51;50;49 03:46:51 INFO - PROCESS | 10203 | |9;json-stringify-tinderbox;58;60;60;60;61;59;59;59;60;59 03:46:51 INFO - PROCESS | 10203 | |10;stanford-crypto-aes;74;74;74;83;73;82;73;73;77;75 03:46:51 INFO - PROCESS | 10203 | |11;stanford-crypto-ccm;121;123;125;123;121;130;122;120;158;120 03:46:51 INFO - PROCESS | 10203 | |12;stanford-crypto-pbkdf2;156;154;153;152;157;156;153;154;157;152 03:46:51 INFO - PROCESS | 10203 | |13;stanford-crypto-sha256-iterative;62;63;68;63;61;62;62;62;62;61 03:46:51 INFO - PROCESS | 10203 | __end_tp_report 03:46:51 INFO - PROCESS | 10203 | __start_cc_report 03:46:51 INFO - PROCESS | 10203 | _x_x_mozilla_cycle_collect,1597 03:46:51 INFO - PROCESS | 10203 | __end_cc_report 03:46:51 INFO - PROCESS | 10203 | __startTimestamp1476355611592__endTimestamp 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | ------- Summary: start ------- 03:46:51 INFO - PROCESS | 10203 | Number of tests: 14 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 03:46:51 INFO - PROCESS | 10203 | Values: 111.0 112.0 112.0 111.0 111.0 112.0 111.0 111.0 111.0 111.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:120.00 stddev:15.13 (12.6%) stddev-sans-first:14.77 03:46:51 INFO - PROCESS | 10203 | Values: 144.0 116.0 117.0 155.0 121.0 118.0 119.0 119.0 147.0 116.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:1.33 (0.8%) stddev-sans-first:1.20 03:46:51 INFO - PROCESS | 10203 | Values: 158.0 159.0 160.0 159.0 159.0 162.0 162.0 160.0 161.0 160.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#3] audio-fft Cycles:10 Average:83.00 Median:75.50 stddev:15.48 (20.5%) stddev-sans-first:16.15 03:46:51 INFO - PROCESS | 10203 | Values: 75.0 75.0 75.0 117.0 76.0 77.0 77.0 76.0 107.0 75.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:82.00 stddev:14.74 (18.0%) stddev-sans-first:15.32 03:46:51 INFO - PROCESS | 10203 | Values: 83.0 82.0 116.0 82.0 83.0 82.0 111.0 82.0 82.0 111.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:46:51 INFO - PROCESS | 10203 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:46:51 INFO - PROCESS | 10203 | Values: 145.0 145.0 145.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 03:46:51 INFO - PROCESS | 10203 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.82 (1.6%) stddev-sans-first:0.60 03:46:51 INFO - PROCESS | 10203 | Values: 48.0 50.0 50.0 50.0 50.0 50.0 49.0 51.0 50.0 49.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.71 03:46:51 INFO - PROCESS | 10203 | Values: 58.0 60.0 60.0 60.0 61.0 59.0 59.0 59.0 60.0 59.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:74.50 stddev:3.74 (5.0%) stddev-sans-first:3.91 03:46:51 INFO - PROCESS | 10203 | Values: 74.0 74.0 74.0 83.0 73.0 82.0 73.0 73.0 77.0 75.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:123.00 stddev:11.53 (9.4%) stddev-sans-first:12.07 03:46:51 INFO - PROCESS | 10203 | Values: 121.0 123.0 125.0 123.0 121.0 130.0 122.0 120.0 158.0 120.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:155.00 stddev:1.96 (1.3%) stddev-sans-first:1.99 03:46:51 INFO - PROCESS | 10203 | Values: 156.0 154.0 153.0 152.0 157.0 156.0 153.0 154.0 157.0 152.0 03:46:51 INFO - PROCESS | 10203 | 03:46:51 INFO - PROCESS | 10203 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.00 stddev:2.01 (3.2%) stddev-sans-first:2.12 03:46:51 INFO - PROCESS | 10203 | Values: 62.0 63.0 68.0 63.0 61.0 62.0 62.0 62.0 62.0 61.0 03:46:51 INFO - PROCESS | 10203 | -------- Summary: end -------- 03:46:51 INFO - PROCESS | 10203 | 03:46:52 INFO - TEST-INFO | 10203: exit 0 03:46:52 INFO - TEST-OK | kraken | took 60249ms 03:46:52 INFO - SUITE-END | took 1175s 03:46:52 INFO - Completed test suite (00:19:35) 03:46:52 INFO - javascript benchmark 03:46:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3068.6777888321726, 3199.280161963558, 3208.4064448077124, 3222.7099561039504, 3257.706806387425, 5595.972020139899, 5620.943790562093, 5671.801486947952, 5690.800821971232, 5703.144528320754, 5604.355499117602, 5687.090065589497, 5703.40114288, 5720.914186287209, 5750.079987202049, 5145.3311069560905, 5146.356705411824, 5169.974150129263, 5184.740762961856, 5249.501297376743, 5074.340335756365, 5074.974625126888, 5266.894662106755, 5268.420473747885, 5291.179867120583, 5037.395512538501, 5084.79660813567, 5089.592832573385, 5105.285260063594, 5157.664751791141, 5088.287639730441, 5097.108006098918, 5157.53582177602, 5157.974210128926, 5187.48125187482, 4934.481879402683, 5055.74721263939, 5070.366204224472, 5104.821331253425, 5122.28288039671, 5405.351357837085, 5426.403095659474, 5430.56555475561, 5448.564114870801, 5503.587230957694, 5848.561357898175, 5856.502197313233, 5966.761329546813, 5968.432998865101, 5970.6716130612795, 5937.495312898409, 5989.3112292086535, 6004.399560044004, 6004.489618382443, 6010.8497287567725, 5551.333839939233, 5585.664860108406, 5633.746481408305, 5639.943600563942, 5650.971745141248, 6164.722587483574, 6190.628562286187, 6197.690115494297, 6201.472874805602, 6203.100550420164, 5711.628744131618, 5963.5825492216, 5964.671943043088, 5981.5214782817275, 5984.820455386345, 5824.88350232993, 5962.165296858443, 6027.005544085177, 6029.698515074228, 6142.78500252489, 6067.362926892701, 6077.787277445268, 6081.969590152021, 6083.239595050619, 6100.938990610037, 6054.273487181478, 6102.298235702951, 6110.572259941761, 6119.663418512025, 6131.754729810847, 4448.310511870665, 4475.149721552894, 4576.4737055239075, 4601.608863246594, 4611.469680986663, 4100.487439070116, 4107.815148318333, 4135.462389889295, 4147.834086636561, 4166.250074986471, 5746.626469279567, 5747.39652336507, 5748.942510574924, 5757.107648314518, 5763.423657634287, 5878.735456904449, 5919.289685237713, 5926.970365148147, 5958.1062840573395, 6129.3870612939245, 5684.09054551278, 5685.914711279334, 5690.146478028246, 5701.087825947911, 5722.513586345207, 5057.671251368649, 5063.06333328328, 5064.569511591482, 5065.772040258197, 5096.210087436453, 6089.330173680892, 6112.083187521729, 6124.472479235491, 6151.630902145886, 6202.472789812826, 5967.433093856077, 6033.487153591994, 6036.879262414815, 6069.514438844793, 6073.48375388097, 6148.78479253224, 6158.199434073542, 6162.969185154046, 6165.5375846810775, 6236.189295391751, 5733.971330143323, 5930.673812940329, 5951.553633477593, 5959.165716799738, 5961.940380596312, 5951.166836642787, 5972.104184372379, 5998.670073146019, 6025.8192254232445, 6134.815955521163, 5873.823785286449, 5945.851353716192, 5956.344802071855, 5969.641821490724, 5973.940260597338, 5882.323532793784, 5938.792142275, 5947.1079338099635, 5949.702514874326, 5949.940500594938, 5715.542756579547, 5786.768529258951, 5803.303603567599, 5845.795397161079, 5892.970535147297, 5887.7056147193325, 5906.350301466921, 5907.616004959664, 5922.763089476372, 5928.644281343133, 5595.5523558116065, 5702.0, 5702.230198923092, 5739.0, 5765.048766953573], "value": 5537.211680686996, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.1666187232757, 592.0527156549521, 603.2519675602251, 612.2928018139046, 617.1421723803912, 35327.823360883194, 44850.0, 45692.771536142354, 45716.771416142954, 45759.77120114395, 48332.0, 48741.26888096681, 48788.26817597739, 48842.0, 48850.0, 9291.72124836256, 9452.905470945305, 9458.432494050345, 9463.574139163755, 9464.337496375256, 8441.746747597583, 8637.093105223956, 10921.726956826064, 11133.0, 11148.0, 11294.717632059223, 11388.601398950997, 11394.088472922187, 11408.087353011739, 11409.942950285236, 11198.496067676933, 11274.267172633752, 11278.943605281962, 11283.0, 11288.266262692981, 10915.890841091647, 11050.668479945614, 11066.833997490045, 11072.169587280987, 11074.833877491845, 45443.0, 45704.542954570694, 45778.31332530015, 45794.0, 45806.31290530645, 3562.9109272268142, 3685.981570092132, 3713.294474049949, 3751.7936513491745, 3927.136030073379, 34190.48714269263, 34202.31595368079, 34245.486317705254, 34362.14094647629, 34595.30809383823, 4603.491097351951, 4619.307103934403, 4679.555442232982, 4717.976410117927, 4727.22000869856, 340179.0, 342263.0, 342537.0, 343177.0, 344088.0, 62574.37425625777, 62675.0, 63075.36924630787, 63084.05373919395, 63264.68367658132, 40256.396154057715, 41405.37891931624, 41824.58175418268, 44181.77909110434, 44194.558054419045, 19763.308284209983, 20136.093875775627, 20162.29431969874, 20162.495937601678, 20166.596668066555, 13569.52506662262, 13788.862111378958, 15371.308291126927, 15435.0, 15465.0, 19818.50453738668, 20812.58374832495, 20832.27087051946, 21612.4596885076, 21633.783662163492, 7369.22623124575, 7532.43506736986, 7671.578063206577, 7697.461177717506, 7704.807379815549, 1043.5408420295046, 1153.6481373181093, 1160.6634076118, 1164.7204670878928, 1176.2942234659135, 34205.0, 34889.82555087208, 34985.82507087499, 35001.64998349984, 35006.47490287648, 1186.5372504723166, 1189.149757923089, 1193.8746431624727, 1203.602811072344, 1253.6865783554113, 18052.277908883498, 18150.18324175389, 18399.72400413995, 18511.0, 18579.628407431777, 548773.0, 556100.0, 556794.0, 557141.0, 559866.2006689941, 62876.0, 63438.682806585675, 63448.36551634517, 63505.0, 63550.0, 1316.4470922212693, 1372.0052961602757, 1436.7198396312829, 1454.8326942401548, 1460.1968917095555, 33062.33875322528, 35748.821255894596, 38189.42715859209, 38214.0, 38410.42384364181, 59775.402245976984, 60436.697816512395, 60499.69750151221, 60507.0, 60511.0, 479156.0, 479157.0, 480067.0, 481327.0, 484188.0, 27378.452430951667, 28045.0, 29342.41315173642, 30761.0, 31480.2129946744, 52444.0, 52777.736111319195, 53309.466905332, 53386.0, 53837.0, 43528.56471435245, 43666.0, 43908.0, 43992.56007439885, 45868.77065614651, 43512.564874352116, 44796.32805507855, 44833.10333793371, 46185.30722039105, 46540.30189547227, 44137.779311104525, 44503.10993780171, 45028.09943801171, 45514.0, 45750.77124614356, 37206.0, 38856.80571597124, 39059.60940390673, 39408.2118357637, 40040.3993940097, 28984.710152898202, 29042.709572904, 29629.5555566671, 29720.0, 29761.404771904006], "value": 21779.342368777638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.16330359459533, 411.709744630036, 421.4310680581215, 425.77008415455646, 444.5088177563792, 19261.229550817967, 20588.279410220628, 20865.37403877886, 21012.264570740026, 21058.473538161554, 19238.13428395722, 19911.800881991177, 19914.203431862745, 20339.694904576445, 20575.794242057575, 5900.144479050525, 6176.938230617681, 6179.443850053494, 6185.072239164139, 6191.566590338678, 5697.572682048863, 5787.623804452697, 5937.0, 5963.612365196248, 6081.331053584102, 5984.072468767348, 6034.396560343975, 6042.184305118797, 6042.758289668408, 6044.818655440344, 5872.031114866042, 5912.438318359749, 5979.431953964367, 5980.55145864062, 5985.221921150222, 5672.376038635747, 5817.360090390053, 5819.418058194189, 5821.883562328772, 5826.592138550303, 13595.728085438335, 13615.31923403826, 13928.721425571533, 13944.860551394459, 13945.16329020252, 2522.1046528482493, 2977.136630377188, 3072.7080927311868, 3091.633466135458, 3144.7326977206967, 10451.425171615558, 10619.362838229654, 10668.0, 10855.40295283759, 10886.29239099456, 1969.1138987455702, 2003.91984320627, 2004.7494063242095, 2039.6838490034067, 2050.7231523744254, 89343.55328223383, 89826.40694372218, 90638.54680726553, 91748.08251917463, 92462.0, 11592.246503977214, 11626.941865290788, 11684.532618695326, 11765.529378824922, 11927.463264153133, 17196.31214751396, 17199.828001719823, 17211.827881721274, 17214.569635759206, 17215.56961075983, 8191.262786349257, 8203.30271926893, 8247.092819789817, 8301.543415112106, 8560.871586926076, 7595.392368610498, 7614.428917831185, 7790.143084260727, 7811.336036436966, 7866.960665196638, 4965.4041515018425, 5023.924641130386, 5084.313617661568, 5103.362079740033, 5278.392984806719, 1949.4931317857458, 1968.4586738646701, 1991.571812060385, 2040.7653119891402, 2058.2487392101775, 949.9145076943108, 950.458238803889, 985.6303886042734, 988.2538683294067, 1048.6696690542456, 20697.172113115306, 20764.480887977923, 20765.79234207669, 20868.89565552193, 21032.158713651283, 1031.489412740698, 1041.8645576075062, 1042.338115296792, 1062.134360496193, 1062.4953147255146, 10606.098481628993, 11668.70828229284, 11681.532738690526, 11725.765484690259, 11729.4135293235, 475048.0, 475497.0, 489966.0, 494758.0, 500254.0, 96513.0348696518, 98263.0, 100027.49986250167, 100387.0, 101639.49180254052, 986.413084214891, 1031.7162780235344, 1040.0535512683573, 1053.7418332508585, 1062.3148069495132, 17727.556811079827, 18037.909810450863, 18449.446516604523, 18534.25862965466, 18746.156422961274, 41793.79103104465, 44351.2259509616, 44772.55227447814, 44772.77613611911, 46178.76910615426, 57304.0, 58618.0, 59655.70172149112, 61390.69304653627, 62148.37851621426, 8597.18326758957, 8989.640414383352, 9014.864777028219, 9148.130927561871, 9159.908400916172, 13193.538226162422, 13989.440422382992, 14030.789538156732, 14105.576832695035, 14351.354189061725, 11563.826542602035, 11730.76538469243, 11978.76042479163, 12698.809517857055, 13203.0, 11706.590269340531, 12277.447514861675, 12543.623691289276, 12629.93685031569, 12693.0, 12342.938285308875, 12452.626421207378, 12737.108402411743, 12799.29603871796, 13116.606501804961, 8699.13008699125, 8723.0, 9119.863202052107, 9177.908220917705, 9542.284328675238, 12155.513779449077, 12488.687782805502, 12558.874411256136, 12604.747905042032, 12812.0], "value": 9594.97376209249, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.7206558688265, 1488.8213414390275, 1532.501936870517, 1662.6757782232462, 1797.4631689905132, 8147.6333564989645, 8477.15228477152, 8874.467531948072, 8918.509481978488, 8919.554022298891, 8541.487510749359, 8746.169113934182, 8746.431481953685, 8983.056779038188, 8990.460572365646, 2468.345888339587, 2522.987385063072, 2524.9747502525015, 2526.570483017882, 2530.8354956927833, 2438.51229754049, 2502.1117503286346, 2513.158092039169, 2514.974850251492, 2515.182565666166, 2483.7640424159677, 2514.409113858251, 2549.1332946798084, 2549.2734570647367, 2550.349660836484, 2409.144753612469, 2409.385606670288, 2415.251272105644, 2459.6433517140094, 2471.6416119662726, 2415.855048697066, 2445.4619983603757, 2449.64480150379, 2452.0559584560074, 2454.1901172612993, 8680.479171249683, 9008.549572521348, 9094.86357704635, 9161.404508706913, 9162.908370916271, 5503.807366742145, 5624.578156638268, 5639.718014099279, 5668.68822214778, 5672.0, 3351.1287065362935, 3367.579052618423, 3418.8803391881165, 3422.401079811048, 3432.1248081739245, 1161.3206274329527, 1187.5724739094005, 1188.5958774016876, 1192.3799624195456, 1206.5234232478122, 12484.625461236177, 12632.936835315857, 12709.110362274738, 12714.74570508594, 12717.30054846983, 4700.106979673919, 4723.881902952454, 4859.1010663027455, 4875.561199491992, 5022.397312322545, 10843.295185812896, 10871.130309575216, 10903.890961090447, 10908.836367454494, 10917.39954302521, 5589.0219211637805, 5602.0, 5606.243157173811, 5613.045782217033, 5622.437756224426, 4534.478534968521, 4659.394278743742, 4985.875353116201, 4995.0, 5081.771320290596, 4036.495438070241, 4102.50769907613, 4166.0, 4193.287141186006, 4194.119234960632, 1356.925369104689, 1374.9175049497062, 1386.4869998100767, 1399.923004234777, 1426.5577670922048, 889.4663202078701, 892.7768057985504, 901.4771432569095, 914.8216097860986, 939.4879790514186, 5860.70696465175, 5900.291964964232, 5905.055191169478, 5910.615809972338, 6194.101855231056, 1001.6544292219173, 1015.192921627308, 1063.4948399510172, 1070.7537266428765, 1091.159806948661, 3705.6294370562728, 3771.9434208486896, 3786.734928554975, 3827.4641550182996, 3838.731288809812, 11118.22172447937, 11481.36852473122, 11518.94240528792, 11524.711882203012, 11545.595904143483, 11365.602203922823, 11393.373364465035, 11406.486708098155, 11409.42952852354, 11420.42897855104, 8060.274575288252, 8398.454100483572, 8430.494170349799, 8623.0, 8632.611532481053, 5808.825735227781, 5932.406759324033, 5951.018082016419, 5966.075258334965, 6083.543734219863, 10171.135453486537, 10308.896911031094, 10334.018268264503, 10453.006964338376, 10465.9476702616, 10356.637517686844, 10462.895371046496, 10634.255602107778, 10733.19501037441, 10757.46212689378, 7280.490365674352, 7458.142313633784, 7707.845843083219, 7713.344365728976, 7860.174681658344, 8286.212809783061, 8339.666413343399, 8561.657533698582, 8562.143785621638, 8574.0, 7738.264864837832, 7892.960535197287, 7933.404994625542, 8125.106238313662, 8303.00363956329, 7868.370530357673, 7876.72431464896, 7889.921100788919, 7906.802329941567, 7967.163447837894, 6553.5084868635995, 6648.601083934979, 6653.467722582085, 6672.833179170365, 6706.329367063255, 5036.924446133237, 5081.745912704424, 5104.285400043923, 5141.460146684544, 5260.316158899319, 5032.547070763502, 5045.772940217773, 5073.568746656576, 5117.078925793393, 5261.973690131525], "value": 4768.303525355481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.53522392530823, 324.4241471388286, 332.82194026195975, 333.0358611818785, 335.2942056970076, 5303.893922121555, 5486.725663716818, 5537.197106419567, 5634.63374880633, 5649.689267090308, 5268.341457317835, 5374.7043912584795, 5394.730263486829, 5409.404965453797, 5458.890822183554, 4278.871633850989, 4279.2083464559, 4286.249906266391, 4288.914221715564, 4304.31131019037, 4163.833446662145, 4274.615284624383, 4276.401303817468, 4294.806733696975, 4310.935335969947, 4209.726367786099, 4219.831206751726, 4222.134462435209, 4222.134462435209, 4226.387173859788, 4214.36784482327, 4217.093324935123, 4218.936715949263, 4230.123516009113, 4234.089670720779, 3651.835667394974, 3744.962550374516, 3745.3445647011936, 3746.8572085513906, 3752.4746535485056, 5277.812492189227, 5280.788768449257, 5292.824305416758, 5300.284461597712, 5314.388845282765, 4860.708357498526, 4868.367112275417, 4870.14772414826, 4870.245112007645, 4871.196252618349, 5554.305711786027, 5707.600467967244, 5707.971460142714, 5709.857253568694, 5712.685802280873, 4089.1821635672686, 4089.8159582818844, 4118.835246590133, 4119.691023173274, 4121.1345617420375, 5869.44459718175, 5898.498627616657, 5921.891007221243, 5947.10793380992, 5960.523158147338, 4087.8773636790943, 4185.246655602021, 4187.183499217624, 4196.622303992655, 4234.513031001413, 4236.661067114624, 4242.151569686075, 4252.4471818664, 4253.659707223414, 4294.291441912112, 4783.736894470838, 4801.855944321676, 4808.422989241313, 4820.204666230033, 4829.710217386968, 5513.070425351175, 5720.628159169641, 5736.168255602997, 5753.532849123447, 5758.683272420057, 1437.7843323501559, 1458.3218803256325, 1459.1391079263285, 1459.1682740837832, 1467.2810322941682, 890.670451932776, 915.4598786715869, 924.3945215883607, 930.3859452761145, 931.4923366765129, 4876.439209490883, 4898.510148985144, 4935.975320123376, 4969.0, 4982.427020892644, 5177.747679759317, 5193.948060519422, 5207.635465517453, 5259.316288882421, 5266.868328291824, 4799.544043315949, 4990.3762029746285, 4991.975040124848, 4995.951659819457, 5020.34735484392, 2777.750002499785, 2788.7211278872355, 2795.916122516328, 2799.9440011199663, 2802.691703912609, 5272.472752724697, 5321.988822123784, 5340.759665815048, 5380.704061276589, 5390.946090539044, 5280.683159010395, 5316.867078323073, 5601.80393686219, 6020.76574302267, 6088.173547935601, 6102.206713127265, 6131.110988906584, 6137.606333529927, 6151.323354431009, 6157.53818463626, 5613.859653508695, 5934.495567876779, 5964.807038592386, 5968.910466343095, 5976.282846058501, 5848.853778655568, 5854.443827836349, 5862.856742935087, 5880.26496687914, 5905.852353691193, 6113.0, 6163.657810032159, 6175.475084617858, 6186.164867742978, 6199.380061993765, 3893.929169478416, 3906.8632597858937, 4135.958640413557, 4145.97927010363, 4165.291900376883, 5856.682246494504, 5896.764129434775, 5914.822555323347, 5938.881222375442, 5943.465088142088, 5674.801381951612, 5808.589435384268, 5813.6221145625395, 5817.650940943557, 5830.970845145747, 5767.625104368202, 5789.710514474175, 5802.4197580241635, 5807.709614519173, 5810.94189058104, 5708.942910570841, 5720.456556627114, 5729.1119876419225, 5734.856628584152, 5754.53963682896, 5559.972200138974, 5746.706990927008, 5779.942200577941, 5787.247657804459, 5791.652500849794, 5642.71786410686, 5654.85862853432, 5655.971720141373, 5664.394378585732, 5669.291338582677], "value": 4289.100929518343, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.89155572442, 760.8440269744702, 769.3537428560012, 787.5707739282088, 847.9533625650587, 14231.217283049427, 14279.357428915699, 14280.785788213185, 15075.095494270337, 15836.445724399648, 15091.169985650813, 15128.924355378207, 15222.314995825187, 15245.39018439261, 15288.0, 707.3245050976305, 729.4565548666251, 843.1989609870617, 850.8298340331927, 873.4671850171391, 865.1478293880523, 876.2157868707503, 876.5047748022379, 876.5135349880813, 876.9035406105355, 919.4115765909823, 919.6275508419101, 919.659725901414, 919.8114386550775, 920.3787443475627, 744.5607091815828, 763.1338430880935, 869.0657543141149, 918.8943271523793, 919.462114662923, 662.7978466567695, 664.9634270115142, 665.5108495255982, 666.1340257665016, 666.6133642487346, 15017.0, 15039.248037598076, 15045.322960466805, 15048.699026019527, 15061.397544098223, 14609.853901460956, 14734.484293049692, 14735.484258051023, 14878.181700006604, 15078.321475533518, 13237.271950042743, 13602.863971360259, 13616.523421680293, 13654.249016304098, 14006.369713362923, 4520.3219517072375, 4531.660125490568, 4605.861824145281, 4638.628909687251, 4694.24892017279, 88179.55910220408, 91008.99999999933, 91693.54153229258, 93087.53456232675, 93167.53416232875, 11490.770184596262, 11515.884841151481, 11606.651800446, 11650.184487085822, 12207.755844883053, 21096.78903210979, 21802.0, 21914.561708765734, 21915.232966846084, 21926.45183870384, 9598.856017159747, 9620.326577139673, 9676.56455459506, 9759.951200243953, 9925.702228933143, 5215.713135777492, 5357.987443928338, 5428.0, 5432.592555558349, 5435.239066530688, 3537.7169826413824, 3672.375696131664, 3672.485851980725, 3672.889813305605, 3673.1368128489785, 634.2990994950605, 645.3320812958565, 648.8896887529131, 651.840299126717, 657.5495785387026, 637.7070316374077, 750.6847124207845, 756.9848603027908, 762.4091329219788, 796.5181065455432, 10137.64518241858, 10278.17774578032, 10410.947945260225, 10462.843057354146, 10464.739999502184, 734.7832389445126, 740.7703611880227, 749.1833901047884, 758.7192738686721, 764.8776195808643, 4530.728156310566, 4844.32179494871, 4869.099216644932, 4877.804887804448, 4990.575801056951, 103413.48293258486, 117070.41464792621, 121485.0, 121714.0, 121874.0, 104569.47715261375, 106177.46911265394, 106353.46823265834, 107381.0, 109669.45165274122, 89665.0, 90360.5481972586, 91563.54218228866, 92468.53765731129, 92723.0, 9493.0, 9628.71113866585, 9690.46702431373, 9734.610615575297, 9790.0, 40062.0, 53409.0, 54252.10117645864, 54381.18428223501, 56930.430695692514, 78733.60633196797, 82094.0, 83764.0, 84323.15676843152, 84699.5765021171, 12705.42825572833, 12711.36443177819, 12803.48786048585, 13016.088873778745, 13025.869741302844, 14095.506657266946, 14435.855641443452, 14473.203973781543, 14473.348699308346, 14625.707485850437, 13118.344082795631, 13283.530056277934, 13716.725665486834, 14113.576592702235, 14276.357563909856, 12905.483780648668, 13190.406431710788, 13335.799963000756, 13450.86549134496, 14223.928880355532, 11630.418479076181, 12618.0, 12731.299778512272, 12758.808617870925, 13697.109687870257, 10593.947030264799, 10857.808511165764, 10941.726456838644, 11044.337339759319, 11065.889341106807, 11039.282446640942, 11134.832977505506, 11364.602238921598, 11416.714582135513, 11417.0], "value": 7867.887379906136, "unit": "score"}], "name": "dromaeo_css", "value": 7556.761529965118, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 112.0, 111.0, 111.0, 112.0, 111.0, 111.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 117.0, 155.0, 121.0, 118.0, 119.0, 119.0, 147.0, 116.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 160.0, 159.0, 159.0, 162.0, 162.0, 160.0, 161.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 117.0, 76.0, 77.0, 77.0, 76.0, 107.0, 75.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 116.0, 82.0, 83.0, 82.0, 111.0, 82.0, 82.0, 111.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 50.0, 50.0, 50.0, 50.0, 50.0, 49.0, 51.0, 50.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 60.0, 60.0, 61.0, 59.0, 59.0, 59.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 83.0, 73.0, 82.0, 73.0, 73.0, 77.0, 75.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 125.0, 123.0, 121.0, 130.0, 122.0, 120.0, 158.0, 120.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 153.0, 152.0, 157.0, 156.0, 153.0, 154.0, 157.0, 152.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 68.0, 63.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1458.2999999999997, "alertThreshold": 2.0}]} 03:46:52 INFO - Return code: 0 03:46:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:46:52 INFO - # TBPL SUCCESS # 03:46:52 INFO - Running post-action listener: _resource_record_post_action 03:46:52 INFO - [mozharness: 2016-10-13 10:46:52.927157Z] Finished run-tests step (success) 03:46:52 INFO - Running post-run listener: _resource_record_post_run 03:46:53 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 367857664; Read time: 92; Write time: 312364 03:46:53 INFO - TinderboxPrint: CPU usage
13.0% 03:46:53 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 92 03:46:53 INFO - TinderboxPrint: I/O write bytes / time
367,857,664 / 312,364 03:46:53 INFO - TinderboxPrint: CPU idle
8,279.3 (87.0%) 03:46:53 INFO - TinderboxPrint: CPU user
1,187.8 (12.5%) 03:46:53 INFO - TinderboxPrint: Swap in / out
0 / 0 03:46:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118763520; Read time: 0; Write time: 95732 03:46:53 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248541184; Read time: 16; Write time: 216596 03:46:53 INFO - Running post-run listener: _upload_blobber_files 03:46:53 INFO - Blob upload gear active. 03:46:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:46:53 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 03:46:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:46:53 INFO - (blobuploader) - INFO - Open directory for files ... 03:46:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:46:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:46:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:54 INFO - (blobuploader) - INFO - Done attempting. 03:46:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:46:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:57 INFO - (blobuploader) - INFO - Done attempting. 03:46:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:46:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:46:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:58 INFO - (blobuploader) - INFO - Done attempting. 03:46:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:46:58 INFO - Return code: 0 03:46:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:46:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:46:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72878adf4157b5faf338db8d7c63f8a8c0d04d382858598240db82858c03217aab9652c623d21cd72c207085731b838df607cfd8150d713c050fae5fdcd125a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c35a908f399a76f6a350006a88857c54ada0dd99ebc6fd9bd7b63381b58b307fed0a2636a1c7297554f8e988a266871cb3b6cc3696c243ee98275c9eaa614739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/056c69bd565cb4d6c2f1857bbb6b8ba933af4f6f96b8d2bec55755bbabb2abb14e8d42e660700832e9bb126a10c5e9c3bae39ec6a9e450b30ce44173d3e02e65"} 03:46:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:46:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:46:58 INFO - Contents: 03:46:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72878adf4157b5faf338db8d7c63f8a8c0d04d382858598240db82858c03217aab9652c623d21cd72c207085731b838df607cfd8150d713c050fae5fdcd125a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c35a908f399a76f6a350006a88857c54ada0dd99ebc6fd9bd7b63381b58b307fed0a2636a1c7297554f8e988a266871cb3b6cc3696c243ee98275c9eaa614739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/056c69bd565cb4d6c2f1857bbb6b8ba933af4f6f96b8d2bec55755bbabb2abb14e8d42e660700832e9bb126a10c5e9c3bae39ec6a9e450b30ce44173d3e02e65"} 03:46:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:46:58 INFO - Copying logs to upload dir... 03:46:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:46:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.985979 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-13 03:46:57.860722) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 03:46:57.864213) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72878adf4157b5faf338db8d7c63f8a8c0d04d382858598240db82858c03217aab9652c623d21cd72c207085731b838df607cfd8150d713c050fae5fdcd125a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c35a908f399a76f6a350006a88857c54ada0dd99ebc6fd9bd7b63381b58b307fed0a2636a1c7297554f8e988a266871cb3b6cc3696c243ee98275c9eaa614739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/056c69bd565cb4d6c2f1857bbb6b8ba933af4f6f96b8d2bec55755bbabb2abb14e8d42e660700832e9bb126a10c5e9c3bae39ec6a9e450b30ce44173d3e02e65"} build_url:https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/ZV6BTFsVSAejCkMf6HREmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72878adf4157b5faf338db8d7c63f8a8c0d04d382858598240db82858c03217aab9652c623d21cd72c207085731b838df607cfd8150d713c050fae5fdcd125a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c35a908f399a76f6a350006a88857c54ada0dd99ebc6fd9bd7b63381b58b307fed0a2636a1c7297554f8e988a266871cb3b6cc3696c243ee98275c9eaa614739", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/056c69bd565cb4d6c2f1857bbb6b8ba933af4f6f96b8d2bec55755bbabb2abb14e8d42e660700832e9bb126a10c5e9c3bae39ec6a9e450b30ce44173d3e02e65"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 03:46:57.890496) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:46:57.890932) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1476354381.50505-1418937491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004771 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:46:57.976916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 03:46:57.977290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 03:46:57.977606) ========= ========= Total master_lag: 0.15 =========