builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1474731011.93 results: success (0) buildid: 20160924055025 builduid: a3b6dfc082eb471a9f7e5aa1dc3c1ddc revision: d9c70cda4277f12c6a74be1388da4f88baf591c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:11.932519) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:11.932984) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:11.933290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:11.990877) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:11.991177) ========= 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=3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-24 08:30:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-09-24 08:30:12 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.228826 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:12.244967) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:12.245306) ========= 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=3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.051453 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:12.306871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-24 08:30:12.307219) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d9c70cda4277f12c6a74be1388da4f88baf591c3 --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 d9c70cda4277f12c6a74be1388da4f88baf591c3 --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=3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-24 08:30:12,450 truncating revision to first 12 chars 2016-09-24 08:30:12,450 Setting DEBUG logging. 2016-09-24 08:30:12,450 attempt 1/10 2016-09-24 08:30:12,450 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d9c70cda4277?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-24 08:30:13,252 unpacking tar archive at: mozilla-aurora-d9c70cda4277/testing/mozharness/ program finished with exit code 0 elapsedTime=1.124894 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-24 08:30:13.445441) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:13.445753) ========= script_repo_revision: d9c70cda4277f12c6a74be1388da4f88baf591c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:13.446131) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:13.446405) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-24 08:30:13.456693) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-24 08:30:13.456967) ========= /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=3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:30:13 INFO - MultiFileLogger online at 20160924 08:30:13 in /builds/slave/test 08:30:13 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 08:30:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:13 INFO - {'append_to_log': False, 08:30:13 INFO - 'base_work_dir': '/builds/slave/test', 08:30:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:30:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:13 INFO - 'branch': 'Mozilla-Aurora', 08:30:13 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:13 INFO - 'config_files': ('talos/linux_config.py',), 08:30:13 INFO - 'default_actions': ('clobber', 08:30:13 INFO - 'read-buildbot-config', 08:30:13 INFO - 'download-and-extract', 08:30:13 INFO - 'populate-webroot', 08:30:13 INFO - 'create-virtualenv', 08:30:13 INFO - 'install', 08:30:13 INFO - 'run-tests'), 08:30:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:13 INFO - 'download_minidump_stackwalk': True, 08:30:13 INFO - 'download_symbols': 'ondemand', 08:30:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:13 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:13 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:13 INFO - 'installer_path': 'installer.exe', 08:30:13 INFO - 'log_level': 'info', 08:30:13 INFO - 'log_name': 'talos', 08:30:13 INFO - 'log_to_console': True, 08:30:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:30:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:30:13 INFO - 'opt_config_files': (), 08:30:13 INFO - 'pip_index': False, 08:30:13 INFO - 'sps_profile': False, 08:30:13 INFO - 'sps_profile_interval': 0, 08:30:13 INFO - 'suite': 'dromaeojs', 08:30:13 INFO - 'system_bits': '32', 08:30:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:13 INFO - 'title': 'talos-linux64-ix-059', 08:30:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:13 INFO - 'use_talos_json': True, 08:30:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:13 INFO - 'work_dir': 'build'} 08:30:13 INFO - [mozharness: 2016-09-24 15:30:13.842083Z] Running clobber step. 08:30:13 INFO - Running pre-action listener: _resource_record_pre_action 08:30:13 INFO - Running main action method: clobber 08:30:13 INFO - rmtree: /builds/slave/test/build 08:30:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:15 INFO - Running post-action listener: _resource_record_post_action 08:30:15 INFO - [mozharness: 2016-09-24 15:30:15.707063Z] Finished clobber step (success) 08:30:15 INFO - [mozharness: 2016-09-24 15:30:15.707164Z] Running read-buildbot-config step. 08:30:15 INFO - Running pre-action listener: _resource_record_pre_action 08:30:15 INFO - Running main action method: read_buildbot_config 08:30:15 INFO - Using buildbot properties: 08:30:15 INFO - { 08:30:15 INFO - "project": "", 08:30:15 INFO - "product": "firefox", 08:30:15 INFO - "script_repo_revision": "production", 08:30:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:30:15 INFO - "repository": "", 08:30:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:30:15 INFO - "buildid": "20160924055025", 08:30:15 INFO - "pgo_build": "True", 08:30:15 INFO - "basedir": "/builds/slave/test", 08:30:15 INFO - "buildnumber": 788, 08:30:15 INFO - "slavename": "talos-linux64-ix-059", 08:30:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:30:15 INFO - "platform": "ubuntu64_hw", 08:30:15 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:30:15 INFO - "branch": "mozilla-aurora", 08:30:15 INFO - "repo_path": "releases/mozilla-aurora", 08:30:15 INFO - "revision": "d9c70cda4277f12c6a74be1388da4f88baf591c3", 08:30:15 INFO - "stage_platform": "linux64-pgo", 08:30:15 INFO - "builduid": "a3b6dfc082eb471a9f7e5aa1dc3c1ddc", 08:30:15 INFO - "slavebuilddir": "test" 08:30:15 INFO - } 08:30:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 08:30:15 INFO - Running post-action listener: _resource_record_post_action 08:30:15 INFO - [mozharness: 2016-09-24 15:30:15.709655Z] Finished read-buildbot-config step (success) 08:30:15 INFO - [mozharness: 2016-09-24 15:30:15.709745Z] Running download-and-extract step. 08:30:15 INFO - Running pre-action listener: _resource_record_pre_action 08:30:15 INFO - Running main action method: download_and_extract 08:30:15 INFO - mkdir: /builds/slave/test/build/tests 08:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:15 INFO - trying https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 08:30:15 INFO - Downloading https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/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 08:30:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/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 08:30:16 INFO - Downloaded 1361 bytes. 08:30:16 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 08:30:16 INFO - Using the following test package requirements: 08:30:16 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 08:30:16 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:30:16 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:30:16 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'jsshell-linux-x86_64.zip'], 08:30:16 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:30:16 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 08:30:16 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:30:16 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:30:16 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:30:16 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:30:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:30:16 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:30:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 08:30:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:30:16 INFO - Fetch https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:30:16 INFO - Expected file size: 21898121 08:30:21 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 08:30:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 08:30:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:30:21 INFO - Fetch https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:30:21 INFO - Expected file size: 21898121 08:30:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 08:30:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:30:26 INFO - Fetch https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 08:30:27 INFO - Expected file size: 14414059 08:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:27 INFO - trying https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:30:27 INFO - Downloading https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/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 08:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/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 08:30:29 INFO - Downloaded 63976272 bytes. 08:30:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:30:29 INFO - mkdir: /builds/slave/test/properties 08:30:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:29 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:29 INFO - Contents: 08:30:29 INFO - build_url:https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:30:29 INFO - Running post-action listener: _resource_record_post_action 08:30:29 INFO - Running post-action listener: set_extra_try_arguments 08:30:29 INFO - [mozharness: 2016-09-24 15:30:29.756965Z] Finished download-and-extract step (success) 08:30:29 INFO - [mozharness: 2016-09-24 15:30:29.757197Z] Running populate-webroot step. 08:30:29 INFO - Running pre-action listener: _resource_record_pre_action 08:30:29 INFO - Running main action method: populate_webroot 08:30:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:29 INFO - '--tppagecycles', 08:30:29 INFO - '7'], 08:30:29 INFO - 'tests': ['tsvgm']}}, 08:30:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['damp', 'tps']}, 08:30:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'tests': ['damp', 'tps']}, 08:30:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['dromaeo_dom']}, 08:30:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['basic_compositor_video']}, 08:30:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['a11yr', 08:30:29 INFO - 'ts_paint', 08:30:29 INFO - 'tpaint', 08:30:29 INFO - 'sessionrestore', 08:30:29 INFO - 'sessionrestore_no_auto_restore', 08:30:29 INFO - 'tabpaint']}, 08:30:29 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:29 INFO - 'ts_paint', 08:30:29 INFO - 'tpaint', 08:30:29 INFO - 'sessionrestore', 08:30:29 INFO - 'sessionrestore_no_auto_restore', 08:30:29 INFO - 'tabpaint']}, 08:30:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:29 INFO - 'ts_paint', 08:30:29 INFO - 'tpaint', 08:30:29 INFO - 'sessionrestore', 08:30:29 INFO - 'sessionrestore_no_auto_restore', 08:30:29 INFO - 'tabpaint']}, 08:30:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:29 INFO - 'ts_paint', 08:30:29 INFO - 'tpaint', 08:30:29 INFO - 'sessionrestore', 08:30:29 INFO - 'sessionrestore_no_auto_restore', 08:30:29 INFO - 'tabpaint']}, 08:30:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['a11yr', 08:30:29 INFO - 'ts_paint', 08:30:29 INFO - 'tpaint', 08:30:29 INFO - 'sessionrestore', 08:30:29 INFO - 'sessionrestore_no_auto_restore', 08:30:29 INFO - 'tabpaint']}, 08:30:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['a11yr', 08:30:29 INFO - 'ts_paint', 08:30:29 INFO - 'tpaint', 08:30:29 INFO - 'sessionrestore', 08:30:29 INFO - 'sessionrestore_no_auto_restore', 08:30:29 INFO - 'tabpaint']}, 08:30:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['tsvgx', 08:30:29 INFO - 'tsvgr_opacity', 08:30:29 INFO - 'tart', 08:30:29 INFO - 'tscrollx', 08:30:29 INFO - 'cart']}, 08:30:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:29 INFO - 'tsvgr_opacity', 08:30:29 INFO - 'tart', 08:30:29 INFO - 'tscrollx', 08:30:29 INFO - 'cart']}, 08:30:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'talos_options': ['--disable-e10s'], 08:30:29 INFO - 'tests': ['tp5o']}, 08:30:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'tests': ['tp5o']}, 08:30:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'talos_options': ['--disable-e10s', 08:30:29 INFO - '--xperf_path', 08:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:29 INFO - 'tests': ['tp5n']}, 08:30:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:29 INFO - 'talos_options': ['--xperf_path', 08:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:29 INFO - 'tests': ['tp5n']}}, 08:30:29 INFO - 'talos.zip': {'path': '', 08:30:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:29 INFO - Running post-action listener: _resource_record_post_action 08:30:29 INFO - [mozharness: 2016-09-24 15:30:29.777972Z] Finished populate-webroot step (success) 08:30:29 INFO - [mozharness: 2016-09-24 15:30:29.778061Z] Running create-virtualenv step. 08:30:29 INFO - Running pre-action listener: _resource_record_pre_action 08:30:29 INFO - Running main action method: create_virtualenv 08:30:29 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:29 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 08:30:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:29 INFO - Using real prefix '/usr' 08:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:30 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:32 INFO - Installing pip.................done. 08:30:32 INFO - Return code: 0 08:30:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:30:32 INFO - Using env: {'DISPLAY': ':0', 08:30:32 INFO - 'HOME': '/home/cltbld', 08:30:32 INFO - 'LANG': 'en_US.UTF-8', 08:30:32 INFO - 'LANGUAGE': 'en_US:en', 08:30:32 INFO - 'LOGNAME': 'cltbld', 08:30:32 INFO - 'MAIL': '/var/mail/cltbld', 08:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:32 INFO - 'MOZ_NO_REMOTE': '1', 08:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:32 INFO - 'NO_EM_RESTART': '1', 08:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:32 INFO - 'PWD': '/builds/slave/test', 08:30:32 INFO - 'SHELL': '/bin/bash', 08:30:32 INFO - 'SHLVL': '1', 08:30:32 INFO - 'TERM': 'linux', 08:30:32 INFO - 'TMOUT': '86400', 08:30:32 INFO - 'USER': 'cltbld', 08:30:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:32 INFO - '_': '/tools/buildbot/bin/python'} 08:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:32 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 08:30:32 INFO - Cleaning up... 08:30:32 INFO - Return code: 0 08:30:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:30:32 INFO - Using env: {'DISPLAY': ':0', 08:30:32 INFO - 'HOME': '/home/cltbld', 08:30:32 INFO - 'LANG': 'en_US.UTF-8', 08:30:32 INFO - 'LANGUAGE': 'en_US:en', 08:30:32 INFO - 'LOGNAME': 'cltbld', 08:30:32 INFO - 'MAIL': '/var/mail/cltbld', 08:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:32 INFO - 'MOZ_NO_REMOTE': '1', 08:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:32 INFO - 'NO_EM_RESTART': '1', 08:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:32 INFO - 'PWD': '/builds/slave/test', 08:30:32 INFO - 'SHELL': '/bin/bash', 08:30:32 INFO - 'SHLVL': '1', 08:30:32 INFO - 'TERM': 'linux', 08:30:32 INFO - 'TMOUT': '86400', 08:30:32 INFO - 'USER': 'cltbld', 08:30:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:32 INFO - '_': '/tools/buildbot/bin/python'} 08:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:33 INFO - Downloading/unpacking psutil>=3.1.1 08:30:33 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 08:30:33 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 08:30:33 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 08:30:33 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 08:30:33 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 08:30:33 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 08:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:35 INFO - Installing collected packages: psutil 08:30:35 INFO - Running setup.py install for psutil 08:30:35 INFO - building 'psutil._psutil_linux' extension 08:30:35 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 08:30:36 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 08:30:36 INFO - building 'psutil._psutil_posix' extension 08:30:36 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 08:30:36 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 08:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:36 INFO - Successfully installed psutil 08:30:36 INFO - Cleaning up... 08:30:36 INFO - Return code: 0 08:30:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:36 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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:36 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 08:30:36 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 08:30:36 INFO - Using env: {'DISPLAY': ':0', 08:30:36 INFO - 'HOME': '/home/cltbld', 08:30:36 INFO - 'LANG': 'en_US.UTF-8', 08:30:36 INFO - 'LANGUAGE': 'en_US:en', 08:30:36 INFO - 'LOGNAME': 'cltbld', 08:30:36 INFO - 'MAIL': '/var/mail/cltbld', 08:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:36 INFO - 'MOZ_NO_REMOTE': '1', 08:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:36 INFO - 'NO_EM_RESTART': '1', 08:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:36 INFO - 'PWD': '/builds/slave/test', 08:30:36 INFO - 'SHELL': '/bin/bash', 08:30:36 INFO - 'SHLVL': '1', 08:30:36 INFO - 'TERM': 'linux', 08:30:36 INFO - 'TMOUT': '86400', 08:30:36 INFO - 'USER': 'cltbld', 08:30:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:36 INFO - '_': '/tools/buildbot/bin/python'} 08:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:36 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 08:30:36 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 08:30:36 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 08:30:36 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 08:30:36 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 08:30:36 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 08:30:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:39 INFO - Installing collected packages: mozsystemmonitor 08:30:39 INFO - Running setup.py install for mozsystemmonitor 08:30:39 INFO - Successfully installed mozsystemmonitor 08:30:39 INFO - Cleaning up... 08:30:39 INFO - Return code: 0 08:30:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:39 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')]} 08:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:39 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')]} 08:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:39 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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:39 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 08:30:39 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 08:30:39 INFO - Using env: {'DISPLAY': ':0', 08:30:39 INFO - 'HOME': '/home/cltbld', 08:30:39 INFO - 'LANG': 'en_US.UTF-8', 08:30:39 INFO - 'LANGUAGE': 'en_US:en', 08:30:39 INFO - 'LOGNAME': 'cltbld', 08:30:39 INFO - 'MAIL': '/var/mail/cltbld', 08:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:39 INFO - 'MOZ_NO_REMOTE': '1', 08:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:39 INFO - 'NO_EM_RESTART': '1', 08:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:39 INFO - 'PWD': '/builds/slave/test', 08:30:39 INFO - 'SHELL': '/bin/bash', 08:30:39 INFO - 'SHLVL': '1', 08:30:39 INFO - 'TERM': 'linux', 08:30:39 INFO - 'TMOUT': '86400', 08:30:39 INFO - 'USER': 'cltbld', 08:30:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:39 INFO - '_': '/tools/buildbot/bin/python'} 08:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:42 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:42 INFO - warning: no files found matching '*.txt' 08:30:42 INFO - no previously-included directories found matching 'build' 08:30:42 INFO - no previously-included directories found matching 'dist' 08:30:42 INFO - no previously-included directories found matching '.git*' 08:30:42 INFO - Installing collected packages: jsonschema, functools32 08:30:42 INFO - Running setup.py install for functools32 08:30:42 INFO - warning: no files found matching '*.txt' 08:30:42 INFO - no previously-included directories found matching 'build' 08:30:42 INFO - no previously-included directories found matching 'dist' 08:30:42 INFO - no previously-included directories found matching '.git*' 08:30:42 INFO - Successfully installed jsonschema functools32 08:30:42 INFO - Cleaning up... 08:30:42 INFO - Return code: 0 08:30:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:30:42 INFO - Using env: {'DISPLAY': ':0', 08:30:42 INFO - 'HOME': '/home/cltbld', 08:30:42 INFO - 'LANG': 'en_US.UTF-8', 08:30:42 INFO - 'LANGUAGE': 'en_US:en', 08:30:42 INFO - 'LOGNAME': 'cltbld', 08:30:42 INFO - 'MAIL': '/var/mail/cltbld', 08:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:42 INFO - 'MOZ_NO_REMOTE': '1', 08:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:42 INFO - 'NO_EM_RESTART': '1', 08:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:42 INFO - 'PWD': '/builds/slave/test', 08:30:42 INFO - 'SHELL': '/bin/bash', 08:30:42 INFO - 'SHLVL': '1', 08:30:42 INFO - 'TERM': 'linux', 08:30:42 INFO - 'TMOUT': '86400', 08:30:42 INFO - 'USER': 'cltbld', 08:30:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:42 INFO - '_': '/tools/buildbot/bin/python'} 08:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:42 INFO - Cleaning up... 08:30:42 INFO - Return code: 0 08:30:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:30:42 INFO - Using env: {'DISPLAY': ':0', 08:30:42 INFO - 'HOME': '/home/cltbld', 08:30:42 INFO - 'LANG': 'en_US.UTF-8', 08:30:42 INFO - 'LANGUAGE': 'en_US:en', 08:30:42 INFO - 'LOGNAME': 'cltbld', 08:30:42 INFO - 'MAIL': '/var/mail/cltbld', 08:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:42 INFO - 'MOZ_NO_REMOTE': '1', 08:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:42 INFO - 'NO_EM_RESTART': '1', 08:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:42 INFO - 'PWD': '/builds/slave/test', 08:30:42 INFO - 'SHELL': '/bin/bash', 08:30:42 INFO - 'SHLVL': '1', 08:30:42 INFO - 'TERM': 'linux', 08:30:42 INFO - 'TMOUT': '86400', 08:30:42 INFO - 'USER': 'cltbld', 08:30:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:42 INFO - '_': '/tools/buildbot/bin/python'} 08:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:42 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:42 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 08:30:42 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 08:30:42 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 08:30:42 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 08:30:42 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 08:30:42 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 08:30:44 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:30:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 INFO - Downloading docopt-0.6.1.tar.gz 08:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:45 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:45 INFO - Running setup.py install for blobuploader 08:30:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:30:45 INFO - Running setup.py install for requests 08:30:45 INFO - Running setup.py install for docopt 08:30:46 INFO - Successfully installed blobuploader requests docopt 08:30:46 INFO - Cleaning up... 08:30:46 INFO - Return code: 0 08:30:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:46 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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:46 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 08:30:46 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 08:30:46 INFO - Using env: {'DISPLAY': ':0', 08:30:46 INFO - 'HOME': '/home/cltbld', 08:30:46 INFO - 'LANG': 'en_US.UTF-8', 08:30:46 INFO - 'LANGUAGE': 'en_US:en', 08:30:46 INFO - 'LOGNAME': 'cltbld', 08:30:46 INFO - 'MAIL': '/var/mail/cltbld', 08:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:46 INFO - 'MOZ_NO_REMOTE': '1', 08:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:46 INFO - 'NO_EM_RESTART': '1', 08:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:46 INFO - 'PWD': '/builds/slave/test', 08:30:46 INFO - 'SHELL': '/bin/bash', 08:30:46 INFO - 'SHLVL': '1', 08:30:46 INFO - 'TERM': 'linux', 08:30:46 INFO - 'TMOUT': '86400', 08:30:46 INFO - 'USER': 'cltbld', 08:30:46 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:46 INFO - '_': '/tools/buildbot/bin/python'} 08:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:46 INFO - Running setup.py (path:/tmp/pip-Bkz7ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:46 INFO - Running setup.py (path:/tmp/pip-XSM6cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:46 INFO - Running setup.py (path:/tmp/pip-FpTXP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:46 INFO - Running setup.py (path:/tmp/pip-60S_pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:46 INFO - Running setup.py (path:/tmp/pip-v67d5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:46 INFO - Running setup.py (path:/tmp/pip-jzLE5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:46 INFO - Running setup.py (path:/tmp/pip-Z4pglV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:46 INFO - Running setup.py (path:/tmp/pip-28jc_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:46 INFO - Running setup.py (path:/tmp/pip-Ioklk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:46 INFO - Running setup.py (path:/tmp/pip-8v46jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:47 INFO - Running setup.py (path:/tmp/pip-XLmiWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:47 INFO - Running setup.py (path:/tmp/pip-zeuurV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:47 INFO - Running setup.py (path:/tmp/pip-NRZ58L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:47 INFO - Running setup.py (path:/tmp/pip-J3wewf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:47 INFO - Running setup.py (path:/tmp/pip-f2Mu4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:47 INFO - Running setup.py (path:/tmp/pip-jWt3u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:47 INFO - Running setup.py (path:/tmp/pip-GeRMXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:30:47 INFO - Running setup.py install for manifestparser 08:30:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:30:47 INFO - Running setup.py install for mozcrash 08:30:47 INFO - Running setup.py install for mozdebug 08:30:47 INFO - Running setup.py install for mozdevice 08:30:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:30:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:30:47 INFO - Running setup.py install for mozfile 08:30:48 INFO - Running setup.py install for mozhttpd 08:30:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Running setup.py install for mozinfo 08:30:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Running setup.py install for mozInstall 08:30:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Running setup.py install for mozleak 08:30:48 INFO - Running setup.py install for mozlog 08:30:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Running setup.py install for moznetwork 08:30:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Running setup.py install for mozprocess 08:30:48 INFO - Running setup.py install for mozprofile 08:30:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:30:48 INFO - Running setup.py install for mozrunner 08:30:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:30:49 INFO - Running setup.py install for mozscreenshot 08:30:49 INFO - Running setup.py install for moztest 08:30:49 INFO - Running setup.py install for mozversion 08:30:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:30:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:30:49 INFO - Cleaning up... 08:30:49 INFO - Return code: 0 08:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:49 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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:49 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 08:30:49 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 08:30:49 INFO - Using env: {'DISPLAY': ':0', 08:30:49 INFO - 'HOME': '/home/cltbld', 08:30:49 INFO - 'LANG': 'en_US.UTF-8', 08:30:49 INFO - 'LANGUAGE': 'en_US:en', 08:30:49 INFO - 'LOGNAME': 'cltbld', 08:30:49 INFO - 'MAIL': '/var/mail/cltbld', 08:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:49 INFO - 'MOZ_NO_REMOTE': '1', 08:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:49 INFO - 'NO_EM_RESTART': '1', 08:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:49 INFO - 'PWD': '/builds/slave/test', 08:30:49 INFO - 'SHELL': '/bin/bash', 08:30:49 INFO - 'SHLVL': '1', 08:30:49 INFO - 'TERM': 'linux', 08:30:49 INFO - 'TMOUT': '86400', 08:30:49 INFO - 'USER': 'cltbld', 08:30:49 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:49 INFO - '_': '/tools/buildbot/bin/python'} 08:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:49 INFO - Running setup.py (path:/tmp/pip-IObBqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:49 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)) 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:49 INFO - Running setup.py (path:/tmp/pip-D5OC9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:49 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)) 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:49 INFO - Running setup.py (path:/tmp/pip-wulCd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:49 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)) 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:49 INFO - Running setup.py (path:/tmp/pip-ZfeR9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:49 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)) 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:49 INFO - Running setup.py (path:/tmp/pip-WKex7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:49 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)) 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:49 INFO - Running setup.py (path:/tmp/pip-nnZfJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:49 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)) 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:49 INFO - Running setup.py (path:/tmp/pip-MxTOpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:50 INFO - Running setup.py (path:/tmp/pip-uhPWYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:50 INFO - Running setup.py (path:/tmp/pip-zSBUHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:50 INFO - Running setup.py (path:/tmp/pip-KV3HhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:50 INFO - Running setup.py (path:/tmp/pip-fFnJ7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:50 INFO - Running setup.py (path:/tmp/pip-jY4uZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:50 INFO - Running setup.py (path:/tmp/pip-wfMNSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:50 INFO - Running setup.py (path:/tmp/pip-m8WA7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:50 INFO - Running setup.py (path:/tmp/pip-WXPxxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:50 INFO - Running setup.py (path:/tmp/pip-3kacsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:50 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)) 08:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:50 INFO - Running setup.py (path:/tmp/pip-F0NivN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:50 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)) 08:30:50 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)) 08:30:50 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)) 08:30:50 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)) 08:30:50 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)) 08:30:50 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)) 08:30:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:30:50 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 08:30:50 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 08:30:50 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 08:30:50 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 08:30:50 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 08:30:50 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 08:30:52 INFO - Downloading blessings-1.6.tar.gz 08:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:30:52 INFO - Installing collected packages: blessings 08:30:52 INFO - Running setup.py install for blessings 08:30:52 INFO - Successfully installed blessings 08:30:52 INFO - Cleaning up... 08:30:52 INFO - Return code: 0 08:30:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:53 INFO - Reading from file tmpfile_stdout 08:30:53 INFO - Current package versions: 08:30:53 INFO - argparse == 1.2.1 08:30:53 INFO - blessings == 1.6 08:30:53 INFO - blobuploader == 1.2.4 08:30:53 INFO - docopt == 0.6.1 08:30:53 INFO - functools32 == 3.2.3-2 08:30:53 INFO - jsonschema == 2.5.1 08:30:53 INFO - manifestparser == 1.1 08:30:53 INFO - mozInstall == 1.12 08:30:53 INFO - mozcrash == 0.17 08:30:53 INFO - mozdebug == 0.1 08:30:53 INFO - mozdevice == 0.48 08:30:53 INFO - mozfile == 1.2 08:30:53 INFO - mozhttpd == 0.7 08:30:53 INFO - mozinfo == 0.9 08:30:53 INFO - mozleak == 0.1 08:30:53 INFO - mozlog == 3.4 08:30:53 INFO - moznetwork == 0.27 08:30:53 INFO - mozprocess == 0.23 08:30:53 INFO - mozprofile == 0.28 08:30:53 INFO - mozrunner == 6.12 08:30:53 INFO - mozscreenshot == 0.1 08:30:53 INFO - mozsystemmonitor == 0.3 08:30:53 INFO - moztest == 0.7 08:30:53 INFO - mozversion == 1.4 08:30:53 INFO - psutil == 3.1.1 08:30:53 INFO - requests == 1.2.3 08:30:53 INFO - wsgiref == 0.1.2 08:30:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:53 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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:53 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 08:30:53 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 08:30:53 INFO - Using env: {'DISPLAY': ':0', 08:30:53 INFO - 'HOME': '/home/cltbld', 08:30:53 INFO - 'LANG': 'en_US.UTF-8', 08:30:53 INFO - 'LANGUAGE': 'en_US:en', 08:30:53 INFO - 'LOGNAME': 'cltbld', 08:30:53 INFO - 'MAIL': '/var/mail/cltbld', 08:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:53 INFO - 'MOZ_NO_REMOTE': '1', 08:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:53 INFO - 'NO_EM_RESTART': '1', 08:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:53 INFO - 'PWD': '/builds/slave/test', 08:30:53 INFO - 'SHELL': '/bin/bash', 08:30:53 INFO - 'SHLVL': '1', 08:30:53 INFO - 'TERM': 'linux', 08:30:53 INFO - 'TMOUT': '86400', 08:30:53 INFO - 'USER': 'cltbld', 08:30:53 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:53 INFO - '_': '/tools/buildbot/bin/python'} 08:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 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)) 08:30:53 INFO - Cleaning up... 08:30:53 INFO - Return code: 0 08:30:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:53 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 0x7ff262400f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', '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 08:30:53 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 08:30:53 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 08:30:53 INFO - Using env: {'DISPLAY': ':0', 08:30:53 INFO - 'HOME': '/home/cltbld', 08:30:53 INFO - 'LANG': 'en_US.UTF-8', 08:30:53 INFO - 'LANGUAGE': 'en_US:en', 08:30:53 INFO - 'LOGNAME': 'cltbld', 08:30:53 INFO - 'MAIL': '/var/mail/cltbld', 08:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:53 INFO - 'MOZ_NO_REMOTE': '1', 08:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:53 INFO - 'NO_EM_RESTART': '1', 08:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:53 INFO - 'PWD': '/builds/slave/test', 08:30:53 INFO - 'SHELL': '/bin/bash', 08:30:53 INFO - 'SHLVL': '1', 08:30:53 INFO - 'TERM': 'linux', 08:30:53 INFO - 'TMOUT': '86400', 08:30:53 INFO - 'USER': 'cltbld', 08:30:53 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:53 INFO - '_': '/tools/buildbot/bin/python'} 08:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:30:53 INFO - Cleaning up... 08:30:53 INFO - Return code: 0 08:30:53 INFO - Running post-action listener: _resource_record_post_action 08:30:53 INFO - Running post-action listener: _start_resource_monitoring 08:30:53 INFO - Starting resource monitoring. 08:30:53 INFO - [mozharness: 2016-09-24 15:30:53.678354Z] Finished create-virtualenv step (success) 08:30:53 INFO - [mozharness: 2016-09-24 15:30:53.679321Z] Running install step. 08:30:53 INFO - Running pre-action listener: _resource_record_pre_action 08:30:53 INFO - Running main action method: install 08:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:53 INFO - Reading from file tmpfile_stdout 08:30:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:30:53 INFO - Reading from file tmpfile_stdout 08:30:53 INFO - Output received: 08:30:53 INFO - Usage: mozinstall [options] installer 08:30:53 INFO - Options: 08:30:53 INFO - -h, --help show this help message and exit 08:30:53 INFO - -d DEST, --destination=DEST 08:30:53 INFO - Directory to install application into. [default: 08:30:53 INFO - "/builds/slave/test"] 08:30:53 INFO - --app=APP Application being installed. [default: firefox] 08:30:53 INFO - mkdir: /builds/slave/test/build/application 08:30:53 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'] 08:30:53 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 08:31:09 INFO - Reading from file tmpfile_stdout 08:31:09 INFO - Output received: 08:31:09 INFO - /builds/slave/test/build/application/firefox/firefox 08:31:09 INFO - Running post-action listener: _resource_record_post_action 08:31:09 INFO - [mozharness: 2016-09-24 15:31:09.933874Z] Finished install step (success) 08:31:09 INFO - [mozharness: 2016-09-24 15:31:09.934123Z] Running run-tests step. 08:31:09 INFO - Running pre-action listener: _resource_record_pre_action 08:31:09 INFO - Running main action method: run_tests 08:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:09 INFO - Python 2.7.3 08:31:09 INFO - Return code: 0 08:31:09 INFO - grabbing minidump binary from tooltool 08:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:09 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 0x107ef60>, '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 0x107fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:09 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 08:31:09 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 08:31:09 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 08:31:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:10 INFO - Return code: 0 08:31:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:31:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:31:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:10 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/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 08:31:10 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/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 08:31:10 INFO - Using env: {'DISPLAY': ':0', 08:31:10 INFO - 'HOME': '/home/cltbld', 08:31:10 INFO - 'LANG': 'en_US.UTF-8', 08:31:10 INFO - 'LANGUAGE': 'en_US:en', 08:31:10 INFO - 'LOGNAME': 'cltbld', 08:31:10 INFO - 'MAIL': '/var/mail/cltbld', 08:31:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:10 INFO - 'MOZ_NO_REMOTE': '1', 08:31:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:10 INFO - 'NO_EM_RESTART': '1', 08:31:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:10 INFO - 'PWD': '/builds/slave/test', 08:31:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:10 INFO - 'SHELL': '/bin/bash', 08:31:10 INFO - 'SHLVL': '1', 08:31:10 INFO - 'TERM': 'linux', 08:31:10 INFO - 'TMOUT': '86400', 08:31:10 INFO - 'USER': 'cltbld', 08:31:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373', 08:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:10 INFO - '_': '/tools/buildbot/bin/python'} 08:31:10 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/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 08:31:10 INFO - mozversion application_buildid: 20160924055025 08:31:10 INFO - mozversion application_changeset: d9c70cda4277f12c6a74be1388da4f88baf591c3 08:31:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:31:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:10 INFO - mozversion application_name: Firefox 08:31:10 INFO - mozversion application_remotingname: firefox-dev 08:31:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:31:10 INFO - mozversion application_vendor: Mozilla 08:31:10 INFO - mozversion application_version: 51.0a2 08:31:10 INFO - mozversion platform_buildid: 20160924055025 08:31:10 INFO - mozversion platform_changeset: d9c70cda4277f12c6a74be1388da4f88baf591c3 08:31:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:31:10 INFO - mozversion platform_version: 51.0a2 08:31:10 INFO - using testdate: 1474731070 08:31:10 INFO - actual date: 1474731070 08:31:10 INFO - starting webserver on 'localhost:33777' 08:31:10 INFO - SUITE-START | Running 2 tests 08:31:10 INFO - TEST-START | dromaeo_css 08:31:10 INFO - operating with platform_type : linux_ 08:31:10 INFO - Initialising browser for dromaeo_css test... 08:31:10 INFO - TEST-INFO | started process 3313 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7BZ97U/profile http://localhost:33777/getInfo.html) 08:31:16 INFO - PROCESS | 3313 | __metrics Screen width/height:1600/1200 08:31:16 INFO - PROCESS | 3313 | colorDepth:24 08:31:16 INFO - PROCESS | 3313 | Browser inner width/height: 1024/697 08:31:16 INFO - PROCESS | 3313 | __metrics 08:31:16 INFO - PROCESS | 3313 | 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" 08:31:16 INFO - PROCESS | 3313 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:17 INFO - PROCESS | 3313 | console.error: PushService: 08:31:17 INFO - PROCESS | 3313 | stateChangeProcessEnqueue: Error transitioning state 08:31:17 INFO - PROCESS | 3313 | UnknownError 08:31:17 INFO - TEST-INFO | 3313: exit 0 08:31:17 INFO - Browser initialized. 08:31:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:17 INFO - TEST-INFO | started process 3403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7BZ97U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:29 INFO - PROCESS | 3403 | RSS: Main: 145354752 08:31:29 INFO - PROCESS | 3403 | 08:32:20 INFO - PROCESS | 3403 | 1474731140825 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] 08:34:30 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33777/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33777/tests/dromaeo/cssquery-ext.html) 08:34:31 INFO - PROCESS | 3403 | RSS: Main: 246513664 08:34:31 INFO - PROCESS | 3403 | 08:37:33 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33777/tests/dromaeo/cssquery-ext.html (next: http://localhost:33777/tests/dromaeo/cssquery-jquery.html) 08:37:33 INFO - PROCESS | 3403 | RSS: Main: 230297600 08:37:33 INFO - PROCESS | 3403 | 08:40:35 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33777/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33777/tests/dromaeo/cssquery-mootools.html) 08:40:36 INFO - PROCESS | 3403 | RSS: Main: 226619392 08:40:36 INFO - PROCESS | 3403 | 08:43:38 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33777/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33777/tests/dromaeo/cssquery-prototype.html) 08:43:38 INFO - PROCESS | 3403 | RSS: Main: 225435648 08:43:38 INFO - PROCESS | 3403 | 08:46:41 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33777/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33777/tests/dromaeo/cssquery-yui.html) 08:46:42 INFO - PROCESS | 3403 | RSS: Main: 177627136 08:46:42 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33777/tests/dromaeo/cssquery-yui.html (next: http://localhost:33777/tests/dromaeo/cssquery-dojo.html) 08:49:44 INFO - PROCESS | 3403 | RSS: Main: 268382208 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | __start_tp_report 08:49:44 INFO - PROCESS | 3403 | _x_x_mozilla_page_load 08:49:44 INFO - PROCESS | 3403 | _x_x_mozilla_page_load_details 08:49:44 INFO - PROCESS | 3403 | |i|pagename|runs| 08:49:44 INFO - PROCESS | 3403 | |0;dojo.html;3110.300182458947;3146.8583913723864;3148.4490214212506;3168.9207769805766;3185.251465905513;5600.103983362663;5668.886622267552;5689.715514224293;5706.029974904266;5711.628744131628;5710.514606258473;5771.220885180499;5776.018076926922;5776.797812076578;5807.883842323162;5173.042987047389;5177.870553236162;5198.116320225571;5253.185756207794;5284.709340986245;5044.218146187347;5072.74636268187;5289.391719952197;5299.205119232127;5327.387350454689;5101.566366858821;5114.43741188469;5130;5155.587552995751;5157.6131790115705;5125.974370128144;5173.6119791015635;5241.973790131063;5243.711595862226;5272.868178295535;4960.826371076995;5071.442141364447;5135.97280543889;5176.456472070416;5199.168133098686;5485.232067510552;5502.697351645658;5515.7242137892945;5521.751521181556;5562.193481945095;5908.0842469416875;5909.674967876765;6015.248093988252;6016.097585362187;6021.548383871227;5824.330202026778;6030.698465076729;6053.152558641794;6058.545609079337;6059.36376680451;5449.945500545023;5477.75350109242;5613.8596535086135;5615.943840561583;5628.831135065954;6168.9691551541955;6192.473639740582;6195.256569211633;6201.317855035943;6203.565750397519;5664.631798933056;5939;5952.285725712942;5954.55340849438;5957.017092179828;5737.311522617312;5861.120831875252;5901.321348044944;5901.675407852524;6017.157597936292;5962.046072628359;5969.910451343233;5971.28344598651;5977.880442391128;5979.342272350038;5932.3177834548715;5994.880102397928;5995.220621319288;5997.490213331828;6015.849603759853;4555.088982203506;4593.311003349458;4618.422697162855;4646;4652.488226295105;4127.050778320943;4156.106437116035;4162.0427301720165;4183.748975061506;4185.727927684752;5771.163181338767;5787.8553036174435;5788.218590490314;5788.450097240755;5794.101914203306;5896.439838215449;5946.137810017523;5992.310884248367;6031.577789554777;6122.142899994004;5782.739776710058;5785.681787501644;5791.594588378857;5815.331236907809;5823.970880145572;5281.603879709037;5291.3385826771655;5293.444188360167;5296.655717378358;5297.576193904555;6072.149899014142;6125.908111378244;6134.5590473767;6289.937100629027;6331.746730130835;5986.9401305986385;6114.358196334042;6116.518381401779;6117.0662765122115;6123.099607467949;6278.811635650938;6285.465735412541;6292.874142517216;6298.9921612541775;6304.401081897213;5759.193712880116;5789.594728368974;5988.461038506555;5989.460948514655;5991.820245392645;6047.03247480401;6103.664298463627;6130.459285710702;6132.845047373448;6133.202683651097;5939.287285525765;5997.400259973968;6010.639361638141;6013.609115407485;6016.42843929826;5637.126245431965;5997.070454079625;6032.3967603239325;6040.6677632730625;6041.214642096499;5690.829275121753;5809.2738407699035;5816.912746308724;5858.882822343615;5945.881082378241;5924.940750592439;5977.9103313451205;5978.8206353809455;5985.790497332572;5987.251593550806;5668.234788303691;5722.713864306851;5799.681017543907;5837.328707198726;5849.883002340014 08:49:44 INFO - PROCESS | 3403 | |1;ext.html;589.3281658908845;614.6957256158202;643.6009674002117;658.7068754404291;663.3929954092006;35119;35640.82179589102;38120.42819357712;38154.618453815456;38223.42664860026;46844.76577617116;47978.99999999991;48052.759736201355;51494.48505514944;51574.74212628932;9142.36003479755;9274.860877086849;9277.536123193848;9286.58210380531;9300.674476393327;8656.047834738174;8948.239399651005;9018.233450156744;9030.367874248805;9032.09679032098;8708.17272359128;11058.391788451632;11291.322520648788;11336.376499292535;11653.708657283594;11191.440427978609;11291.661250162508;11304.208705390625;11314.434278286053;11334.263272887236;11047.889521104766;11221.102311815035;11222.102231821435;11226.32642041472;11229.270097443721;45394.31908521375;45771;46021.53978460206;46042;46154;3950.4271880577157;3963.850483359822;3998.260321840469;4067.5525692173837;4073.429719839225;34803.30393392118;34983.82508087469;34992.65007349919;35005.649943500495;35249;4684.227102528079;4694.389729335165;4752.738599377033;4763.404574428197;4859.854204373874;375680;376171;378001;378102;378794.10602947086;63721;64295;64302.35697642963;64314.35685643084;64327.35672643307;39885.40171897424;41056.38415423771;41161;43445.34831977523;66246;16096.839031609768;16189.352425903067;16221.35114595403;16230.269637866088;16244.51266462008;12743.681407964876;12840.743185136245;12860.93569532165;12866.9356653218;12883.227006379648;18690.15894284733;19051.61896762057;20196.394108176777;20844.47888802792;21127.8943605281;7041.718331266692;7187.460940429489;7243.710251589878;7376.225496322809;7399.556026638419;957.0620791624248;962.1581116523042;979.9412035277907;1042.3485321673954;1101.3336931156696;34112.1004714602;34316.31367372689;34357.312853742784;34556.48165277523;34674.826625867216;1090.7872964771952;1135.7955567997674;1164.2781475485085;1194.6714653470192;1226.7730469863104;16984.40554580584;17815.554611134565;17904.462866114038;17958.19188136537;17991.550211244823;559655;563239;563410;564870;566070;64796.35203647998;65550.67224663847;65566.67216663982;65597.67201164059;65682.34317656858;1302.8892544133855;1371.6090914089636;1407.7325308191412;1409.7814838700017;1429.7140571885664;32196.678033219367;33710.15724606806;33887;34006.82996585001;34366.656333436345;55859.44140558705;56434;56530.999999998356;56655.71672141613;56735;457680;459157;460774;461879;463234;26482.86758566195;31882.20294492656;31940.5208921871;32099.679003210604;32614.184645384055;52587.84888342313;52665.47334526606;52806;53018.34908254649;53365;43301.783491082344;44277.11445771131;44360;46235.76882115568;46425.30362044639;44274.55725442704;44517;45113.548864510936;46788.53211467842;46834;44198.55801441945;44278.33582496201;44401.77799110984;44403;44939;37164.81417592895;39091.804540977115;39263.2147357057;39474.210515790095;39795.60204398035;29814;29845;29920;29980.65955864121;30139.849300754235 08:49:44 INFO - PROCESS | 3403 | |2;jquery.html;414.3184461560731;414.86931616540767;424.9617534421901;426.3583307122781;444.6353989728422;20187.29344472944;20325.983700814955;20989.685154722672;21299.14803407862;21481.57036859262;17326.960382377067;17465.3013879445;17929.282828686868;18040.097995100226;18233.270669173216;5220.425753167148;5470.781168753245;5475.5072043516075;5479.479449452296;5480.04099282626;4016.256992456405;4194.266003449399;5430.348358197022;5457.045017122007;5770.711464426783;5947.286325640929;6083;6091.878162436748;6094.421030002165;6099.451049405553;5868.911966320508;6012.699365031753;6047.848803779897;6055.122007308915;6094.8171554853425;5745.368009518949;5889.234399528077;5889.793857214977;5899.233099697055;5900.262467191601;14577.344019519041;14699.19154446505;22636.981335839926;23757.88121059401;24214.757852421426;2394.185976767907;2576.0211119774553;2578.510083084208;2737.7536021757937;2747.983246198899;9988.500574971295;10160.89839101607;10265.897341026568;10440.321379110334;10723.731906702316;1927.7204805303293;1942.6114777044647;2032.7459067616549;2058.7220725202055;2064.215598072738;84256.15743842545;85837.57081214554;87452.5627371859;87901;88254.55872720659;11836.822447663291;11879.643610691694;11880.76238475243;12091.093168012434;12241.69395765113;17038.233279502452;17119.57201069983;17135.48593542196;17162.485125446256;17184.31262749476;8563.78857928277;8568.143185681382;8640.13598640131;8689.478631282143;8754.08082151365;7546.698132074765;7674.309312161932;7729.652165652559;7936.722214722457;8139.104698483162;5088.491150884956;5190.636655434083;5209.6353255272525;5262.447443018505;5321.414644389114;1850.1960588426177;1983.6826107822967;2014.1641218893994;2105.5999360121527;2135.444784356079;965.3821554205314;1064.2443864855884;1067.108964015056;1068.1988508618535;1076.5747529725716;19759.40721778349;19861.205551778057;19910.402687919384;19953.60092798136;20137.697934530996;1047.2826114111706;1090.5528733219342;1102.7077824376547;1115.2082021764638;1125.4766533561933;11188.328700278009;11209.831852522218;11297.943510282395;11530.538778448768;11579.421028948518;444667;452678;490100;508061;510235;94013;94596.52701736447;95030;98022.01977980272;98757;1029.161233594618;1061.9327575786285;1065.674969134406;1071.3751309292109;1083.06315037492;17588.56028599244;17779.911100444933;18172.90913545424;18223.81776182221;18423.447296581126;43612.34581481217;43702.56297436985;44336.556634434535;45495.77252113718;46297.537024629324;58733.4126658745;58752;63428;63890;65906.67046664737;13077.803832942704;13373;13477.797833032317;13519.662008449473;13529.120607160503;14124.576262712135;14281.571552853431;14473;14587.19770412595;14643.780343295071;12639.1784534005;12743.171693839871;12806.67983300425;12825.615231543068;12852.807207892076;12429.689257768629;12665.936670316589;12714;12780.552680656132;12889.162204456681;12713.55502557406;13085.542006029744;13151.868481315065;13195.538156164488;13203.273819939996;13160.934195328962;13425.26161061112;13465.326733663082;13507.729845403233;13696.52062177819;12380.133390662568;12428.875711243134;12653.683657908257;13075.019373546831;13372.456299080557 08:49:44 INFO - PROCESS | 3403 | |3;mootools.html;1496.7904493370927;1544.451719639528;1643.95327816868;1765.4527096600048;1819.1722766141406;9363.71908842735;10126.34178778379;10130.69607911762;10132.240081993843;10146.441945692985;8177.713780017701;8387.874181887277;9083.72748817535;9311.441313521176;9896.455694936776;2484.2919767866115;2509.1719732488236;2509.573372526675;2513.5726926422462;2517.7608127227886;2439.377958620549;2558.974410255892;2560.1295559509763;2561.065211197909;2564.4742827720365;2499.3126890105186;2511.824172307939;2516.6979962404444;2525.5580273452165;2529.911453099142;2480.4295012147127;2492.1277552856445;2495.962560561593;2504.0734928076545;2511.3972646564785;2461.0155937624913;2464.3099932019063;2470.60470324749;2472.344828620417;2511.3721569607596;9715.07706767856;9745;9759.707208783748;9868.21054315653;9885.950570247174;5493.313335833021;5629.9437005629825;5632;5646.124850648156;5653.9151912721345;3573.6069032406413;3622.510961020255;3685.5024571682748;3692.040069581901;3711.350513660099;1125.4035361258545;1160.1067178272683;1181.9586314479038;1186.9287842729464;1187.139323990109;14027.85972140286;14087.15477071379;14120.152790832582;14155.929220353832;14175.22036288014;4963.503649635008;5098.770555325019;5228;5232;5276.683398996072;11673.182877198515;11677.941610292064;11685.532578696926;11699.06407487399;11699.707507312385;5644.520215781622;5644.8871022579315;5684.857878553069;5693.259876216065;5695.231143795535;4444.333349997526;4571.9085618287445;5267;5273.736313184326;5293.047251494768;4140.763987446805;4188.958110418917;4359.367891655692;4381.868543943623;4404.075144219686;1387.8473367929519;1395.699924516234;1396.748585254664;1398.398688563907;1420.7229590230013;805.3154818404427;807.4105902691068;883.9481017589048;952.5332587032304;998.9900100999042;5887.61730487517;6010.248718910137;6028.668423236764;6162.26052873658;6184.783532576428;463.87011636741767;486.1104179351778;490.57320131485835;491.37595254027184;495.54409942852664;3899.629535194152;3917.5103112110987;4039.6566291864933;4078.5921407858978;4083.305838007546;10946.835797463045;11391.373474458984;11440.942795286137;11470.655880323604;11494.770104597861;11374.203805733685;11379.886201138048;11381.544738210545;11396.373199473943;11401.771964560663;8517.701880434155;8654.567271636393;8692.304615630734;8797.076306987801;8819.073997230327;5660.603757736919;5801.8549536261935;5860.238169037998;5909.734061967136;5972.820815375546;10528.684139475828;10541.57833686644;10552.894471055191;10616.89383106159;10648;10455.634052808115;10556.472176391304;10767;10795;10955.671329860117;6871.656417179021;6980.267071957372;6982.68577913985;8050.074241462073;8113.18868113184;8225.424220304521;8705.782355440911;8994.190522852974;9210.539473026456;9582.329236953346;8342.958285208779;8513.31893448535;8541.914580854113;8580.914190858011;8782.736517904474;8279.461834980708;8281.420300578902;8308.626111825104;8312.625931832961;8331.583420829056;7122.821929451805;7247.057882475456;7315.122185337581;7489.063867016623;7613.733519326797;5011.348524691767;5199.844004679866;5238.109521381451;5262.131748261494;5417.295751552273;5309.787608495772;5350.197470379474;5455.018096742624;5506.115689287088;5548.528375088163 08:49:44 INFO - PROCESS | 3403 | |4;prototype.html;340.5232674256041;348.6321930363466;351.30374376659137;355.6070542051035;358.70586119382085;5462.262594549735;5545.3345598528185;5586.301712285965;5595.300587426572;5620.690862002588;5516.310461192351;5524.1989911462915;5530.115181570949;5545.473180047899;5570.582206334521;4070.3487442009286;4350.847720329789;4351.347297905324;4359.498657654379;4362.738235705868;3851.6533511983916;4126.855560055399;4268.914621707564;4287.528371879091;4304.289792184301;4243.596858298457;4261.424707664457;4263.995921916709;4267.359896015591;4280;4045.59544045596;4145.160565012946;4275.315949448089;4280.614744672963;4287.314029755255;3666.5233519642543;3710.703143748494;3711.2020915503294;3720.069982504374;3721.162738383869;5329.387120481156;5340.866478338034;5378.8924221515745;5384.138537833967;5389.6496727712965;4898.24077268024;4898.24077268024;4899.583535399495;4901.705897646153;4906.411230652345;5716.228309178249;5765.048766953448;5775.5668324875805;5776.862560852512;5786.652800831964;4112.773797441139;4115.97942010288;4125.690573206992;4125.711200215986;4132.710710250284;5352.625316227865;5614.803481878155;5996.370381109964;6031.8793624127275;6036.6076205046975;4165.937510937339;4214.241436541452;4225.809838557272;4239.745615263094;4241.448611680524;4278.828846846153;4282.48610166782;4293.3130699088;4302.806373713128;4304.332828411601;4682.4381074271305;4906.546726636681;4913.606911447076;4917.8524644260015;4919.852404427873;5789.870975159888;5819.330776960619;5834.795782147605;5851.444112809277;5860.501857342174;1483.6439254579038;1502.1437780465249;1505.6762795998693;1509.607502049475;1512.3421311729212;913.4291068082448;948.3409030723581;948.62055177928;954.6944977607238;961.7162936933621;4910.337104490848;4913.582345500601;4919.581835544019;4920.704757714549;4973.8010479581135;5193.532582067632;5196.558292545176;5214;5215.739213039333;5223.485189295062;4687.695299805502;4779.761011949388;4976.233437129839;4976.278439626234;4992.825251116194;2748.7800975921878;2761.895241903255;2766.640336756209;2788.9302767430977;2792.7905407094145;5308.823382413269;5318.946810531923;5356.491133342326;5356.84827762033;5384.542313903284;5313.521783039545;5321.73391330432;5635.239242702265;6099.420555047263;6101.755929762849;6174.44430001302;6182.505399568024;6186.762647470524;6193.287771906198;6193.597416168025;5720.771169153188;5961.2846458425265;5997.490213331916;6021.879562408815;6037.396260374103;5917.224832550304;5957.4042595740075;5958.76164953397;5963.850903727268;5965.940340596539;5951.642901425928;6018.578699490994;6042.697865106639;6051.606645568024;6188.421952402108;4089.5910408958866;4118.0027686572585;4244.1723863846255;4284.55012223712;4302.075053863435;5787.826365209051;5891.705414729332;5943.077568804568;5971.970140149271;5974.2233509643465;5785.132230165341;5795.797147099831;5818.359980402068;5835.824925252249;5851.473367396955;5820;5821.650700957956;5835.095560188178;5842.591018628656;5844.678542680023;5545.500904918576;5725.828225153252;5730.541556675373;5765.942340576708;5784.21913041734;5759.308882934075;5787.276590426197;5787.450192231731;5820.796272130455;5826.73779679907;5522.447755224445;5684.658920464785;5696.373398926197;5709;5722.427757224411 08:49:44 INFO - PROCESS | 3403 | |5;yui.html;784.160947785869;805.3154818404357;842.1957031035362;849.6219182463803;898.1871406377231;15819.604509887244;16465.67068658626;16524.339026438927;16539.17304134793;16547.172641367943;16040.83959160411;16189;16221.513354599352;16426.917865410687;16439.671206575862;726.9781906542812;727.730739626339;867.9262262707678;870.1559487297311;873.026575368466;877.6182360673096;882.0209567380202;883.3154305413292;884.5621417398397;884.6594061286387;916.4730280088976;918.4581097152678;921.5944984206961;922.9400088994194;923.866039424283;760.6310939194486;783.2950344689768;875.3216257400502;893.6470094312775;920.7237828651378;657.8815813153631;658.1016911915249;658.4304576541305;658.4995403493332;659.1134923527848;15977.281022354022;16013.439529616408;16027;16031.83968160315;16057.277422516016;14773.704525909528;14903.403863845433;14917.701645967129;15058.247087645683;15246.161461119635;13715.382807773674;13867.58084424838;13872.93063534686;13978.370973306226;14372.065815721944;4723.102610503994;4766.880827979294;4805.687630304019;4911.386076740408;4916.926246106311;82162;82669;83271.58364208201;88316.11683883208;89866.5506672469;11185.608503702331;11577.479013444416;11596;11623.59317423886;11725.120615953667;22776.430589235402;22956.65565016526;22963.655545166836;23009.309720708407;23017;8702.30381569473;8990.415622984485;9141.131592498701;9214.861777073349;9365.250779937589;5108.693478391308;5275.076861549213;5306.602004849652;5320.627556071116;5372.059889519319;3849.172427928009;3949.308870947573;3990.06233535119;3994.820233089518;4003.179348233605;647.970841312142;672.37133280383;676.3980057748607;677.7153595489906;690.4545409126733;741.5550669598198;793.1949071692237;802.879568064795;806.7660378490173;812.1350761439048;10143.695689129338;10983.505742241618;10986.505607247693;11619.76760464786;11636.185467017396;756.7313603670727;762.6491813765618;767.5126294802841;770.2913319745848;857.9785505362291;5035.77339019745;5167.354080739908;5172.327597412313;5213.296205012351;5305.602079844027;76878;114568.42715786368;116548;117441;117806;98906.5054674722;99936.5003174994;100701.49649251706;101357;103531.4823425878;66599.66700166468;86206.56896715476;89573;90216.548917255;90546;9465.716028519155;9545;9696.17582505495;9721.465319407218;9839.950800245953;39003.80498097491;42336.576634234494;46684.066318674115;53287;53972.99999999843;76422.61788691022;76748.23251767411;77077;78912.21087789278;80095.59952200201;13502.662433439242;13678.658033549242;13764.862351376358;13776.448942042207;13799.448022079005;13175.538856140372;13757.656058598615;14788.48240311627;14851.628709282355;14911.478098266509;10962.671119866416;13329.133606315558;13868.791968120284;14008.929955350159;14904.701905962036;12558.63592365548;12609.558665446666;12828.230306181658;12869.742605147658;13148.868511314764;13390.665233369244;13424.261665608095;13473.730525389634;13570.457181712622;14110.717785644434;10918.454077296263;10966.835497467384;11218.270812397168;11727.530898764295;12102.75794484123;10759.515821787882;10768.061748885277;11569.942150289195;11983.460744266704;12130.878691212974 08:49:44 INFO - PROCESS | 3403 | __end_tp_report 08:49:44 INFO - PROCESS | 3403 | __start_cc_report 08:49:44 INFO - PROCESS | 3403 | _x_x_mozilla_cycle_collect,871 08:49:44 INFO - PROCESS | 3403 | __end_cc_report 08:49:44 INFO - PROCESS | 3403 | __startTimestamp1474732184786__endTimestamp 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | ------- Summary: start ------- 08:49:44 INFO - PROCESS | 3403 | Number of tests: 6 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | [#0] dojo.html Cycles:165 Average:5612.43 Median:5809.27 stddev:641.09 (11.0%) stddev-sans-first:612.27 08:49:44 INFO - PROCESS | 3403 | Values: 3110.3 3146.9 3148.4 3168.9 3185.3 5600.1 5668.9 5689.7 5706.0 5711.6 5710.5 5771.2 5776.0 5776.8 5807.9 5173.0 5177.9 5198.1 5253.2 5284.7 5044.2 5072.7 5289.4 5299.2 5327.4 5101.6 5114.4 5130.0 5155.6 5157.6 5126.0 5173.6 5242.0 5243.7 5272.9 4960.8 5071.4 5136.0 5176.5 5199.2 5485.2 5502.7 5515.7 5521.8 5562.2 5908.1 5909.7 6015.2 6016.1 6021.5 5824.3 6030.7 6053.2 6058.5 6059.4 5449.9 5477.8 5613.9 5615.9 5628.8 6169.0 6192.5 6195.3 6201.3 6203.6 5664.6 5939.0 5952.3 5954.6 5957.0 5737.3 5861.1 5901.3 5901.7 6017.2 5962.0 5969.9 5971.3 5977.9 5979.3 5932.3 5994.9 5995.2 5997.5 6015.8 4555.1 4593.3 4618.4 4646.0 4652.5 4127.1 4156.1 4162.0 4183.7 4185.7 5771.2 5787.9 5788.2 5788.5 5794.1 5896.4 5946.1 5992.3 6031.6 6122.1 5782.7 5785.7 5791.6 5815.3 5824.0 5281.6 5291.3 5293.4 5296.7 5297.6 6072.1 6125.9 6134.6 6289.9 6331.7 5986.9 6114.4 6116.5 6117.1 6123.1 6278.8 6285.5 6292.9 6299.0 6304.4 5759.2 5789.6 5988.5 5989.5 5991.8 6047.0 6103.7 6130.5 6132.8 6133.2 5939.3 5997.4 6010.6 6013.6 6016.4 5637.1 5997.1 6032.4 6040.7 6041.2 5690.8 5809.3 5816.9 5858.9 5945.9 5924.9 5977.9 5978.8 5985.8 5987.3 5668.2 5722.7 5799.7 5837.3 5849.9 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | [#1] ext.html Cycles:180 Average:63750.71 Median:4070.49 stddev:125401.10 (3080.7%) stddev-sans-first:125663.21 08:49:44 INFO - PROCESS | 3403 | Values: 589.3 614.7 643.6 658.7 663.4 35119.0 35640.8 38120.4 38154.6 38223.4 46844.8 47979.0 48052.8 51494.5 51574.7 9142.4 9274.9 9277.5 9286.6 9300.7 8656.0 8948.2 9018.2 9030.4 9032.1 8708.2 11058.4 11291.3 11336.4 11653.7 11191.4 11291.7 11304.2 11314.4 11334.3 11047.9 11221.1 11222.1 11226.3 11229.3 45394.3 45771.0 46021.5 46042.0 46154.0 3950.4 3963.9 3998.3 4067.6 4073.4 34803.3 34983.8 34992.7 35005.6 35249.0 4684.2 4694.4 4752.7 4763.4 4859.9 375680.0 376171.0 378001.0 378102.0 378794.1 63721.0 64295.0 64302.4 64314.4 64327.4 39885.4 41056.4 41161.0 43445.3 66246.0 16096.8 16189.4 16221.4 16230.3 16244.5 12743.7 12840.7 12860.9 12866.9 12883.2 18690.2 19051.6 20196.4 20844.5 21127.9 7041.7 7187.5 7243.7 7376.2 7399.6 957.1 962.2 979.9 1042.3 1101.3 34112.1 34316.3 34357.3 34556.5 34674.8 1090.8 1135.8 1164.3 1194.7 1226.8 16984.4 17815.6 17904.5 17958.2 17991.6 559655.0 563239.0 563410.0 564870.0 566070.0 64796.4 65550.7 65566.7 65597.7 65682.3 1302.9 1371.6 1407.7 1409.8 1429.7 32196.7 33710.2 33887.0 34006.8 34366.7 55859.4 56434.0 56531.0 56655.7 56735.0 457680.0 459157.0 460774.0 461879.0 463234.0 26482.9 31882.2 31940.5 32099.7 32614.2 52587.8 52665.5 52806.0 53018.3 53365.0 43301.8 44277.1 44360.0 46235.8 46425.3 44274.6 44517.0 45113.5 46788.5 46834.0 44198.6 44278.3 44401.8 44403.0 44939.0 37164.8 39091.8 39263.2 39474.2 39795.6 29814.0 29845.0 29920.0 29980.7 30139.8 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | [#2] jquery.html Cycles:180 Average:29751.31 Median:11110.02 stddev:79657.68 (717.0%) stddev-sans-first:79850.69 08:49:44 INFO - PROCESS | 3403 | Values: 414.3 414.9 425.0 426.4 444.6 20187.3 20326.0 20989.7 21299.1 21481.6 17327.0 17465.3 17929.3 18040.1 18233.3 5220.4 5470.8 5475.5 5479.5 5480.0 4016.3 4194.3 5430.3 5457.0 5770.7 5947.3 6083.0 6091.9 6094.4 6099.5 5868.9 6012.7 6047.8 6055.1 6094.8 5745.4 5889.2 5889.8 5899.2 5900.3 14577.3 14699.2 22637.0 23757.9 24214.8 2394.2 2576.0 2578.5 2737.8 2748.0 9988.5 10160.9 10265.9 10440.3 10723.7 1927.7 1942.6 2032.7 2058.7 2064.2 84256.2 85837.6 87452.6 87901.0 88254.6 11836.8 11879.6 11880.8 12091.1 12241.7 17038.2 17119.6 17135.5 17162.5 17184.3 8563.8 8568.1 8640.1 8689.5 8754.1 7546.7 7674.3 7729.7 7936.7 8139.1 5088.5 5190.6 5209.6 5262.4 5321.4 1850.2 1983.7 2014.2 2105.6 2135.4 965.4 1064.2 1067.1 1068.2 1076.6 19759.4 19861.2 19910.4 19953.6 20137.7 1047.3 1090.6 1102.7 1115.2 1125.5 11188.3 11209.8 11297.9 11530.5 11579.4 444667.0 452678.0 490100.0 508061.0 510235.0 94013.0 94596.5 95030.0 98022.0 98757.0 1029.2 1061.9 1065.7 1071.4 1083.1 17588.6 17779.9 18172.9 18223.8 18423.4 43612.3 43702.6 44336.6 45495.8 46297.5 58733.4 58752.0 63428.0 63890.0 65906.7 13077.8 13373.0 13477.8 13519.7 13529.1 14124.6 14281.6 14473.0 14587.2 14643.8 12639.2 12743.2 12806.7 12825.6 12852.8 12429.7 12665.9 12714.0 12780.6 12889.2 12713.6 13085.5 13151.9 13195.5 13203.3 13160.9 13425.3 13465.3 13507.7 13696.5 12380.1 12428.9 12653.7 13075.0 13372.5 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | [#3] mootools.html Cycles:180 Average:6163.13 Median:2517.89 stddev:3608.91 (143.3%) stddev-sans-first:3602.00 08:49:44 INFO - PROCESS | 3403 | Values: 1496.8 1544.5 1644.0 1765.5 1819.2 9363.7 10126.3 10130.7 10132.2 10146.4 8177.7 8387.9 9083.7 9311.4 9896.5 2484.3 2509.2 2509.6 2513.6 2517.8 2439.4 2559.0 2560.1 2561.1 2564.5 2499.3 2511.8 2516.7 2525.6 2529.9 2480.4 2492.1 2496.0 2504.1 2511.4 2461.0 2464.3 2470.6 2472.3 2511.4 9715.1 9745.0 9759.7 9868.2 9886.0 5493.3 5629.9 5632.0 5646.1 5653.9 3573.6 3622.5 3685.5 3692.0 3711.4 1125.4 1160.1 1182.0 1186.9 1187.1 14027.9 14087.2 14120.2 14155.9 14175.2 4963.5 5098.8 5228.0 5232.0 5276.7 11673.2 11677.9 11685.5 11699.1 11699.7 5644.5 5644.9 5684.9 5693.3 5695.2 4444.3 4571.9 5267.0 5273.7 5293.0 4140.8 4189.0 4359.4 4381.9 4404.1 1387.8 1395.7 1396.7 1398.4 1420.7 805.3 807.4 883.9 952.5 999.0 5887.6 6010.2 6028.7 6162.3 6184.8 463.9 486.1 490.6 491.4 495.5 3899.6 3917.5 4039.7 4078.6 4083.3 10946.8 11391.4 11440.9 11470.7 11494.8 11374.2 11379.9 11381.5 11396.4 11401.8 8517.7 8654.6 8692.3 8797.1 8819.1 5660.6 5801.9 5860.2 5909.7 5972.8 10528.7 10541.6 10552.9 10616.9 10648.0 10455.6 10556.5 10767.0 10795.0 10955.7 6871.7 6980.3 6982.7 8050.1 8113.2 8225.4 8705.8 8994.2 9210.5 9582.3 8343.0 8513.3 8541.9 8580.9 8782.7 8279.5 8281.4 8308.6 8312.6 8331.6 7122.8 7247.1 7315.1 7489.1 7613.7 5011.3 5199.8 5238.1 5262.1 5417.3 5309.8 5350.2 5455.0 5506.1 5548.5 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | [#4] prototype.html Cycles:180 Average:4767.25 Median:5346.75 stddev:1414.43 (26.5%) stddev-sans-first:1378.82 08:49:44 INFO - PROCESS | 3403 | Values: 340.5 348.6 351.3 355.6 358.7 5462.3 5545.3 5586.3 5595.3 5620.7 5516.3 5524.2 5530.1 5545.5 5570.6 4070.3 4350.8 4351.3 4359.5 4362.7 3851.7 4126.9 4268.9 4287.5 4304.3 4243.6 4261.4 4264.0 4267.4 4280.0 4045.6 4145.2 4275.3 4280.6 4287.3 3666.5 3710.7 3711.2 3720.1 3721.2 5329.4 5340.9 5378.9 5384.1 5389.6 4898.2 4898.2 4899.6 4901.7 4906.4 5716.2 5765.0 5775.6 5776.9 5786.7 4112.8 4116.0 4125.7 4125.7 4132.7 5352.6 5614.8 5996.4 6031.9 6036.6 4165.9 4214.2 4225.8 4239.7 4241.4 4278.8 4282.5 4293.3 4302.8 4304.3 4682.4 4906.5 4913.6 4917.9 4919.9 5789.9 5819.3 5834.8 5851.4 5860.5 1483.6 1502.1 1505.7 1509.6 1512.3 913.4 948.3 948.6 954.7 961.7 4910.3 4913.6 4919.6 4920.7 4973.8 5193.5 5196.6 5214.0 5215.7 5223.5 4687.7 4779.8 4976.2 4976.3 4992.8 2748.8 2761.9 2766.6 2788.9 2792.8 5308.8 5318.9 5356.5 5356.8 5384.5 5313.5 5321.7 5635.2 6099.4 6101.8 6174.4 6182.5 6186.8 6193.3 6193.6 5720.8 5961.3 5997.5 6021.9 6037.4 5917.2 5957.4 5958.8 5963.9 5965.9 5951.6 6018.6 6042.7 6051.6 6188.4 4089.6 4118.0 4244.2 4284.6 4302.1 5787.8 5891.7 5943.1 5972.0 5974.2 5785.1 5795.8 5818.4 5835.8 5851.5 5820.0 5821.7 5835.1 5842.6 5844.7 5545.5 5725.8 5730.5 5765.9 5784.2 5759.3 5787.3 5787.5 5820.8 5826.7 5522.4 5684.7 5696.4 5709.0 5722.4 08:49:44 INFO - PROCESS | 3403 | 08:49:44 INFO - PROCESS | 3403 | [#5] yui.html Cycles:180 Average:21203.18 Median:4744.99 stddev:30056.54 (633.4%) stddev-sans-first:30101.75 08:49:44 INFO - PROCESS | 3403 | Values: 784.2 805.3 842.2 849.6 898.2 15819.6 16465.7 16524.3 16539.2 16547.2 16040.8 16189.0 16221.5 16426.9 16439.7 727.0 727.7 867.9 870.2 873.0 877.6 882.0 883.3 884.6 884.7 916.5 918.5 921.6 922.9 923.9 760.6 783.3 875.3 893.6 920.7 657.9 658.1 658.4 658.5 659.1 15977.3 16013.4 16027.0 16031.8 16057.3 14773.7 14903.4 14917.7 15058.2 15246.2 13715.4 13867.6 13872.9 13978.4 14372.1 4723.1 4766.9 4805.7 4911.4 4916.9 82162.0 82669.0 83271.6 88316.1 89866.6 11185.6 11577.5 11596.0 11623.6 11725.1 22776.4 22956.7 22963.7 23009.3 23017.0 8702.3 8990.4 9141.1 9214.9 9365.3 5108.7 5275.1 5306.6 5320.6 5372.1 3849.2 3949.3 3990.1 3994.8 4003.2 648.0 672.4 676.4 677.7 690.5 741.6 793.2 802.9 806.8 812.1 10143.7 10983.5 10986.5 11619.8 11636.2 756.7 762.6 767.5 770.3 858.0 5035.8 5167.4 5172.3 5213.3 5305.6 76878.0 114568.4 116548.0 117441.0 117806.0 98906.5 99936.5 100701.5 101357.0 103531.5 66599.7 86206.6 89573.0 90216.5 90546.0 9465.7 9545.0 9696.2 9721.5 9840.0 39003.8 42336.6 46684.1 53287.0 53973.0 76422.6 76748.2 77077.0 78912.2 80095.6 13502.7 13678.7 13764.9 13776.4 13799.4 13175.5 13757.7 14788.5 14851.6 14911.5 10962.7 13329.1 13868.8 14008.9 14904.7 12558.6 12609.6 12828.2 12869.7 13148.9 13390.7 13424.3 13473.7 13570.5 14110.7 10918.5 10966.8 11218.3 11727.5 12102.8 10759.5 10768.1 11569.9 11983.5 12130.9 08:49:44 INFO - PROCESS | 3403 | -------- Summary: end -------- 08:49:44 INFO - PROCESS | 3403 | 08:49:45 INFO - TEST-INFO | 3403: exit 0 08:49:45 INFO - TEST-OK | dromaeo_css | took 1115234ms 08:49:45 INFO - TEST-START | kraken 08:49:45 INFO - operating with platform_type : linux_ 08:49:45 INFO - Initialising browser for kraken test... 08:49:45 INFO - TEST-INFO | started process 3721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpltXTYm/profile http://localhost:33777/getInfo.html) 08:49:49 INFO - PROCESS | 3721 | __metrics Screen width/height:1600/1200 08:49:49 INFO - PROCESS | 3721 | colorDepth:24 08:49:49 INFO - PROCESS | 3721 | Browser inner width/height: 1024/697 08:49:49 INFO - PROCESS | 3721 | __metrics 08:49:49 INFO - PROCESS | 3721 | 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" 08:49:49 INFO - PROCESS | 3721 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:50 INFO - PROCESS | 3721 | console.error: PushService: 08:49:50 INFO - PROCESS | 3721 | stateChangeProcessEnqueue: Error transitioning state 08:49:50 INFO - PROCESS | 3721 | UnknownError 08:49:50 INFO - TEST-INFO | 3721: exit 0 08:49:50 INFO - Browser initialized. 08:49:50 INFO - Running cycle 1/1 for kraken test... 08:49:50 INFO - TEST-INFO | started process 3812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpltXTYm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:02 INFO - PROCESS | 3812 | RSS: Main: 144584704 08:50:02 INFO - PROCESS | 3812 | 08:50:05 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33777/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:05 INFO - PROCESS | 3812 | RSS: Main: 185262080 08:50:05 INFO - PROCESS | 3812 | 08:50:08 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33777/tests/kraken/driver.html?testName=audio-dft) 08:50:09 INFO - PROCESS | 3812 | RSS: Main: 174923776 08:50:09 INFO - PROCESS | 3812 | 08:50:11 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33777/tests/kraken/driver.html?testName=audio-fft) 08:50:12 INFO - PROCESS | 3812 | RSS: Main: 266571776 08:50:12 INFO - PROCESS | 3812 | 08:50:14 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33777/tests/kraken/driver.html?testName=audio-oscillator) 08:50:15 INFO - PROCESS | 3812 | RSS: Main: 172453888 08:50:15 INFO - PROCESS | 3812 | 08:50:17 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33777/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:17 INFO - PROCESS | 3812 | RSS: Main: 174575616 08:50:17 INFO - PROCESS | 3812 | 08:50:20 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33777/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:21 INFO - PROCESS | 3812 | RSS: Main: 210825216 08:50:21 INFO - PROCESS | 3812 | 08:50:24 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33777/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:25 INFO - PROCESS | 3812 | RSS: Main: 209690624 08:50:25 INFO - PROCESS | 3812 | 08:50:28 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33777/tests/kraken/driver.html?testName=json-parse-financial) 08:50:28 INFO - PROCESS | 3812 | RSS: Main: 209289216 08:50:28 INFO - PROCESS | 3812 | 08:50:30 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33777/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:30 INFO - PROCESS | 3812 | RSS: Main: 170995712 08:50:30 INFO - PROCESS | 3812 | 08:50:32 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:33 INFO - PROCESS | 3812 | RSS: Main: 178053120 08:50:33 INFO - PROCESS | 3812 | 08:50:35 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:35 INFO - PROCESS | 3812 | RSS: Main: 183091200 08:50:35 INFO - PROCESS | 3812 | 08:50:38 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:50:38 INFO - PROCESS | 3812 | RSS: Main: 184721408 08:50:38 INFO - PROCESS | 3812 | 08:50:41 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:50:42 INFO - PROCESS | 3812 | RSS: Main: 182382592 08:50:42 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | Cycle 1(1): loaded http://localhost:33777/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33777/tests/kraken/driver.html?testName=ai-astar) 08:50:43 INFO - PROCESS | 3812 | RSS: Main: 233926656 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | __start_tp_report 08:50:43 INFO - PROCESS | 3812 | _x_x_mozilla_page_load 08:50:43 INFO - PROCESS | 3812 | _x_x_mozilla_page_load_details 08:50:43 INFO - PROCESS | 3812 | |i|pagename|runs| 08:50:43 INFO - PROCESS | 3812 | |0;ai-astar;108;111;109;110;111;109;110;110;110;110 08:50:43 INFO - PROCESS | 3812 | |1;audio-beat-detection;147;118;119;150;118;120;122;116;149;118 08:50:43 INFO - PROCESS | 3812 | |2;audio-dft;161;161;160;161;162;161;163;159;161;161 08:50:43 INFO - PROCESS | 3812 | |3;audio-fft;74;75;75;108;76;76;75;86;105;75 08:50:43 INFO - PROCESS | 3812 | |4;audio-oscillator;81;81;105;81;81;81;102;80;81;111 08:50:43 INFO - PROCESS | 3812 | |5;imaging-gaussian-blur;117;117;116;116;117;116;118;117;117;117 08:50:43 INFO - PROCESS | 3812 | |6;imaging-darkroom;145;145;146;145;145;145;146;144;145;146 08:50:43 INFO - PROCESS | 3812 | |7;imaging-desaturate;95;95;95;95;96;96;95;95;96;95 08:50:43 INFO - PROCESS | 3812 | |8;json-parse-financial;51;50;50;51;49;51;49;51;51;50 08:50:43 INFO - PROCESS | 3812 | |9;json-stringify-tinderbox;58;58;58;58;58;58;57;57;58;58 08:50:43 INFO - PROCESS | 3812 | |10;stanford-crypto-aes;73;73;73;81;72;73;74;74;72;81 08:50:43 INFO - PROCESS | 3812 | |11;stanford-crypto-ccm;120;122;122;121;119;117;125;131;159;132 08:50:43 INFO - PROCESS | 3812 | |12;stanford-crypto-pbkdf2;157;154;156;154;155;159;157;152;155;156 08:50:43 INFO - PROCESS | 3812 | |13;stanford-crypto-sha256-iterative;63;63;66;62;62;64;62;61;61;61 08:50:43 INFO - PROCESS | 3812 | __end_tp_report 08:50:43 INFO - PROCESS | 3812 | __start_cc_report 08:50:43 INFO - PROCESS | 3812 | _x_x_mozilla_cycle_collect,1580 08:50:43 INFO - PROCESS | 3812 | __end_cc_report 08:50:43 INFO - PROCESS | 3812 | __startTimestamp1474732243930__endTimestamp 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | ------- Summary: start ------- 08:50:43 INFO - PROCESS | 3812 | Number of tests: 14 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:0.92 (0.8%) stddev-sans-first:0.71 08:50:43 INFO - PROCESS | 3812 | Values: 108.0 111.0 109.0 110.0 111.0 109.0 110.0 110.0 110.0 110.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:121.00 stddev:14.57 (12.0%) stddev-sans-first:13.68 08:50:43 INFO - PROCESS | 3812 | Values: 147.0 118.0 119.0 150.0 118.0 120.0 122.0 116.0 149.0 118.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 08:50:43 INFO - PROCESS | 3812 | Values: 161.0 161.0 160.0 161.0 162.0 161.0 163.0 159.0 161.0 161.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#3] audio-fft Cycles:10 Average:82.50 Median:75.00 stddev:13.13 (17.5%) stddev-sans-first:13.56 08:50:43 INFO - PROCESS | 3812 | Values: 74.0 75.0 75.0 108.0 76.0 76.0 75.0 86.0 105.0 75.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:81.00 stddev:12.34 (15.2%) stddev-sans-first:12.79 08:50:43 INFO - PROCESS | 3812 | Values: 81.0 81.0 105.0 81.0 81.0 81.0 102.0 80.0 81.0 111.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 08:50:43 INFO - PROCESS | 3812 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 118.0 117.0 117.0 117.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 08:50:43 INFO - PROCESS | 3812 | Values: 145.0 145.0 146.0 145.0 145.0 145.0 146.0 144.0 145.0 146.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:50:43 INFO - PROCESS | 3812 | Values: 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.83 08:50:43 INFO - PROCESS | 3812 | Values: 51.0 50.0 50.0 51.0 49.0 51.0 49.0 51.0 51.0 50.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:58.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 08:50:43 INFO - PROCESS | 3812 | Values: 58.0 58.0 58.0 58.0 58.0 58.0 57.0 57.0 58.0 58.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:73.50 stddev:3.44 (4.7%) stddev-sans-first:3.60 08:50:43 INFO - PROCESS | 3812 | Values: 73.0 73.0 73.0 81.0 72.0 73.0 74.0 74.0 72.0 81.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:123.50 stddev:12.33 (10.0%) stddev-sans-first:12.83 08:50:43 INFO - PROCESS | 3812 | Values: 120.0 122.0 122.0 121.0 119.0 117.0 125.0 131.0 159.0 132.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.50 Median:156.00 stddev:1.96 (1.3%) stddev-sans-first:2.00 08:50:43 INFO - PROCESS | 3812 | Values: 157.0 154.0 156.0 154.0 155.0 159.0 157.0 152.0 155.0 156.0 08:50:43 INFO - PROCESS | 3812 | 08:50:43 INFO - PROCESS | 3812 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:1.58 (2.5%) stddev-sans-first:1.67 08:50:43 INFO - PROCESS | 3812 | Values: 63.0 63.0 66.0 62.0 62.0 64.0 62.0 61.0 61.0 61.0 08:50:43 INFO - PROCESS | 3812 | -------- Summary: end -------- 08:50:43 INFO - PROCESS | 3812 | 08:50:44 INFO - TEST-INFO | 3812: exit 0 08:50:44 INFO - TEST-OK | kraken | took 59371ms 08:50:44 INFO - SUITE-END | took 1174s 08:50:45 INFO - Completed test suite (00:19:34) 08:50:45 INFO - javascript benchmark 08:50:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.300182458947, 3146.8583913723864, 3148.4490214212506, 3168.9207769805766, 3185.251465905513, 5600.103983362663, 5668.886622267552, 5689.715514224293, 5706.029974904266, 5711.628744131628, 5710.514606258473, 5771.220885180499, 5776.018076926922, 5776.797812076578, 5807.883842323162, 5173.042987047389, 5177.870553236162, 5198.116320225571, 5253.185756207794, 5284.709340986245, 5044.218146187347, 5072.74636268187, 5289.391719952197, 5299.205119232127, 5327.387350454689, 5101.566366858821, 5114.43741188469, 5130.0, 5155.587552995751, 5157.6131790115705, 5125.974370128144, 5173.6119791015635, 5241.973790131063, 5243.711595862226, 5272.868178295535, 4960.826371076995, 5071.442141364447, 5135.97280543889, 5176.456472070416, 5199.168133098686, 5485.232067510552, 5502.697351645658, 5515.7242137892945, 5521.751521181556, 5562.193481945095, 5908.0842469416875, 5909.674967876765, 6015.248093988252, 6016.097585362187, 6021.548383871227, 5824.330202026778, 6030.698465076729, 6053.152558641794, 6058.545609079337, 6059.36376680451, 5449.945500545023, 5477.75350109242, 5613.8596535086135, 5615.943840561583, 5628.831135065954, 6168.9691551541955, 6192.473639740582, 6195.256569211633, 6201.317855035943, 6203.565750397519, 5664.631798933056, 5939.0, 5952.285725712942, 5954.55340849438, 5957.017092179828, 5737.311522617312, 5861.120831875252, 5901.321348044944, 5901.675407852524, 6017.157597936292, 5962.046072628359, 5969.910451343233, 5971.28344598651, 5977.880442391128, 5979.342272350038, 5932.3177834548715, 5994.880102397928, 5995.220621319288, 5997.490213331828, 6015.849603759853, 4555.088982203506, 4593.311003349458, 4618.422697162855, 4646.0, 4652.488226295105, 4127.050778320943, 4156.106437116035, 4162.0427301720165, 4183.748975061506, 4185.727927684752, 5771.163181338767, 5787.8553036174435, 5788.218590490314, 5788.450097240755, 5794.101914203306, 5896.439838215449, 5946.137810017523, 5992.310884248367, 6031.577789554777, 6122.142899994004, 5782.739776710058, 5785.681787501644, 5791.594588378857, 5815.331236907809, 5823.970880145572, 5281.603879709037, 5291.3385826771655, 5293.444188360167, 5296.655717378358, 5297.576193904555, 6072.149899014142, 6125.908111378244, 6134.5590473767, 6289.937100629027, 6331.746730130835, 5986.9401305986385, 6114.358196334042, 6116.518381401779, 6117.0662765122115, 6123.099607467949, 6278.811635650938, 6285.465735412541, 6292.874142517216, 6298.9921612541775, 6304.401081897213, 5759.193712880116, 5789.594728368974, 5988.461038506555, 5989.460948514655, 5991.820245392645, 6047.03247480401, 6103.664298463627, 6130.459285710702, 6132.845047373448, 6133.202683651097, 5939.287285525765, 5997.400259973968, 6010.639361638141, 6013.609115407485, 6016.42843929826, 5637.126245431965, 5997.070454079625, 6032.3967603239325, 6040.6677632730625, 6041.214642096499, 5690.829275121753, 5809.2738407699035, 5816.912746308724, 5858.882822343615, 5945.881082378241, 5924.940750592439, 5977.9103313451205, 5978.8206353809455, 5985.790497332572, 5987.251593550806, 5668.234788303691, 5722.713864306851, 5799.681017543907, 5837.328707198726, 5849.883002340014], "value": 5568.073764288037, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.3281658908845, 614.6957256158202, 643.6009674002117, 658.7068754404291, 663.3929954092006, 35119.0, 35640.82179589102, 38120.42819357712, 38154.618453815456, 38223.42664860026, 46844.76577617116, 47978.99999999991, 48052.759736201355, 51494.48505514944, 51574.74212628932, 9142.36003479755, 9274.860877086849, 9277.536123193848, 9286.58210380531, 9300.674476393327, 8656.047834738174, 8948.239399651005, 9018.233450156744, 9030.367874248805, 9032.09679032098, 8708.17272359128, 11058.391788451632, 11291.322520648788, 11336.376499292535, 11653.708657283594, 11191.440427978609, 11291.661250162508, 11304.208705390625, 11314.434278286053, 11334.263272887236, 11047.889521104766, 11221.102311815035, 11222.102231821435, 11226.32642041472, 11229.270097443721, 45394.31908521375, 45771.0, 46021.53978460206, 46042.0, 46154.0, 3950.4271880577157, 3963.850483359822, 3998.260321840469, 4067.5525692173837, 4073.429719839225, 34803.30393392118, 34983.82508087469, 34992.65007349919, 35005.649943500495, 35249.0, 4684.227102528079, 4694.389729335165, 4752.738599377033, 4763.404574428197, 4859.854204373874, 375680.0, 376171.0, 378001.0, 378102.0, 378794.10602947086, 63721.0, 64295.0, 64302.35697642963, 64314.35685643084, 64327.35672643307, 39885.40171897424, 41056.38415423771, 41161.0, 43445.34831977523, 66246.0, 16096.839031609768, 16189.352425903067, 16221.35114595403, 16230.269637866088, 16244.51266462008, 12743.681407964876, 12840.743185136245, 12860.93569532165, 12866.9356653218, 12883.227006379648, 18690.15894284733, 19051.61896762057, 20196.394108176777, 20844.47888802792, 21127.8943605281, 7041.718331266692, 7187.460940429489, 7243.710251589878, 7376.225496322809, 7399.556026638419, 957.0620791624248, 962.1581116523042, 979.9412035277907, 1042.3485321673954, 1101.3336931156696, 34112.1004714602, 34316.31367372689, 34357.312853742784, 34556.48165277523, 34674.826625867216, 1090.7872964771952, 1135.7955567997674, 1164.2781475485085, 1194.6714653470192, 1226.7730469863104, 16984.40554580584, 17815.554611134565, 17904.462866114038, 17958.19188136537, 17991.550211244823, 559655.0, 563239.0, 563410.0, 564870.0, 566070.0, 64796.35203647998, 65550.67224663847, 65566.67216663982, 65597.67201164059, 65682.34317656858, 1302.8892544133855, 1371.6090914089636, 1407.7325308191412, 1409.7814838700017, 1429.7140571885664, 32196.678033219367, 33710.15724606806, 33887.0, 34006.82996585001, 34366.656333436345, 55859.44140558705, 56434.0, 56530.999999998356, 56655.71672141613, 56735.0, 457680.0, 459157.0, 460774.0, 461879.0, 463234.0, 26482.86758566195, 31882.20294492656, 31940.5208921871, 32099.679003210604, 32614.184645384055, 52587.84888342313, 52665.47334526606, 52806.0, 53018.34908254649, 53365.0, 43301.783491082344, 44277.11445771131, 44360.0, 46235.76882115568, 46425.30362044639, 44274.55725442704, 44517.0, 45113.548864510936, 46788.53211467842, 46834.0, 44198.55801441945, 44278.33582496201, 44401.77799110984, 44403.0, 44939.0, 37164.81417592895, 39091.804540977115, 39263.2147357057, 39474.210515790095, 39795.60204398035, 29814.0, 29845.0, 29920.0, 29980.65955864121, 30139.849300754235], "value": 21374.635372371697, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.3184461560731, 414.86931616540767, 424.9617534421901, 426.3583307122781, 444.6353989728422, 20187.29344472944, 20325.983700814955, 20989.685154722672, 21299.14803407862, 21481.57036859262, 17326.960382377067, 17465.3013879445, 17929.282828686868, 18040.097995100226, 18233.270669173216, 5220.425753167148, 5470.781168753245, 5475.5072043516075, 5479.479449452296, 5480.04099282626, 4016.256992456405, 4194.266003449399, 5430.348358197022, 5457.045017122007, 5770.711464426783, 5947.286325640929, 6083.0, 6091.878162436748, 6094.421030002165, 6099.451049405553, 5868.911966320508, 6012.699365031753, 6047.848803779897, 6055.122007308915, 6094.8171554853425, 5745.368009518949, 5889.234399528077, 5889.793857214977, 5899.233099697055, 5900.262467191601, 14577.344019519041, 14699.19154446505, 22636.981335839926, 23757.88121059401, 24214.757852421426, 2394.185976767907, 2576.0211119774553, 2578.510083084208, 2737.7536021757937, 2747.983246198899, 9988.500574971295, 10160.89839101607, 10265.897341026568, 10440.321379110334, 10723.731906702316, 1927.7204805303293, 1942.6114777044647, 2032.7459067616549, 2058.7220725202055, 2064.215598072738, 84256.15743842545, 85837.57081214554, 87452.5627371859, 87901.0, 88254.55872720659, 11836.822447663291, 11879.643610691694, 11880.76238475243, 12091.093168012434, 12241.69395765113, 17038.233279502452, 17119.57201069983, 17135.48593542196, 17162.485125446256, 17184.31262749476, 8563.78857928277, 8568.143185681382, 8640.13598640131, 8689.478631282143, 8754.08082151365, 7546.698132074765, 7674.309312161932, 7729.652165652559, 7936.722214722457, 8139.104698483162, 5088.491150884956, 5190.636655434083, 5209.6353255272525, 5262.447443018505, 5321.414644389114, 1850.1960588426177, 1983.6826107822967, 2014.1641218893994, 2105.5999360121527, 2135.444784356079, 965.3821554205314, 1064.2443864855884, 1067.108964015056, 1068.1988508618535, 1076.5747529725716, 19759.40721778349, 19861.205551778057, 19910.402687919384, 19953.60092798136, 20137.697934530996, 1047.2826114111706, 1090.5528733219342, 1102.7077824376547, 1115.2082021764638, 1125.4766533561933, 11188.328700278009, 11209.831852522218, 11297.943510282395, 11530.538778448768, 11579.421028948518, 444667.0, 452678.0, 490100.0, 508061.0, 510235.0, 94013.0, 94596.52701736447, 95030.0, 98022.01977980272, 98757.0, 1029.161233594618, 1061.9327575786285, 1065.674969134406, 1071.3751309292109, 1083.06315037492, 17588.56028599244, 17779.911100444933, 18172.90913545424, 18223.81776182221, 18423.447296581126, 43612.34581481217, 43702.56297436985, 44336.556634434535, 45495.77252113718, 46297.537024629324, 58733.4126658745, 58752.0, 63428.0, 63890.0, 65906.67046664737, 13077.803832942704, 13373.0, 13477.797833032317, 13519.662008449473, 13529.120607160503, 14124.576262712135, 14281.571552853431, 14473.0, 14587.19770412595, 14643.780343295071, 12639.1784534005, 12743.171693839871, 12806.67983300425, 12825.615231543068, 12852.807207892076, 12429.689257768629, 12665.936670316589, 12714.0, 12780.552680656132, 12889.162204456681, 12713.55502557406, 13085.542006029744, 13151.868481315065, 13195.538156164488, 13203.273819939996, 13160.934195328962, 13425.26161061112, 13465.326733663082, 13507.729845403233, 13696.52062177819, 12380.133390662568, 12428.875711243134, 12653.683657908257, 13075.019373546831, 13372.456299080557], "value": 9841.084478275314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.7904493370927, 1544.451719639528, 1643.95327816868, 1765.4527096600048, 1819.1722766141406, 9363.71908842735, 10126.34178778379, 10130.69607911762, 10132.240081993843, 10146.441945692985, 8177.713780017701, 8387.874181887277, 9083.72748817535, 9311.441313521176, 9896.455694936776, 2484.2919767866115, 2509.1719732488236, 2509.573372526675, 2513.5726926422462, 2517.7608127227886, 2439.377958620549, 2558.974410255892, 2560.1295559509763, 2561.065211197909, 2564.4742827720365, 2499.3126890105186, 2511.824172307939, 2516.6979962404444, 2525.5580273452165, 2529.911453099142, 2480.4295012147127, 2492.1277552856445, 2495.962560561593, 2504.0734928076545, 2511.3972646564785, 2461.0155937624913, 2464.3099932019063, 2470.60470324749, 2472.344828620417, 2511.3721569607596, 9715.07706767856, 9745.0, 9759.707208783748, 9868.21054315653, 9885.950570247174, 5493.313335833021, 5629.9437005629825, 5632.0, 5646.124850648156, 5653.9151912721345, 3573.6069032406413, 3622.510961020255, 3685.5024571682748, 3692.040069581901, 3711.350513660099, 1125.4035361258545, 1160.1067178272683, 1181.9586314479038, 1186.9287842729464, 1187.139323990109, 14027.85972140286, 14087.15477071379, 14120.152790832582, 14155.929220353832, 14175.22036288014, 4963.503649635008, 5098.770555325019, 5228.0, 5232.0, 5276.683398996072, 11673.182877198515, 11677.941610292064, 11685.532578696926, 11699.06407487399, 11699.707507312385, 5644.520215781622, 5644.8871022579315, 5684.857878553069, 5693.259876216065, 5695.231143795535, 4444.333349997526, 4571.9085618287445, 5267.0, 5273.736313184326, 5293.047251494768, 4140.763987446805, 4188.958110418917, 4359.367891655692, 4381.868543943623, 4404.075144219686, 1387.8473367929519, 1395.699924516234, 1396.748585254664, 1398.398688563907, 1420.7229590230013, 805.3154818404427, 807.4105902691068, 883.9481017589048, 952.5332587032304, 998.9900100999042, 5887.61730487517, 6010.248718910137, 6028.668423236764, 6162.26052873658, 6184.783532576428, 463.87011636741767, 486.1104179351778, 490.57320131485835, 491.37595254027184, 495.54409942852664, 3899.629535194152, 3917.5103112110987, 4039.6566291864933, 4078.5921407858978, 4083.305838007546, 10946.835797463045, 11391.373474458984, 11440.942795286137, 11470.655880323604, 11494.770104597861, 11374.203805733685, 11379.886201138048, 11381.544738210545, 11396.373199473943, 11401.771964560663, 8517.701880434155, 8654.567271636393, 8692.304615630734, 8797.076306987801, 8819.073997230327, 5660.603757736919, 5801.8549536261935, 5860.238169037998, 5909.734061967136, 5972.820815375546, 10528.684139475828, 10541.57833686644, 10552.894471055191, 10616.89383106159, 10648.0, 10455.634052808115, 10556.472176391304, 10767.0, 10795.0, 10955.671329860117, 6871.656417179021, 6980.267071957372, 6982.68577913985, 8050.074241462073, 8113.18868113184, 8225.424220304521, 8705.782355440911, 8994.190522852974, 9210.539473026456, 9582.329236953346, 8342.958285208779, 8513.31893448535, 8541.914580854113, 8580.914190858011, 8782.736517904474, 8279.461834980708, 8281.420300578902, 8308.626111825104, 8312.625931832961, 8331.583420829056, 7122.821929451805, 7247.057882475456, 7315.122185337581, 7489.063867016623, 7613.733519326797, 5011.348524691767, 5199.844004679866, 5238.109521381451, 5262.131748261494, 5417.295751552273, 5309.787608495772, 5350.197470379474, 5455.018096742624, 5506.115689287088, 5548.528375088163], "value": 4817.172464375712, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.5232674256041, 348.6321930363466, 351.30374376659137, 355.6070542051035, 358.70586119382085, 5462.262594549735, 5545.3345598528185, 5586.301712285965, 5595.300587426572, 5620.690862002588, 5516.310461192351, 5524.1989911462915, 5530.115181570949, 5545.473180047899, 5570.582206334521, 4070.3487442009286, 4350.847720329789, 4351.347297905324, 4359.498657654379, 4362.738235705868, 3851.6533511983916, 4126.855560055399, 4268.914621707564, 4287.528371879091, 4304.289792184301, 4243.596858298457, 4261.424707664457, 4263.995921916709, 4267.359896015591, 4280.0, 4045.59544045596, 4145.160565012946, 4275.315949448089, 4280.614744672963, 4287.314029755255, 3666.5233519642543, 3710.703143748494, 3711.2020915503294, 3720.069982504374, 3721.162738383869, 5329.387120481156, 5340.866478338034, 5378.8924221515745, 5384.138537833967, 5389.6496727712965, 4898.24077268024, 4898.24077268024, 4899.583535399495, 4901.705897646153, 4906.411230652345, 5716.228309178249, 5765.048766953448, 5775.5668324875805, 5776.862560852512, 5786.652800831964, 4112.773797441139, 4115.97942010288, 4125.690573206992, 4125.711200215986, 4132.710710250284, 5352.625316227865, 5614.803481878155, 5996.370381109964, 6031.8793624127275, 6036.6076205046975, 4165.937510937339, 4214.241436541452, 4225.809838557272, 4239.745615263094, 4241.448611680524, 4278.828846846153, 4282.48610166782, 4293.3130699088, 4302.806373713128, 4304.332828411601, 4682.4381074271305, 4906.546726636681, 4913.606911447076, 4917.8524644260015, 4919.852404427873, 5789.870975159888, 5819.330776960619, 5834.795782147605, 5851.444112809277, 5860.501857342174, 1483.6439254579038, 1502.1437780465249, 1505.6762795998693, 1509.607502049475, 1512.3421311729212, 913.4291068082448, 948.3409030723581, 948.62055177928, 954.6944977607238, 961.7162936933621, 4910.337104490848, 4913.582345500601, 4919.581835544019, 4920.704757714549, 4973.8010479581135, 5193.532582067632, 5196.558292545176, 5214.0, 5215.739213039333, 5223.485189295062, 4687.695299805502, 4779.761011949388, 4976.233437129839, 4976.278439626234, 4992.825251116194, 2748.7800975921878, 2761.895241903255, 2766.640336756209, 2788.9302767430977, 2792.7905407094145, 5308.823382413269, 5318.946810531923, 5356.491133342326, 5356.84827762033, 5384.542313903284, 5313.521783039545, 5321.73391330432, 5635.239242702265, 6099.420555047263, 6101.755929762849, 6174.44430001302, 6182.505399568024, 6186.762647470524, 6193.287771906198, 6193.597416168025, 5720.771169153188, 5961.2846458425265, 5997.490213331916, 6021.879562408815, 6037.396260374103, 5917.224832550304, 5957.4042595740075, 5958.76164953397, 5963.850903727268, 5965.940340596539, 5951.642901425928, 6018.578699490994, 6042.697865106639, 6051.606645568024, 6188.421952402108, 4089.5910408958866, 4118.0027686572585, 4244.1723863846255, 4284.55012223712, 4302.075053863435, 5787.826365209051, 5891.705414729332, 5943.077568804568, 5971.970140149271, 5974.2233509643465, 5785.132230165341, 5795.797147099831, 5818.359980402068, 5835.824925252249, 5851.473367396955, 5820.0, 5821.650700957956, 5835.095560188178, 5842.591018628656, 5844.678542680023, 5545.500904918576, 5725.828225153252, 5730.541556675373, 5765.942340576708, 5784.21913041734, 5759.308882934075, 5787.276590426197, 5787.450192231731, 5820.796272130455, 5826.73779679907, 5522.447755224445, 5684.658920464785, 5696.373398926197, 5709.0, 5722.427757224411], "value": 4320.707097726805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.160947785869, 805.3154818404357, 842.1957031035362, 849.6219182463803, 898.1871406377231, 15819.604509887244, 16465.67068658626, 16524.339026438927, 16539.17304134793, 16547.172641367943, 16040.83959160411, 16189.0, 16221.513354599352, 16426.917865410687, 16439.671206575862, 726.9781906542812, 727.730739626339, 867.9262262707678, 870.1559487297311, 873.026575368466, 877.6182360673096, 882.0209567380202, 883.3154305413292, 884.5621417398397, 884.6594061286387, 916.4730280088976, 918.4581097152678, 921.5944984206961, 922.9400088994194, 923.866039424283, 760.6310939194486, 783.2950344689768, 875.3216257400502, 893.6470094312775, 920.7237828651378, 657.8815813153631, 658.1016911915249, 658.4304576541305, 658.4995403493332, 659.1134923527848, 15977.281022354022, 16013.439529616408, 16027.0, 16031.83968160315, 16057.277422516016, 14773.704525909528, 14903.403863845433, 14917.701645967129, 15058.247087645683, 15246.161461119635, 13715.382807773674, 13867.58084424838, 13872.93063534686, 13978.370973306226, 14372.065815721944, 4723.102610503994, 4766.880827979294, 4805.687630304019, 4911.386076740408, 4916.926246106311, 82162.0, 82669.0, 83271.58364208201, 88316.11683883208, 89866.5506672469, 11185.608503702331, 11577.479013444416, 11596.0, 11623.59317423886, 11725.120615953667, 22776.430589235402, 22956.65565016526, 22963.655545166836, 23009.309720708407, 23017.0, 8702.30381569473, 8990.415622984485, 9141.131592498701, 9214.861777073349, 9365.250779937589, 5108.693478391308, 5275.076861549213, 5306.602004849652, 5320.627556071116, 5372.059889519319, 3849.172427928009, 3949.308870947573, 3990.06233535119, 3994.820233089518, 4003.179348233605, 647.970841312142, 672.37133280383, 676.3980057748607, 677.7153595489906, 690.4545409126733, 741.5550669598198, 793.1949071692237, 802.879568064795, 806.7660378490173, 812.1350761439048, 10143.695689129338, 10983.505742241618, 10986.505607247693, 11619.76760464786, 11636.185467017396, 756.7313603670727, 762.6491813765618, 767.5126294802841, 770.2913319745848, 857.9785505362291, 5035.77339019745, 5167.354080739908, 5172.327597412313, 5213.296205012351, 5305.602079844027, 76878.0, 114568.42715786368, 116548.0, 117441.0, 117806.0, 98906.5054674722, 99936.5003174994, 100701.49649251706, 101357.0, 103531.4823425878, 66599.66700166468, 86206.56896715476, 89573.0, 90216.548917255, 90546.0, 9465.716028519155, 9545.0, 9696.17582505495, 9721.465319407218, 9839.950800245953, 39003.80498097491, 42336.576634234494, 46684.066318674115, 53287.0, 53972.99999999843, 76422.61788691022, 76748.23251767411, 77077.0, 78912.21087789278, 80095.59952200201, 13502.662433439242, 13678.658033549242, 13764.862351376358, 13776.448942042207, 13799.448022079005, 13175.538856140372, 13757.656058598615, 14788.48240311627, 14851.628709282355, 14911.478098266509, 10962.671119866416, 13329.133606315558, 13868.791968120284, 14008.929955350159, 14904.701905962036, 12558.63592365548, 12609.558665446666, 12828.230306181658, 12869.742605147658, 13148.868511314764, 13390.665233369244, 13424.261665608095, 13473.730525389634, 13570.457181712622, 14110.717785644434, 10918.454077296263, 10966.835497467384, 11218.270812397168, 11727.530898764295, 12102.75794484123, 10759.515821787882, 10768.061748885277, 11569.942150289195, 11983.460744266704, 12130.878691212974], "value": 7957.488879085107, "unit": "score"}], "name": "dromaeo_css", "value": 7608.560296355696, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 109.0, 110.0, 111.0, 109.0, 110.0, 110.0, 110.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 119.0, 150.0, 118.0, 120.0, 122.0, 116.0, 149.0, 118.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 162.0, 161.0, 163.0, 159.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 108.0, 76.0, 76.0, 75.0, 86.0, 105.0, 75.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 105.0, 81.0, 81.0, 81.0, 102.0, 80.0, 81.0, 111.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 118.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, 146.0, 145.0, 145.0, 145.0, 146.0, 144.0, 145.0, 146.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 51.0, 49.0, 51.0, 49.0, 51.0, 51.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 57.0, 57.0, 58.0, 58.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 81.0, 72.0, 73.0, 74.0, 74.0, 72.0, 81.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 122.0, 122.0, 121.0, 119.0, 117.0, 125.0, 131.0, 159.0, 132.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 154.0, 156.0, 154.0, 155.0, 159.0, 157.0, 152.0, 155.0, 156.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 66.0, 62.0, 62.0, 64.0, 62.0, 61.0, 61.0, 61.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1454.1999999999996, "alertThreshold": 2.0}]} 08:50:45 INFO - Return code: 0 08:50:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:50:45 INFO - # TBPL SUCCESS # 08:50:45 INFO - Running post-action listener: _resource_record_post_action 08:50:45 INFO - [mozharness: 2016-09-24 15:50:45.244534Z] Finished run-tests step (success) 08:50:45 INFO - Running post-run listener: _resource_record_post_run 08:50:45 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7876608; Write bytes: 377790464; Read time: 2032; Write time: 377452 08:50:45 INFO - TinderboxPrint: CPU usage
13.0% 08:50:45 INFO - TinderboxPrint: I/O read bytes / time
7,876,608 / 2,032 08:50:45 INFO - TinderboxPrint: I/O write bytes / time
377,790,464 / 377,452 08:50:45 INFO - TinderboxPrint: CPU idle
8,271.8 (87.0%) 08:50:45 INFO - TinderboxPrint: CPU user
1,185.9 (12.5%) 08:50:45 INFO - TinderboxPrint: Swap in / out
0 / 0 08:50:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1224704; Read time: 0; Write time: 40 08:50:45 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 5672960; Write bytes: 376565760; Read time: 1892; Write time: 377412 08:50:45 INFO - Running post-run listener: _upload_blobber_files 08:50:45 INFO - Blob upload gear active. 08:50:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:50:45 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 08:50:45 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'] 08:50:45 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 08:50:46 INFO - (blobuploader) - INFO - Open directory for files ... 08:50:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:50:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:50 INFO - (blobuploader) - INFO - Done attempting. 08:50:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:50:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:50:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:51 INFO - (blobuploader) - INFO - Done attempting. 08:50:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:50:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:50:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:52 INFO - (blobuploader) - INFO - Done attempting. 08:50:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:50:52 INFO - Return code: 0 08:50:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:50:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:50:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/583ff867589123fce2cd2ae02c2ffbf9b6b65cb3d8fb5879ba3c6a4cdf94eeb7950980efe4b367e775e174f7564333c9fc8fefa17bed067abc56809310dcb443", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b315a316ad932ea450d32975f875f65aeb17c76c04244b688dbbe42b0fb567b635daae44d75685d319b10cd4e6ba2828aedd294bb5ad32574b99a45e4fbfdb4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ba2024fa19804c0e9796b38e33b428ab46c4c9c24824456908295d5763f707f5f0e58542b9aa6b60be3ff8f7e6656f4b5aaa17610e9c4dd2da928db9369603c"} 08:50:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:50:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:50:52 INFO - Contents: 08:50:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/583ff867589123fce2cd2ae02c2ffbf9b6b65cb3d8fb5879ba3c6a4cdf94eeb7950980efe4b367e775e174f7564333c9fc8fefa17bed067abc56809310dcb443", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b315a316ad932ea450d32975f875f65aeb17c76c04244b688dbbe42b0fb567b635daae44d75685d319b10cd4e6ba2828aedd294bb5ad32574b99a45e4fbfdb4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ba2024fa19804c0e9796b38e33b428ab46c4c9c24824456908295d5763f707f5f0e58542b9aa6b60be3ff8f7e6656f4b5aaa17610e9c4dd2da928db9369603c"} 08:50:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:50:52 INFO - Copying logs to upload dir... 08:50:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:50:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.037898 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-24 08:50:52.487099) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-24 08:50:52.491040) ========= 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=3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373 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/583ff867589123fce2cd2ae02c2ffbf9b6b65cb3d8fb5879ba3c6a4cdf94eeb7950980efe4b367e775e174f7564333c9fc8fefa17bed067abc56809310dcb443", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b315a316ad932ea450d32975f875f65aeb17c76c04244b688dbbe42b0fb567b635daae44d75685d319b10cd4e6ba2828aedd294bb5ad32574b99a45e4fbfdb4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ba2024fa19804c0e9796b38e33b428ab46c4c9c24824456908295d5763f707f5f0e58542b9aa6b60be3ff8f7e6656f4b5aaa17610e9c4dd2da928db9369603c"} build_url:https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010322 build_url: 'https://queue.taskcluster.net/v1/task/LHNNWEoCRvKjSzg9xXlE0Q/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/583ff867589123fce2cd2ae02c2ffbf9b6b65cb3d8fb5879ba3c6a4cdf94eeb7950980efe4b367e775e174f7564333c9fc8fefa17bed067abc56809310dcb443", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b315a316ad932ea450d32975f875f65aeb17c76c04244b688dbbe42b0fb567b635daae44d75685d319b10cd4e6ba2828aedd294bb5ad32574b99a45e4fbfdb4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ba2024fa19804c0e9796b38e33b428ab46c4c9c24824456908295d5763f707f5f0e58542b9aa6b60be3ff8f7e6656f4b5aaa17610e9c4dd2da928db9369603c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-24 08:50:52.517980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:50:52.518278) ========= 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=3a3620fb28aea1604ab609ef000001d2-1474729730.924226-1159133373 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004383 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:50:52.600220) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-24 08:50:52.600516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-24 08:50:52.600803) ========= ========= Total master_lag: 0.13 =========