builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1478796538.99 results: success (0) buildid: 20161110061021 builduid: 60274563655d421b88a0fed83827f706 revision: b5b6afc2af1d1b7702274b0be1cad9f062ba54a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:58.988492) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:58.988859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:58.989130) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.003654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.003927) ========= 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=00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-10 08:48:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.0M=0s 2016-11-10 08:48:59 (69.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.071058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.088505) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.088833) ========= 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=00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016932 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.143085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.143423) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b5b6afc2af1d1b7702274b0be1cad9f062ba54a4 --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 b5b6afc2af1d1b7702274b0be1cad9f062ba54a4 --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=00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-10 08:48:59,187 truncating revision to first 12 chars 2016-11-10 08:48:59,187 Setting DEBUG logging. 2016-11-10 08:48:59,187 attempt 1/10 2016-11-10 08:48:59,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b5b6afc2af1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-10 08:48:59,539 unpacking tar archive at: mozilla-aurora-b5b6afc2af1d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.699522 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.855803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.856085) ========= script_repo_revision: b5b6afc2af1d1b7702274b0be1cad9f062ba54a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.856455) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.856720) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 08:48:59.865776) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-11-10 08:48:59.866080) ========= /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=00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:49:00 INFO - MultiFileLogger online at 20161110 08:49:00 in /builds/slave/test 08:49:00 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:49:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:00 INFO - {'append_to_log': False, 08:49:00 INFO - 'base_work_dir': '/builds/slave/test', 08:49:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:49:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:00 INFO - 'branch': 'Mozilla-Aurora', 08:49:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:00 INFO - 'config_files': ('talos/linux_config.py',), 08:49:00 INFO - 'default_actions': ('clobber', 08:49:00 INFO - 'read-buildbot-config', 08:49:00 INFO - 'download-and-extract', 08:49:00 INFO - 'populate-webroot', 08:49:00 INFO - 'create-virtualenv', 08:49:00 INFO - 'install', 08:49:00 INFO - 'run-tests'), 08:49:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:00 INFO - 'download_minidump_stackwalk': True, 08:49:00 INFO - 'download_symbols': 'ondemand', 08:49:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:00 INFO - 'installer_path': 'installer.exe', 08:49:00 INFO - 'log_level': 'info', 08:49:00 INFO - 'log_name': 'talos', 08:49:00 INFO - 'log_to_console': True, 08:49:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:49:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:49:00 INFO - 'opt_config_files': (), 08:49:00 INFO - 'pip_index': False, 08:49:00 INFO - 'sps_profile': False, 08:49:00 INFO - 'sps_profile_interval': 0, 08:49:00 INFO - 'suite': 'dromaeojs', 08:49:00 INFO - 'system_bits': '32', 08:49:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:00 INFO - 'title': 'talos-linux64-ix-016', 08:49:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:00 INFO - 'use_talos_json': True, 08:49:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:00 INFO - 'work_dir': 'build'} 08:49:00 INFO - [mozharness: 2016-11-10 16:49:00.006927Z] Running clobber step. 08:49:00 INFO - Running pre-action listener: _resource_record_pre_action 08:49:00 INFO - Running main action method: clobber 08:49:00 INFO - rmtree: /builds/slave/test/build 08:49:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:00 INFO - Running post-action listener: _resource_record_post_action 08:49:00 INFO - [mozharness: 2016-11-10 16:49:00.470486Z] Finished clobber step (success) 08:49:00 INFO - [mozharness: 2016-11-10 16:49:00.470586Z] Running read-buildbot-config step. 08:49:00 INFO - Running pre-action listener: _resource_record_pre_action 08:49:00 INFO - Running main action method: read_buildbot_config 08:49:00 INFO - Using buildbot properties: 08:49:00 INFO - { 08:49:00 INFO - "project": "", 08:49:00 INFO - "product": "firefox", 08:49:00 INFO - "script_repo_revision": "production", 08:49:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:49:00 INFO - "repository": "", 08:49:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:49:00 INFO - "buildid": "20161110061021", 08:49:00 INFO - "pgo_build": "True", 08:49:00 INFO - "basedir": "/builds/slave/test", 08:49:00 INFO - "buildnumber": 879, 08:49:00 INFO - "slavename": "talos-linux64-ix-016", 08:49:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:49:00 INFO - "platform": "ubuntu64_hw", 08:49:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:49:00 INFO - "branch": "mozilla-aurora", 08:49:00 INFO - "repo_path": "releases/mozilla-aurora", 08:49:00 INFO - "revision": "b5b6afc2af1d1b7702274b0be1cad9f062ba54a4", 08:49:00 INFO - "stage_platform": "linux64-pgo", 08:49:00 INFO - "builduid": "60274563655d421b88a0fed83827f706", 08:49:00 INFO - "slavebuilddir": "test" 08:49:00 INFO - } 08:49:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 08:49:00 INFO - Running post-action listener: _resource_record_post_action 08:49:00 INFO - [mozharness: 2016-11-10 16:49:00.473057Z] Finished read-buildbot-config step (success) 08:49:00 INFO - [mozharness: 2016-11-10 16:49:00.473146Z] Running download-and-extract step. 08:49:00 INFO - Running pre-action listener: _resource_record_pre_action 08:49:00 INFO - Running main action method: download_and_extract 08:49:00 INFO - mkdir: /builds/slave/test/build/tests 08:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:00 INFO - trying https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 08:49:00 INFO - Downloading https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/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:49:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/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:49:01 INFO - Downloaded 1361 bytes. 08:49:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 08:49:01 INFO - Using the following test package requirements: 08:49:01 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 08:49:01 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:49:01 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:49:01 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'jsshell-linux-x86_64.zip'], 08:49:01 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:49:01 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 08:49:01 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:49:01 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:49:01 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:49:01 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:49:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:49:01 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:49:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 08:49:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:49:01 INFO - Fetch https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:49:01 INFO - Expected file size: 21897162 08:49:06 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:49:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 08:49:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:49:06 INFO - Fetch https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:49:06 INFO - Expected file size: 21897162 08:49:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 08:49:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:49:11 INFO - Fetch https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 08:49:12 INFO - Expected file size: 14414089 08:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:13 INFO - trying https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:49:13 INFO - Downloading https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/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:49:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/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:49:15 INFO - Downloaded 64038955 bytes. 08:49:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:49:15 INFO - mkdir: /builds/slave/test/properties 08:49:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:15 INFO - Contents: 08:49:15 INFO - build_url:https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:49:16 INFO - Running post-action listener: _resource_record_post_action 08:49:16 INFO - Running post-action listener: set_extra_try_arguments 08:49:16 INFO - [mozharness: 2016-11-10 16:49:16.245766Z] Finished download-and-extract step (success) 08:49:16 INFO - [mozharness: 2016-11-10 16:49:16.245994Z] Running populate-webroot step. 08:49:16 INFO - Running pre-action listener: _resource_record_pre_action 08:49:16 INFO - Running main action method: populate_webroot 08:49:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:16 INFO - '--tppagecycles', 08:49:16 INFO - '7'], 08:49:16 INFO - 'tests': ['tsvgm']}}, 08:49:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['damp', 'tps']}, 08:49:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'tests': ['damp', 'tps']}, 08:49:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['dromaeo_dom']}, 08:49:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['basic_compositor_video']}, 08:49:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['a11yr', 08:49:16 INFO - 'ts_paint', 08:49:16 INFO - 'tpaint', 08:49:16 INFO - 'sessionrestore', 08:49:16 INFO - 'sessionrestore_no_auto_restore', 08:49:16 INFO - 'tabpaint']}, 08:49:16 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:16 INFO - 'ts_paint', 08:49:16 INFO - 'tpaint', 08:49:16 INFO - 'sessionrestore', 08:49:16 INFO - 'sessionrestore_no_auto_restore', 08:49:16 INFO - 'tabpaint']}, 08:49:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:16 INFO - 'ts_paint', 08:49:16 INFO - 'tpaint', 08:49:16 INFO - 'sessionrestore', 08:49:16 INFO - 'sessionrestore_no_auto_restore', 08:49:16 INFO - 'tabpaint']}, 08:49:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:16 INFO - 'ts_paint', 08:49:16 INFO - 'tpaint', 08:49:16 INFO - 'sessionrestore', 08:49:16 INFO - 'sessionrestore_no_auto_restore', 08:49:16 INFO - 'tabpaint']}, 08:49:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['a11yr', 08:49:16 INFO - 'ts_paint', 08:49:16 INFO - 'tpaint', 08:49:16 INFO - 'sessionrestore', 08:49:16 INFO - 'sessionrestore_no_auto_restore', 08:49:16 INFO - 'tabpaint']}, 08:49:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['a11yr', 08:49:16 INFO - 'ts_paint', 08:49:16 INFO - 'tpaint', 08:49:16 INFO - 'sessionrestore', 08:49:16 INFO - 'sessionrestore_no_auto_restore', 08:49:16 INFO - 'tabpaint']}, 08:49:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['tsvgx', 08:49:16 INFO - 'tsvgr_opacity', 08:49:16 INFO - 'tart', 08:49:16 INFO - 'tscrollx', 08:49:16 INFO - 'cart']}, 08:49:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:16 INFO - 'tsvgr_opacity', 08:49:16 INFO - 'tart', 08:49:16 INFO - 'tscrollx', 08:49:16 INFO - 'cart']}, 08:49:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'talos_options': ['--disable-e10s'], 08:49:16 INFO - 'tests': ['tp5o']}, 08:49:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'tests': ['tp5o']}, 08:49:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'talos_options': ['--disable-e10s', 08:49:16 INFO - '--xperf_path', 08:49:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:16 INFO - 'tests': ['tp5n']}, 08:49:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:16 INFO - 'talos_options': ['--xperf_path', 08:49:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:16 INFO - 'tests': ['tp5n']}}, 08:49:16 INFO - 'talos.zip': {'path': '', 08:49:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:16 INFO - Running post-action listener: _resource_record_post_action 08:49:16 INFO - [mozharness: 2016-11-10 16:49:16.264048Z] Finished populate-webroot step (success) 08:49:16 INFO - [mozharness: 2016-11-10 16:49:16.264137Z] Running create-virtualenv step. 08:49:16 INFO - Running pre-action listener: _resource_record_pre_action 08:49:16 INFO - Running main action method: create_virtualenv 08:49:16 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:16 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:49:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:16 INFO - Using real prefix '/usr' 08:49:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:18 INFO - Installing pip.................done. 08:49:18 INFO - Return code: 0 08:49:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:49:18 INFO - Using env: {'DISPLAY': ':0', 08:49:18 INFO - 'HOME': '/home/cltbld', 08:49:18 INFO - 'LANG': 'en_US.UTF-8', 08:49:18 INFO - 'LANGUAGE': 'en_US:en', 08:49:18 INFO - 'LOGNAME': 'cltbld', 08:49:18 INFO - 'MAIL': '/var/mail/cltbld', 08:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:18 INFO - 'MOZ_NO_REMOTE': '1', 08:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:18 INFO - 'NO_EM_RESTART': '1', 08:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:18 INFO - 'PWD': '/builds/slave/test', 08:49:18 INFO - 'SHELL': '/bin/bash', 08:49:18 INFO - 'SHLVL': '1', 08:49:18 INFO - 'TERM': 'linux', 08:49:18 INFO - 'TMOUT': '86400', 08:49:18 INFO - 'USER': 'cltbld', 08:49:18 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:18 INFO - '_': '/tools/buildbot/bin/python'} 08:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:19 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:49:19 INFO - Cleaning up... 08:49:19 INFO - Return code: 0 08:49:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49:19 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:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:19 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:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:19 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:19 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:49:19 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:49:19 INFO - Using env: {'DISPLAY': ':0', 08:49:19 INFO - 'HOME': '/home/cltbld', 08:49:19 INFO - 'LANG': 'en_US.UTF-8', 08:49:19 INFO - 'LANGUAGE': 'en_US:en', 08:49:19 INFO - 'LOGNAME': 'cltbld', 08:49:19 INFO - 'MAIL': '/var/mail/cltbld', 08:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:19 INFO - 'MOZ_NO_REMOTE': '1', 08:49:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:19 INFO - 'NO_EM_RESTART': '1', 08:49:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:19 INFO - 'PWD': '/builds/slave/test', 08:49:19 INFO - 'SHELL': '/bin/bash', 08:49:19 INFO - 'SHLVL': '1', 08:49:19 INFO - 'TERM': 'linux', 08:49:19 INFO - 'TMOUT': '86400', 08:49:19 INFO - 'USER': 'cltbld', 08:49:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:19 INFO - '_': '/tools/buildbot/bin/python'} 08:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:19 INFO - Downloading/unpacking psutil>=3.1.1 08:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:21 INFO - Installing collected packages: psutil 08:49:21 INFO - Running setup.py install for psutil 08:49:21 INFO - building 'psutil._psutil_linux' extension 08:49:21 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:49:21 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:49:21 INFO - building 'psutil._psutil_posix' extension 08:49:21 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:49:21 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:49:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:21 INFO - Successfully installed psutil 08:49:21 INFO - Cleaning up... 08:49:21 INFO - Return code: 0 08:49:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:49:21 INFO - Using env: {'DISPLAY': ':0', 08:49:21 INFO - 'HOME': '/home/cltbld', 08:49:21 INFO - 'LANG': 'en_US.UTF-8', 08:49:21 INFO - 'LANGUAGE': 'en_US:en', 08:49:21 INFO - 'LOGNAME': 'cltbld', 08:49:21 INFO - 'MAIL': '/var/mail/cltbld', 08:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:21 INFO - 'MOZ_NO_REMOTE': '1', 08:49:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:21 INFO - 'NO_EM_RESTART': '1', 08:49:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:21 INFO - 'PWD': '/builds/slave/test', 08:49:21 INFO - 'SHELL': '/bin/bash', 08:49:21 INFO - 'SHLVL': '1', 08:49:21 INFO - 'TERM': 'linux', 08:49:21 INFO - 'TMOUT': '86400', 08:49:21 INFO - 'USER': 'cltbld', 08:49:21 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:21 INFO - '_': '/tools/buildbot/bin/python'} 08:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:24 INFO - Installing collected packages: mozsystemmonitor 08:49:24 INFO - Running setup.py install for mozsystemmonitor 08:49:24 INFO - Successfully installed mozsystemmonitor 08:49:24 INFO - Cleaning up... 08:49:24 INFO - Return code: 0 08:49:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:24 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:24 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:49:24 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:49:24 INFO - Using env: {'DISPLAY': ':0', 08:49:24 INFO - 'HOME': '/home/cltbld', 08:49:24 INFO - 'LANG': 'en_US.UTF-8', 08:49:24 INFO - 'LANGUAGE': 'en_US:en', 08:49:24 INFO - 'LOGNAME': 'cltbld', 08:49:24 INFO - 'MAIL': '/var/mail/cltbld', 08:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:24 INFO - 'MOZ_NO_REMOTE': '1', 08:49:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:24 INFO - 'NO_EM_RESTART': '1', 08:49:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:24 INFO - 'PWD': '/builds/slave/test', 08:49:24 INFO - 'SHELL': '/bin/bash', 08:49:24 INFO - 'SHLVL': '1', 08:49:24 INFO - 'TERM': 'linux', 08:49:24 INFO - 'TMOUT': '86400', 08:49:24 INFO - 'USER': 'cltbld', 08:49:24 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:24 INFO - '_': '/tools/buildbot/bin/python'} 08:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:24 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:27 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:49:27 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:49:27 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:49:27 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:49:27 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:49:27 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:49:27 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:27 INFO - warning: no files found matching '*.txt' 08:49:27 INFO - no previously-included directories found matching 'build' 08:49:27 INFO - no previously-included directories found matching 'dist' 08:49:27 INFO - no previously-included directories found matching '.git*' 08:49:27 INFO - Installing collected packages: jsonschema, functools32 08:49:27 INFO - Running setup.py install for functools32 08:49:27 INFO - warning: no files found matching '*.txt' 08:49:27 INFO - no previously-included directories found matching 'build' 08:49:27 INFO - no previously-included directories found matching 'dist' 08:49:27 INFO - no previously-included directories found matching '.git*' 08:49:27 INFO - Successfully installed jsonschema functools32 08:49:27 INFO - Cleaning up... 08:49:27 INFO - Return code: 0 08:49:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49: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:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:27 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:27 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:49:27 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:49:27 INFO - Using env: {'DISPLAY': ':0', 08:49:27 INFO - 'HOME': '/home/cltbld', 08:49:27 INFO - 'LANG': 'en_US.UTF-8', 08:49:27 INFO - 'LANGUAGE': 'en_US:en', 08:49:27 INFO - 'LOGNAME': 'cltbld', 08:49:27 INFO - 'MAIL': '/var/mail/cltbld', 08:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:27 INFO - 'MOZ_NO_REMOTE': '1', 08:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:27 INFO - 'NO_EM_RESTART': '1', 08:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:27 INFO - 'PWD': '/builds/slave/test', 08:49:27 INFO - 'SHELL': '/bin/bash', 08:49:27 INFO - 'SHLVL': '1', 08:49:27 INFO - 'TERM': 'linux', 08:49:27 INFO - 'TMOUT': '86400', 08:49:27 INFO - 'USER': 'cltbld', 08:49:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:27 INFO - '_': '/tools/buildbot/bin/python'} 08:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:28 INFO - Cleaning up... 08:49:28 INFO - Return code: 0 08:49:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:28 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:28 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:49:28 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:49:28 INFO - Using env: {'DISPLAY': ':0', 08:49:28 INFO - 'HOME': '/home/cltbld', 08:49:28 INFO - 'LANG': 'en_US.UTF-8', 08:49:28 INFO - 'LANGUAGE': 'en_US:en', 08:49:28 INFO - 'LOGNAME': 'cltbld', 08:49:28 INFO - 'MAIL': '/var/mail/cltbld', 08:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:28 INFO - 'MOZ_NO_REMOTE': '1', 08:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:28 INFO - 'NO_EM_RESTART': '1', 08:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:28 INFO - 'PWD': '/builds/slave/test', 08:49:28 INFO - 'SHELL': '/bin/bash', 08:49:28 INFO - 'SHLVL': '1', 08:49:28 INFO - 'TERM': 'linux', 08:49:28 INFO - 'TMOUT': '86400', 08:49:28 INFO - 'USER': 'cltbld', 08:49:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:28 INFO - '_': '/tools/buildbot/bin/python'} 08:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:28 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:30 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:30 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:49:30 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:49:30 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:49:30 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:49:30 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:49:30 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:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:30 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:49:30 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:49:30 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:49:30 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:49:30 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:49:30 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:49:30 INFO - Downloading docopt-0.6.1.tar.gz 08:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:30 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:30 INFO - Running setup.py install for blobuploader 08:49:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:31 INFO - Running setup.py install for requests 08:49:31 INFO - Running setup.py install for docopt 08:49:31 INFO - Successfully installed blobuploader requests docopt 08:49:31 INFO - Cleaning up... 08:49:31 INFO - Return code: 0 08:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:31 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:31 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:49:31 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:49:31 INFO - Using env: {'DISPLAY': ':0', 08:49:31 INFO - 'HOME': '/home/cltbld', 08:49:31 INFO - 'LANG': 'en_US.UTF-8', 08:49:31 INFO - 'LANGUAGE': 'en_US:en', 08:49:31 INFO - 'LOGNAME': 'cltbld', 08:49:31 INFO - 'MAIL': '/var/mail/cltbld', 08:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:31 INFO - 'MOZ_NO_REMOTE': '1', 08:49:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:31 INFO - 'NO_EM_RESTART': '1', 08:49:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:31 INFO - 'PWD': '/builds/slave/test', 08:49:31 INFO - 'SHELL': '/bin/bash', 08:49:31 INFO - 'SHLVL': '1', 08:49:31 INFO - 'TERM': 'linux', 08:49:31 INFO - 'TMOUT': '86400', 08:49:31 INFO - 'USER': 'cltbld', 08:49:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:31 INFO - '_': '/tools/buildbot/bin/python'} 08:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:31 INFO - Running setup.py (path:/tmp/pip-_YmzGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:31 INFO - Running setup.py (path:/tmp/pip-5_XIzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:31 INFO - Running setup.py (path:/tmp/pip-FeT_73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:32 INFO - Running setup.py (path:/tmp/pip-BV8_Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:32 INFO - Running setup.py (path:/tmp/pip-qx7_jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:32 INFO - Running setup.py (path:/tmp/pip-wLvP6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:32 INFO - Running setup.py (path:/tmp/pip-jPxOQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:32 INFO - Running setup.py (path:/tmp/pip-dhtBbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:32 INFO - Running setup.py (path:/tmp/pip-VaFKBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:32 INFO - Running setup.py (path:/tmp/pip-PYHrza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:32 INFO - Running setup.py (path:/tmp/pip-SJ_ufv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:32 INFO - Running setup.py (path:/tmp/pip-a3HWqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:32 INFO - Running setup.py (path:/tmp/pip-LTCCpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:32 INFO - Running setup.py (path:/tmp/pip-6HRpzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:32 INFO - Running setup.py (path:/tmp/pip-DlXTDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:32 INFO - Running setup.py (path:/tmp/pip-8aZWUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:32 INFO - Running setup.py (path:/tmp/pip-Q_4dXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:33 INFO - Running setup.py install for manifestparser 08:49:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozcrash 08:49:33 INFO - Running setup.py install for mozdebug 08:49:33 INFO - Running setup.py install for mozdevice 08:49:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozfile 08:49:33 INFO - Running setup.py install for mozhttpd 08:49:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozinfo 08:49:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozInstall 08:49:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozleak 08:49:33 INFO - Running setup.py install for mozlog 08:49:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for moznetwork 08:49:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozprocess 08:49:34 INFO - Running setup.py install for mozprofile 08:49:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozrunner 08:49:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozscreenshot 08:49:34 INFO - Running setup.py install for moztest 08:49:34 INFO - Running setup.py install for mozversion 08:49:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:34 INFO - Cleaning up... 08:49:34 INFO - Return code: 0 08:49:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:34 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:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:34 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:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:34 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:34 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:49:34 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:49:34 INFO - Using env: {'DISPLAY': ':0', 08:49:34 INFO - 'HOME': '/home/cltbld', 08:49:34 INFO - 'LANG': 'en_US.UTF-8', 08:49:34 INFO - 'LANGUAGE': 'en_US:en', 08:49:34 INFO - 'LOGNAME': 'cltbld', 08:49:34 INFO - 'MAIL': '/var/mail/cltbld', 08:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:34 INFO - 'MOZ_NO_REMOTE': '1', 08:49:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:34 INFO - 'NO_EM_RESTART': '1', 08:49:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:34 INFO - 'PWD': '/builds/slave/test', 08:49:34 INFO - 'SHELL': '/bin/bash', 08:49:34 INFO - 'SHLVL': '1', 08:49:34 INFO - 'TERM': 'linux', 08:49:34 INFO - 'TMOUT': '86400', 08:49:34 INFO - 'USER': 'cltbld', 08:49:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:34 INFO - '_': '/tools/buildbot/bin/python'} 08:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:35 INFO - Running setup.py (path:/tmp/pip-ub5q3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:35 INFO - Running setup.py (path:/tmp/pip-nrAScB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:35 INFO - Running setup.py (path:/tmp/pip-3kEK6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:35 INFO - Running setup.py (path:/tmp/pip-_haS17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:35 INFO - Running setup.py (path:/tmp/pip-upQvc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:35 INFO - Running setup.py (path:/tmp/pip-2jryMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:35 INFO - Running setup.py (path:/tmp/pip-S3LLhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:35 INFO - Running setup.py (path:/tmp/pip-__3Hwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:35 INFO - Running setup.py (path:/tmp/pip-N5TNF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:35 INFO - Running setup.py (path:/tmp/pip-1CLFG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:35 INFO - Running setup.py (path:/tmp/pip-FF5Wgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:35 INFO - Running setup.py (path:/tmp/pip-2HZcUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:35 INFO - Running setup.py (path:/tmp/pip-ZyJfG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:35 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:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:35 INFO - Running setup.py (path:/tmp/pip-rqfQVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:36 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:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:36 INFO - Running setup.py (path:/tmp/pip-7yHHwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:36 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:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:36 INFO - Running setup.py (path:/tmp/pip-W3I1h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:36 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:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:36 INFO - Running setup.py (path:/tmp/pip-hwaWsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49: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:49: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:49: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:49: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:49: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:49: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:49:38 INFO - Downloading blessings-1.6.tar.gz 08:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:38 INFO - Installing collected packages: blessings 08:49:38 INFO - Running setup.py install for blessings 08:49:38 INFO - Successfully installed blessings 08:49:38 INFO - Cleaning up... 08:49:38 INFO - Return code: 0 08:49:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:38 INFO - Reading from file tmpfile_stdout 08:49:38 INFO - Current package versions: 08:49:38 INFO - argparse == 1.2.1 08:49:38 INFO - blessings == 1.6 08:49:38 INFO - blobuploader == 1.2.4 08:49:38 INFO - docopt == 0.6.1 08:49:38 INFO - functools32 == 3.2.3-2 08:49:38 INFO - jsonschema == 2.5.1 08:49:38 INFO - manifestparser == 1.1 08:49:38 INFO - mozInstall == 1.12 08:49:38 INFO - mozcrash == 0.17 08:49:38 INFO - mozdebug == 0.1 08:49:38 INFO - mozdevice == 0.48 08:49:38 INFO - mozfile == 1.2 08:49:38 INFO - mozhttpd == 0.7 08:49:38 INFO - mozinfo == 0.9 08:49:38 INFO - mozleak == 0.1 08:49:38 INFO - mozlog == 3.4 08:49:38 INFO - moznetwork == 0.27 08:49:38 INFO - mozprocess == 0.23 08:49:38 INFO - mozprofile == 0.28 08:49:38 INFO - mozrunner == 6.12 08:49:38 INFO - mozscreenshot == 0.1 08:49:38 INFO - mozsystemmonitor == 0.3 08:49:38 INFO - moztest == 0.7 08:49:38 INFO - mozversion == 1.4 08:49:38 INFO - psutil == 3.1.1 08:49:38 INFO - requests == 1.2.3 08:49:38 INFO - wsgiref == 0.1.2 08:49:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:38 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:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:38 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:49:38 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:49:38 INFO - Using env: {'DISPLAY': ':0', 08:49:38 INFO - 'HOME': '/home/cltbld', 08:49:38 INFO - 'LANG': 'en_US.UTF-8', 08:49:38 INFO - 'LANGUAGE': 'en_US:en', 08:49:38 INFO - 'LOGNAME': 'cltbld', 08:49:38 INFO - 'MAIL': '/var/mail/cltbld', 08:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:38 INFO - 'MOZ_NO_REMOTE': '1', 08:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:38 INFO - 'NO_EM_RESTART': '1', 08:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:38 INFO - 'PWD': '/builds/slave/test', 08:49:38 INFO - 'SHELL': '/bin/bash', 08:49:38 INFO - 'SHLVL': '1', 08:49:38 INFO - 'TERM': 'linux', 08:49:38 INFO - 'TMOUT': '86400', 08:49:38 INFO - 'USER': 'cltbld', 08:49:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:38 INFO - '_': '/tools/buildbot/bin/python'} 08:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 INFO - Cleaning up... 08:49:38 INFO - Return code: 0 08:49:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:38 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:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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 0x7fb67cff1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, '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': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', '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:49:38 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:49:38 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:49:38 INFO - Using env: {'DISPLAY': ':0', 08:49:38 INFO - 'HOME': '/home/cltbld', 08:49:38 INFO - 'LANG': 'en_US.UTF-8', 08:49:38 INFO - 'LANGUAGE': 'en_US:en', 08:49:38 INFO - 'LOGNAME': 'cltbld', 08:49:38 INFO - 'MAIL': '/var/mail/cltbld', 08:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:38 INFO - 'MOZ_NO_REMOTE': '1', 08:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:38 INFO - 'NO_EM_RESTART': '1', 08:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:38 INFO - 'PWD': '/builds/slave/test', 08:49:38 INFO - 'SHELL': '/bin/bash', 08:49:38 INFO - 'SHLVL': '1', 08:49:38 INFO - 'TERM': 'linux', 08:49:38 INFO - 'TMOUT': '86400', 08:49:38 INFO - 'USER': 'cltbld', 08:49:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:38 INFO - '_': '/tools/buildbot/bin/python'} 08:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:39 INFO - Cleaning up... 08:49:39 INFO - Return code: 0 08:49:39 INFO - Running post-action listener: _resource_record_post_action 08:49:39 INFO - Running post-action listener: _start_resource_monitoring 08:49:39 INFO - Starting resource monitoring. 08:49:39 INFO - [mozharness: 2016-11-10 16:49:39.125759Z] Finished create-virtualenv step (success) 08:49:39 INFO - [mozharness: 2016-11-10 16:49:39.126262Z] Running install step. 08:49:39 INFO - Running pre-action listener: _resource_record_pre_action 08:49:39 INFO - Running main action method: install 08:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:39 INFO - Reading from file tmpfile_stdout 08:49:39 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:39 INFO - Reading from file tmpfile_stdout 08:49:39 INFO - Output received: 08:49:39 INFO - Usage: mozinstall [options] installer 08:49:39 INFO - Options: 08:49:39 INFO - -h, --help show this help message and exit 08:49:39 INFO - -d DEST, --destination=DEST 08:49:39 INFO - Directory to install application into. [default: 08:49:39 INFO - "/builds/slave/test"] 08:49:39 INFO - --app=APP Application being installed. [default: firefox] 08:49:39 INFO - mkdir: /builds/slave/test/build/application 08:49:39 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:49:39 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:49:55 INFO - Reading from file tmpfile_stdout 08:49:55 INFO - Output received: 08:49:55 INFO - /builds/slave/test/build/application/firefox/firefox 08:49:55 INFO - Running post-action listener: _resource_record_post_action 08:49:55 INFO - [mozharness: 2016-11-10 16:49:55.898258Z] Finished install step (success) 08:49:55 INFO - [mozharness: 2016-11-10 16:49:55.898504Z] Running run-tests step. 08:49:55 INFO - Running pre-action listener: _resource_record_pre_action 08:49:55 INFO - Running main action method: run_tests 08:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:55 INFO - Python 2.7.3 08:49:55 INFO - Return code: 0 08:49:55 INFO - grabbing minidump binary from tooltool 08:49:55 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:49:55 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 0x2450f60>, '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 0x2451d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24521d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:55 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:49:55 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:49:55 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:49:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:56 INFO - Return code: 0 08:49:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:49:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:49:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:56 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/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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:49:56 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/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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:49:56 INFO - Using env: {'DISPLAY': ':0', 08:49:56 INFO - 'HOME': '/home/cltbld', 08:49:56 INFO - 'LANG': 'en_US.UTF-8', 08:49:56 INFO - 'LANGUAGE': 'en_US:en', 08:49:56 INFO - 'LOGNAME': 'cltbld', 08:49:56 INFO - 'MAIL': '/var/mail/cltbld', 08:49:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:56 INFO - 'MOZ_NO_REMOTE': '1', 08:49:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:56 INFO - 'NO_EM_RESTART': '1', 08:49:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:56 INFO - 'PWD': '/builds/slave/test', 08:49:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:56 INFO - 'SHELL': '/bin/bash', 08:49:56 INFO - 'SHLVL': '1', 08:49:56 INFO - 'TERM': 'linux', 08:49:56 INFO - 'TMOUT': '86400', 08:49:56 INFO - 'USER': 'cltbld', 08:49:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336', 08:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:56 INFO - '_': '/tools/buildbot/bin/python'} 08:49:56 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/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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:49:56 INFO - mozversion application_buildid: 20161110061021 08:49:56 INFO - mozversion application_changeset: b5b6afc2af1d1b7702274b0be1cad9f062ba54a4 08:49:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:49:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:56 INFO - mozversion application_name: Firefox 08:49:56 INFO - mozversion application_remotingname: firefox-dev 08:49:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:49:56 INFO - mozversion application_vendor: Mozilla 08:49:56 INFO - mozversion application_version: 51.0a2 08:49:56 INFO - mozversion platform_buildid: 20161110061021 08:49:56 INFO - mozversion platform_changeset: b5b6afc2af1d1b7702274b0be1cad9f062ba54a4 08:49:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:49:56 INFO - mozversion platform_version: 51.0a2 08:49:56 INFO - using testdate: 1478796596 08:49:56 INFO - actual date: 1478796596 08:49:56 INFO - starting webserver on 'localhost:51411' 08:49:56 INFO - SUITE-START | Running 2 tests 08:49:56 INFO - TEST-START | dromaeo_css 08:49:56 INFO - operating with platform_type : linux_ 08:49:56 INFO - Initialising browser for dromaeo_css test... 08:49:56 INFO - TEST-INFO | started process 6967 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgJPNqG/profile http://localhost:51411/getInfo.html) 08:50:00 INFO - PROCESS | 6967 | __metrics Screen width/height:1600/1200 08:50:00 INFO - PROCESS | 6967 | colorDepth:24 08:50:00 INFO - PROCESS | 6967 | Browser inner width/height: 1024/697 08:50:00 INFO - PROCESS | 6967 | __metrics 08:50:00 INFO - PROCESS | 6967 | 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:50:00 INFO - PROCESS | 6967 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:50:01 INFO - PROCESS | 6967 | console.error: PushService: 08:50:01 INFO - PROCESS | 6967 | stateChangeProcessEnqueue: Error transitioning state 08:50:01 INFO - PROCESS | 6967 | UnknownError 08:50:01 INFO - TEST-INFO | 6967: exit 0 08:50:01 INFO - Browser initialized. 08:50:01 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:01 INFO - TEST-INFO | started process 7061 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgJPNqG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:13 INFO - PROCESS | 7061 | RSS: Main: 144936960 08:50:13 INFO - PROCESS | 7061 | 08:51:04 INFO - PROCESS | 7061 | 1478796664957 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:53:14 INFO - PROCESS | 7061 | Cycle 1(1): loaded http://localhost:51411/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51411/tests/dromaeo/cssquery-ext.html) 08:53:15 INFO - PROCESS | 7061 | RSS: Main: 239865856 08:53:15 INFO - PROCESS | 7061 | 08:56:17 INFO - PROCESS | 7061 | Cycle 1(1): loaded http://localhost:51411/tests/dromaeo/cssquery-ext.html (next: http://localhost:51411/tests/dromaeo/cssquery-jquery.html) 08:56:18 INFO - PROCESS | 7061 | RSS: Main: 226902016 08:56:18 INFO - PROCESS | 7061 | 08:59:20 INFO - PROCESS | 7061 | Cycle 1(1): loaded http://localhost:51411/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51411/tests/dromaeo/cssquery-mootools.html) 08:59:20 INFO - PROCESS | 7061 | RSS: Main: 174489600 08:59:20 INFO - PROCESS | 7061 | 09:02:22 INFO - PROCESS | 7061 | Cycle 1(1): loaded http://localhost:51411/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51411/tests/dromaeo/cssquery-prototype.html) 09:02:23 INFO - PROCESS | 7061 | RSS: Main: 222277632 09:02:23 INFO - PROCESS | 7061 | 09:05:25 INFO - PROCESS | 7061 | Cycle 1(1): loaded http://localhost:51411/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51411/tests/dromaeo/cssquery-yui.html) 09:05:26 INFO - PROCESS | 7061 | RSS: Main: 175669248 09:05:26 INFO - PROCESS | 7061 | 09:08:28 INFO - PROCESS | 7061 | Cycle 1(1): loaded http://localhost:51411/tests/dromaeo/cssquery-yui.html (next: http://localhost:51411/tests/dromaeo/cssquery-dojo.html) 09:08:29 INFO - PROCESS | 7061 | RSS: Main: 267456512 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | __start_tp_report 09:08:29 INFO - PROCESS | 7061 | _x_x_mozilla_page_load 09:08:29 INFO - PROCESS | 7061 | _x_x_mozilla_page_load_details 09:08:29 INFO - PROCESS | 7061 | |i|pagename|runs| 09:08:29 INFO - PROCESS | 7061 | |0;dojo.html;3169.6513383527813;3213.7107660310567;3223.452013157763;3231.676832316767;3259.2666650003753;5600.327960644723;5678.630888992213;5680.147977803332;5701.971490142543;5727.627704199229;5645.125005624135;5712.600117991742;5718.142278658193;5732.627379220347;5743;5120.8975820483565;5140.1518749406305;5164.328637277167;5164.845054648366;5223.112070947929;5085.567726743231;5246.449122842104;5255.159174532076;5259.500347466985;5288.735563221842;5050.3434553508;5144;5158.27784110223;5168.483151684839;5171.379434467869;5189;5216.400113986902;5220.347456567929;5282.286891269668;5285.603579731497;5134.0758663440565;5168.586513078944;5243.003829272426;5249.711265880375;5283.181106928432;5520.447955204488;5556.916646250269;5561.610687251894;5574.052411090124;5644.604877658566;5994.910076348815;6005.729742161613;6007.879842403171;6084.513238940918;6103.023516237381;6128.877422451526;6133;6134.877302453926;6150.138980542728;6153.323134455206;5513.696746678931;5549.306336707952;5619.044762390404;5677.091665333527;5697;6195.310984315285;6237.656928868956;6274.623522588659;6275.34108918557;6280.434760871543;5995.0806013041965;5995.700214989233;5997.7001149942325;6005.459508644243;6033.698315084228;5845.035569131131;5891.734871930774;5893.381194974466;6027.246594175728;6083.604565703304;6034.034554471351;6041.879162416727;6069.0592958091565;6071.939280607226;6077.665728384893;5992;5998.550108741862;6053.001254792998;6057.151998720183;6075.635461872302;4553.61294289989;4597.4712908015335;4639.512851150581;4665.86002419928;4667;4155.16896620671;4168.791560421967;4171.416001759756;4217.0511634881905;4222.662187025031;5855.560832937546;5860.586358256808;5865.648061116347;5866.67733274674;5906.143609176645;5884.529237660977;5925.792597259161;6169.321374648785;6184.536159788034;6186.133941248228;5679.949209396275;5718.885622287614;5723.513501352431;5773.134029895466;5801.535877129904;5100.51545103222;5116.232565115262;5139.743012849342;5152.175651895679;5161.974190129101;6118.388161184024;6250.437460628475;6313.210848643919;6318;6330;5968.194293770459;6013.368596297326;6082.300535438481;6105.267367915879;6128.625347140727;6305.684715764101;6316.7473301069285;6324.430801227912;6328.335524769833;6335.239771227482;5969.76120955157;5979.820605381846;5980.491658209101;5980.880382392241;5989.8203053908455;5899.705014749331;5917.319508256433;5990.400959903975;6022.578419510767;6051.515878729779;5923.940760592339;5938.554608404474;5946.940530594638;5948.196993406008;5954.047352423591;5917.437843404869;5921.940780592139;5929.110633404851;5931.584789064724;5936.554758393224;5649.971750141387;5753.741081651252;5828.242328497268;5836.562257830595;5929.347771745019;5996;5996.580239383201;6006.279246490274;6009.819705408845;6026.517878569616;5814.825555233349;5866.384029676993;5868.911966320423;5869.413058694096;5879 09:08:29 INFO - PROCESS | 7061 | |1;ext.html;594.7591225553649;632.6773345593747;640.8910485217513;655.9704813283403;667.4126768443768;32656.346873062525;36492.81753591232;45938.3109253361;46616.30075548869;46801;48779;49286.50713492864;49336;49384.75307623457;49392.2591161132;9337.206337461324;9487.430754154739;9489.857652135224;9493.952530237339;9501;8626.439281446717;8829.95585022074;11044.723881902937;11281.492332845;11288.0969522438;11429.42852857358;11501.654950351503;11534.942325288404;11537.077033837315;11543.07655387567;11341.886581134208;11413.315201087962;11418.771624567504;11428.485718142703;11428.599998999995;11088.2238243323;11095.667129986114;11180.16148788836;11184.32894026353;11188.496517656726;44691.10617787626;45074;45076.77461612704;45115.32327015098;45151.54848451539;3763.5295588051495;3804.6956243500454;3887.611238876118;4089.20260549193;4382.255016647146;34495.4825677615;34570.48144277838;34874.65125348739;36395.272094557964;36481.452778208346;4565.885852853705;4671.369365135697;4688.320193571913;4709.105269998723;4716.151092803294;352899;354655;355460;356437;357391;39066.60933390687;39908.80045599753;40257.59742402597;62068;62778.37221627816;41884.58115418868;41908;42047.57952420498;42048.57951420508;42098.57901420946;17584.208710608054;17827.286908523773;17854.55363615894;17855;17861.642767144844;12968.80546791799;12989.41547630359;12989.870101299055;13271.336433178303;13659.931700341434;9362;9619.711408657751;9649.710508684751;9678.17735492491;9751.609935602497;3594.6944509716445;3655.798931058764;3693.7229707772026;3707.647773461517;3719.367707489733;1033.4367769565604;1082.783443311341;1094.9616763413242;1128.537299707116;1250.3435696259355;34404.31191376209;34914.65085349165;34918.30163396718;35182.12044698903;35204.823975879954;1161.9477123529462;1167.5038108803724;1190.5297407523979;1196.1985469735298;1305.7127431964952;17754.644907101785;18196.90901545484;18201.544961376072;18259.178336974866;18323.90838045801;545789;553499;554059;554156;554742;62598;62801.68599156975;62857.68571157115;62977.68511157415;63278.367216328166;1341.4164838295178;1372.4853180057478;1377.0635967542169;1443.501991812823;1477.253986736691;31030;35506.289874202885;35577.82211088928;35771.82114089517;35950.460743089396;52775;52948;53278.46721532735;53370;53411.19883201833;380569;381275;381993;383009;383860;30666.846665766527;31042.37915241637;31557;31705.36589268248;33310.83344583261;50451.49548504468;50696.98606027785;50814;51510;51546;42032.369514456695;42277.78861105675;42315;42322;44154.55845441505;42286.788566056974;42774;42838;43951.340729889715;44093;42523.787381062895;43362.78318608387;44503.332450012625;44599.55400445954;44700;34737.47893781646;37098.62901370952;37769;37915.54179566563;38176.618233818415;28754.424911501235;29165.270868228465;29342.41315173642;29489;29572.260693482836 09:08:29 INFO - PROCESS | 7061 | |2;jquery.html;379.77213671796943;444.6043021710678;450.43245510656584;451.4717780197169;456.13334664138154;18345.082745862703;19517.902410487946;20109.396718098444;20247.39257822264;20528.794712052873;18541.721874171897;19577.902110489464;19701.901490492564;19920.302789402373;20253.59492810143;5768.653880767147;5985.730642121115;5989.790357337494;5997.160397544348;6009.789657361993;5602.915956260659;5913.704314784265;5945.702714864261;5946;5948.048312270038;5752.597318187729;5908.054711246202;5908.084246941731;5908.291005079375;5911.083782013795;5903.970480147615;5924.200232968558;5926.288845338544;5937.376575459558;5992.460678538931;5294.263473647692;5334.466553344673;5365.222042803772;5368.865778355573;5378.1663842104535;23228.41928951773;23344;23445.41386465335;23470.295891123293;23615.645765313533;2511.208969174704;2614.241869857758;2690.9192724218306;2873.655161380627;2964.7183517566014;15028;15056;15148.697026059417;15185.848141518554;15648.687026259411;1984.4641946674317;2010.8491863110326;2020.666590012646;2050.261905713942;2071.5028393185676;85081.57459212726;85406.57296713539;85638;88139;89320;10569;11134.719507256768;11452.255603385753;11510.079193664487;11636.487256656581;15616.297266623029;16147.919260403623;16662.666746665;16743.33026678919;16810.663786724195;8908.821823563621;9160.267178625694;9217.124373184537;9450.669226577174;9498.95250523743;8799.208071273617;8915;8975.37172397939;9088.81822363549;9414.246860251164;6982.93017069833;7160.10498687664;7163.928360716326;7185.820354491179;7257.818554536178;2000.2299114840891;2074.68879668051;2087.1755656515593;2096.853220274566;2175.401764514771;1020.9336393134422;1043.446973104256;1094;1114.1810769084716;1140.8345789860423;22200.22299219552;22409.775902241094;22511.437214069778;22699.319020429415;22908.198213062795;1040.4069680282319;1121.3103941076342;1141.0529260713588;1160.3096157786104;1167.486306025346;11855.585054523222;12427.565035223904;12520.31138287403;12589.74820503603;12602.684932876751;469661;493950;510902;513756;514949;93438.53280733689;96597.5170124145;97106;98162.5091874536;101541.49229253954;1047.539082803564;1072.3351522056219;1110.9833352499718;1134.1607210664215;1142.1205671632797;19269.22923083061;19524.316648917218;19786.604267914274;19830.20679172817;20079.49801254922;42453;43668.907805224786;43716.781416092716;43788.56211437845;43943.56056439395;60018.69990650019;60427.395726043935;61706.69146654417;61799;61864;9233;9296.302777291865;9364.391314564531;9459.39866536708;9720;14234.430622774973;14249.715005700034;14602.269886505845;14604.269786510844;14668.633284167981;12079.27524348542;12598.370081495705;12626.116171867881;12819.935900320439;12968.35158242103;11829.940850295694;11849.644510664693;11902.285862848257;12635.810462842881;12722.300273484678;12129.818052729393;12278.570250041563;12719.93640031794;12856.614301570968;13069.15050521713;8014.078380986261;8274.58627068632;9092.954535227545;9135.132162444557;9532.760741103612;11482.36846973391;11770.82343764827;11809.940950295193;11875.52497900109;12177.208481448677 09:08:29 INFO - PROCESS | 7061 | |3;mootools.html;1369.0827145812304;1473.3222717549922;1539.5612250508605;1727.5249306440728;1918.299820565493;8500.48997060176;9314.627414903403;9650.613975440992;9655.903440965589;9953.751156221098;9673;9779.853302200472;9789.95105024474;9790.559424825882;9797.412155270671;2568.7174410814796;2590.2488278399337;2599.7270286619914;2599.9350016249555;2600.089968511025;2537.8731063446844;2609.2041927212194;2610.2952202905203;2611.281897478199;2612.164107485605;2541.3519552514085;2546.910858119966;2548.9490210195786;2552.2853600991753;2560.1295559509763;2459.434330104068;2482.6524286599893;2494.9875250623813;2501.9874900625473;2506.410993416546;2449.1795248591743;2456.41046148923;2458.5082983403213;2460.360306320352;2496.276079936816;9502.382345147613;9682.080202380763;9730.12428881404;9759.90240097597;9797.216222702167;5610.551155907518;5777.913331300034;5781.277340332458;5785;5794.188813566104;3627.4196128619296;3642.0894776305922;3673.5959044505084;3726.571444283915;3729.6270372962485;1336.859629738873;1337.2778699502257;1345.4752646467884;1357.7963305504154;1385.6882201504682;13630.931845340809;13790.724185516334;13794.862051379458;13799.862001379957;13810.65473363164;4847.466778654345;5001.874953126128;5008.974955125274;5049.747512624354;5122.436531981479;10704.19718521114;10712.517936692868;10717.892821071846;10725.410102444284;10730.141588672888;5787.102999035156;5790.36306006339;5791.739371728282;5792.275965504312;5793.15999180125;4591.701539399995;4750.714957102585;4945.23348880924;5302.098643230583;5387;4224.197402493516;4234.195502854448;4320.913581728348;4339.392485052095;4365.257906155961;1284.9550265740654;1290.1549485087282;1309.3845892430543;1314.2574445438297;1338.1903948111446;978.1587834462357;1018.954147063384;1025.0825511167614;1030.7577719235974;1045.764702941832;6339.11252424661;6627.304133066055;6753.85184518633;6786.321367863175;6893.0694356261265;1009.268280496634;1034.5448002878709;1037.8183817832;1070.8714954205388;1072.2815713471837;4415.2935530315635;4571.588557029883;4589.151007063704;4628.490866004737;4654.511276316006;11286.830697539543;11762.176647634584;11912.10659200546;12032.759344813056;12201.572944947062;12195.939020304842;12228.571999979958;12234.204776689487;12236.592791828876;12243.816342754866;8867.512286824287;9074;9080.18278354938;9095.909040909506;9097.81804363909;6635.502337324817;6701.396874281338;6749.696263668245;6983.895241571482;7005.964970175117;10706.089982351587;11252.212345135762;11390.544378224778;11404.885951140383;11421.314721116427;10960.397178155277;11067.446627668745;11095.16786241019;11137.387443690675;11484.827727583926;8656.956715216384;8735.956320218358;8737.868931966153;8810.118988101138;8851;9426.340156189;9524.95237523808;9528.523573821143;9534.809303814023;9539.14147726708;8003.519788812457;8112.675492980215;8203.917960820316;8392.244697977212;8408.369372297224;8066.153053929253;8068.636911339121;8140.104588495379;8172.019357677117;8192.959035204785;7307.3423391894985;7600.391968642606;7783.727569535039;7870.881936770838;8026.398020148629;5537.1140617501005;5545.365311970196;5551.694656793915;5661.971690141523;5787.247657804627;5636.718164091861;5703;5762.049261871744;5776.768929242951;5806.82579522615 09:08:29 INFO - PROCESS | 7061 | |4;prototype.html;357.77638975640224;362.21941715602884;366.2711204702642;376.2662807525326;378.23030133677935;5430.881956037144;5500.202470641754;5520.199571062193;5533.004059269335;5534.391216966131;5186.095786359632;5296.020236256286;5327.760250788714;5393.217983392406;5448.700321482317;4325.134973005396;4352.869413917571;4360.934585981213;4362.018545827195;4373.453318335216;4290.6138447539715;4362.422813293101;4363.934540981888;4368.672349573779;4381.452318460192;4277.572242775728;4324.502682191557;4328.84849030284;4337.457817772778;4350.129974005195;4329.848455304049;4333.609975102256;4345.283028300327;4345.869623911272;4372.409724687158;3883.864064757748;3901.7463864848696;3903.7072219583647;3905.49228600283;3924.7056470764524;5129.076426436933;5288.471152884719;5319.414864364917;5338.412774594792;5363.973180134074;4920.630952678528;4945;4974.850754477371;4980.153373926405;4990.850274491735;5674.9716251418895;5677.489025987681;5681.744321505542;5691.943080569225;5697.6296540724725;4162.83348666053;4252.851150209728;4283.421738065353;4284.528701842794;4293.969447332649;5940.495057920082;5943.732532036069;5954.225950626391;5955.940440595582;5959.463648271676;4391.407160033355;4391.8682439526865;4392.9121417572105;4394.934075988863;4424.225760491901;4321.157374312042;4423.778811059434;4424.557544245549;4425.380446737459;4441.378207051015;4901.358045054875;4926.655134140576;4927.679700819435;4941.357623508921;4957.752112394366;5566.610337276352;5726.99777538929;5734.971325143348;5746.339170995306;5751.59738818287;1462.722082804285;1483.9669652575824;1492.3359105198083;1501.7522108851917;1539.0611726846614;925.9259259259378;942.9717108486757;1049.9972526237577;1084.9728756781144;1086.1853609792656;5469.617126801086;5503.174523821379;5516.3380394352935;5589.267327128603;5611.5230205433;5752.769889204469;5782.277215348082;5855.765769369263;5898.587098903035;5909.433999990006;5438.184272359178;5457.863553411196;5470.9452905471235;5535.861603459865;5544.390117087117;3060.4797184478693;3064.5709600655928;3092.5361195820806;3101.3177101037736;3105.6583775784648;5751.079827227623;5938.317093534212;5941.673207973603;5948.494377977834;5953.940460595425;5881.176635271066;5884.529237660977;5911.615744976562;5912.526997840162;5920.585559010828;5810.477057064884;5945.227120474484;5973.283206015306;5988.760449581968;6003.489703375175;5801.477866991991;5803.709814509342;5804.709764511673;5813.418658134153;5814.360420353673;5548.361938377138;5711.343195532567;5725.969325521446;5736.741846616829;5751.597388182954;5875.970620146872;5935.2877654681715;5957.374475680164;5969.597144670912;5985.730642121202;4363.934540981952;4370.147821174841;4370.562943705731;4385.846495372647;4413.470383553994;5607.663540187602;5767.336756273083;5795.594308398372;5848.619839710405;5852.853678658068;5632.2678051853;5646.689432081275;5662.66024038559;5662.858428539154;5663.470862867085;5620.550355971431;5647.576431767716;5660.056770479246;5665.66006039639;5666.858328541654;5231.189165679326;5355.839324820262;5358.285348688382;5627.971860140673;5663.830085097454;5737.196792449144;5750.827475175752;5753.626014309057;5755.920582488348;5763.011760942468;5479.4520547944885;5566.22072909801;5588.050031494737;5636.85907852307;5642.576806739429 09:08:29 INFO - PROCESS | 7061 | |5;yui.html;848.5036253791532;872.8516152254113;884.3058199313538;918.742752029432;923.845193508115;16199;17251.396201132964;17262.223199956;17271.74092388615;17289.827101728977;15683.372665093411;15840.603984900383;15842.52472425829;15890.602734931632;16284.430044948429;729.1541811498663;742.6064185981438;832.2177153475744;873.9999999999984;887.0818702642766;868.3443999780158;883.6067949762358;885.6678745470449;885.8715486254489;886.2068448738376;924.7225832250332;926.4256161180044;927.4388994658202;927.6150397584994;927.7680579855037;753.3333000294751;776.3827756933196;888.8622263549161;920.4201353147507;922.7923717163618;677.2854638356532;681.8465845184843;682.0349205873732;682.1643486728767;682.8566001139767;16349.509514714578;16383.672326553402;16401;16452.670946581;16479.258433370527;14644.121352718765;14694.779578306334;14798.482053128197;14861.479848205261;15082.472113475975;14173.362198701083;14235.217063061527;14244;14338.713225735426;14740.115593064344;4818.204996175627;4959.950400496021;4969.602431805447;5081.771320290596;5092.159793634046;81945;83079.5846020766;83622;86235.13764862333;86658.56670716606;11962.461689224006;12208;12210.450529726184;12243.816342754866;12351.69120771988;23658.29025129249;23694.88152559226;23732.288031359087;23920;23967.16114935969;9014.323925705598;9222.170004699476;9266.675666351646;9409.858852117224;9525.618975241052;5052.317937078521;5099.821506247264;5176.767045482962;5225.4774522548205;5225.921611175835;3570.7143428525656;3670.137517683342;3674.669279764834;3676.246369494247;3679.816009199529;639.11163482759;647.5175993884586;656.8686262747469;658.6180015590945;662.0102946095618;633.6261605652687;687.1856849633201;700.0234672631752;750.4709180028067;784.8822676598556;10162.18702503798;10252.077313041862;10260.435676037889;10415.531301091469;10775.676729698122;695.4158506854267;709.0640354731808;725.9528130671489;732.0300601702659;784.3254800871246;4829.975850120797;5094.286799848024;5164.354455693038;5185.870353241199;5241.344831896013;76044.61977690076;91598.54200728954;95786;95850.52074739581;127626;59681.70159149263;80464;89845;96726;100157;65736.67131664311;86175.13824861671;87578.56210718778;87742;89370.55314723385;10390.636327728493;10589.205809564468;10630.574777009142;10633.574657013633;10868.347899126078;51024.74487627538;51046.23430648469;51121.48878511316;52294.21558676699;54542.45457545374;75825.6208718975;77425.2257477418;79042;79680.60159699165;81801.59099204704;12242.265464072185;13293.20240785556;13763.449462021808;14096.154230746188;14293.999420040498;14363.784543231652;14621.92689036548;14778.852211477748;14824.888133389823;15331.846681533489;14119.929400352932;15445.227738612799;15585;15656.13891235993;16146.838531614534;15522.767158492858;15873.365065397255;15970.201489925224;16952;17002.31990720407;14011.579652610439;14131.788023179866;14543.854561454673;14850.925745371203;15100.169490678118;12649.241045537297;12918.289494077915;12969.870301297244;13386.464541418234;13410.32948352598;10987.325381608043;12220.755584888075;12869.22784632925;12998.415071322002;13282.667933301746 09:08:29 INFO - PROCESS | 7061 | __end_tp_report 09:08:29 INFO - PROCESS | 7061 | __start_cc_report 09:08:29 INFO - PROCESS | 7061 | _x_x_mozilla_cycle_collect,866 09:08:29 INFO - PROCESS | 7061 | __end_cc_report 09:08:29 INFO - PROCESS | 7061 | __startTimestamp1478797709186__endTimestamp 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | ------- Summary: start ------- 09:08:29 INFO - PROCESS | 7061 | Number of tests: 6 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | [#0] dojo.html Cycles:165 Average:5636.94 Median:5869.41 stddev:643.44 (11.0%) stddev-sans-first:615.62 09:08:29 INFO - PROCESS | 7061 | Values: 3169.7 3213.7 3223.5 3231.7 3259.3 5600.3 5678.6 5680.1 5702.0 5727.6 5645.1 5712.6 5718.1 5732.6 5743.0 5120.9 5140.2 5164.3 5164.8 5223.1 5085.6 5246.4 5255.2 5259.5 5288.7 5050.3 5144.0 5158.3 5168.5 5171.4 5189.0 5216.4 5220.3 5282.3 5285.6 5134.1 5168.6 5243.0 5249.7 5283.2 5520.4 5556.9 5561.6 5574.1 5644.6 5994.9 6005.7 6007.9 6084.5 6103.0 6128.9 6133.0 6134.9 6150.1 6153.3 5513.7 5549.3 5619.0 5677.1 5697.0 6195.3 6237.7 6274.6 6275.3 6280.4 5995.1 5995.7 5997.7 6005.5 6033.7 5845.0 5891.7 5893.4 6027.2 6083.6 6034.0 6041.9 6069.1 6071.9 6077.7 5992.0 5998.6 6053.0 6057.2 6075.6 4553.6 4597.5 4639.5 4665.9 4667.0 4155.2 4168.8 4171.4 4217.1 4222.7 5855.6 5860.6 5865.6 5866.7 5906.1 5884.5 5925.8 6169.3 6184.5 6186.1 5679.9 5718.9 5723.5 5773.1 5801.5 5100.5 5116.2 5139.7 5152.2 5162.0 6118.4 6250.4 6313.2 6318.0 6330.0 5968.2 6013.4 6082.3 6105.3 6128.6 6305.7 6316.7 6324.4 6328.3 6335.2 5969.8 5979.8 5980.5 5980.9 5989.8 5899.7 5917.3 5990.4 6022.6 6051.5 5923.9 5938.6 5946.9 5948.2 5954.0 5917.4 5921.9 5929.1 5931.6 5936.6 5650.0 5753.7 5828.2 5836.6 5929.3 5996.0 5996.6 6006.3 6009.8 6026.5 5814.8 5866.4 5868.9 5869.4 5879.0 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | [#1] ext.html Cycles:180 Average:59606.11 Median:192186.85 stddev:116225.34 (60.5%) stddev-sans-first:116466.93 09:08:29 INFO - PROCESS | 7061 | Values: 594.8 632.7 640.9 656.0 667.4 32656.3 36492.8 45938.3 46616.3 46801.0 48779.0 49286.5 49336.0 49384.8 49392.3 9337.2 9487.4 9489.9 9494.0 9501.0 8626.4 8830.0 11044.7 11281.5 11288.1 11429.4 11501.7 11534.9 11537.1 11543.1 11341.9 11413.3 11418.8 11428.5 11428.6 11088.2 11095.7 11180.2 11184.3 11188.5 44691.1 45074.0 45076.8 45115.3 45151.5 3763.5 3804.7 3887.6 4089.2 4382.3 34495.5 34570.5 34874.7 36395.3 36481.5 4565.9 4671.4 4688.3 4709.1 4716.2 352899.0 354655.0 355460.0 356437.0 357391.0 39066.6 39908.8 40257.6 62068.0 62778.4 41884.6 41908.0 42047.6 42048.6 42098.6 17584.2 17827.3 17854.6 17855.0 17861.6 12968.8 12989.4 12989.9 13271.3 13659.9 9362.0 9619.7 9649.7 9678.2 9751.6 3594.7 3655.8 3693.7 3707.6 3719.4 1033.4 1082.8 1095.0 1128.5 1250.3 34404.3 34914.7 34918.3 35182.1 35204.8 1161.9 1167.5 1190.5 1196.2 1305.7 17754.6 18196.9 18201.5 18259.2 18323.9 545789.0 553499.0 554059.0 554156.0 554742.0 62598.0 62801.7 62857.7 62977.7 63278.4 1341.4 1372.5 1377.1 1443.5 1477.3 31030.0 35506.3 35577.8 35771.8 35950.5 52775.0 52948.0 53278.5 53370.0 53411.2 380569.0 381275.0 381993.0 383009.0 383860.0 30666.8 31042.4 31557.0 31705.4 33310.8 50451.5 50697.0 50814.0 51510.0 51546.0 42032.4 42277.8 42315.0 42322.0 44154.6 42286.8 42774.0 42838.0 43951.3 44093.0 42523.8 43362.8 44503.3 44599.6 44700.0 34737.5 37098.6 37769.0 37915.5 38176.6 28754.4 29165.3 29342.4 29489.0 29572.3 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | [#2] jquery.html Cycles:180 Average:30462.18 Median:23068.31 stddev:82653.61 (358.3%) stddev-sans-first:82854.62 09:08:29 INFO - PROCESS | 7061 | Values: 379.8 444.6 450.4 451.5 456.1 18345.1 19517.9 20109.4 20247.4 20528.8 18541.7 19577.9 19701.9 19920.3 20253.6 5768.7 5985.7 5989.8 5997.2 6009.8 5602.9 5913.7 5945.7 5946.0 5948.0 5752.6 5908.1 5908.1 5908.3 5911.1 5904.0 5924.2 5926.3 5937.4 5992.5 5294.3 5334.5 5365.2 5368.9 5378.2 23228.4 23344.0 23445.4 23470.3 23615.6 2511.2 2614.2 2690.9 2873.7 2964.7 15028.0 15056.0 15148.7 15185.8 15648.7 1984.5 2010.8 2020.7 2050.3 2071.5 85081.6 85406.6 85638.0 88139.0 89320.0 10569.0 11134.7 11452.3 11510.1 11636.5 15616.3 16147.9 16662.7 16743.3 16810.7 8908.8 9160.3 9217.1 9450.7 9499.0 8799.2 8915.0 8975.4 9088.8 9414.2 6982.9 7160.1 7163.9 7185.8 7257.8 2000.2 2074.7 2087.2 2096.9 2175.4 1020.9 1043.4 1094.0 1114.2 1140.8 22200.2 22409.8 22511.4 22699.3 22908.2 1040.4 1121.3 1141.1 1160.3 1167.5 11855.6 12427.6 12520.3 12589.7 12602.7 469661.0 493950.0 510902.0 513756.0 514949.0 93438.5 96597.5 97106.0 98162.5 101541.5 1047.5 1072.3 1111.0 1134.2 1142.1 19269.2 19524.3 19786.6 19830.2 20079.5 42453.0 43668.9 43716.8 43788.6 43943.6 60018.7 60427.4 61706.7 61799.0 61864.0 9233.0 9296.3 9364.4 9459.4 9720.0 14234.4 14249.7 14602.3 14604.3 14668.6 12079.3 12598.4 12626.1 12819.9 12968.4 11829.9 11849.6 11902.3 12635.8 12722.3 12129.8 12278.6 12719.9 12856.6 13069.2 8014.1 8274.6 9093.0 9135.1 9532.8 11482.4 11770.8 11809.9 11875.5 12177.2 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | [#3] mootools.html Cycles:180 Average:6365.36 Median:4580.37 stddev:3632.81 (79.3%) stddev-sans-first:3623.60 09:08:29 INFO - PROCESS | 7061 | Values: 1369.1 1473.3 1539.6 1727.5 1918.3 8500.5 9314.6 9650.6 9655.9 9953.8 9673.0 9779.9 9790.0 9790.6 9797.4 2568.7 2590.2 2599.7 2599.9 2600.1 2537.9 2609.2 2610.3 2611.3 2612.2 2541.4 2546.9 2548.9 2552.3 2560.1 2459.4 2482.7 2495.0 2502.0 2506.4 2449.2 2456.4 2458.5 2460.4 2496.3 9502.4 9682.1 9730.1 9759.9 9797.2 5610.6 5777.9 5781.3 5785.0 5794.2 3627.4 3642.1 3673.6 3726.6 3729.6 1336.9 1337.3 1345.5 1357.8 1385.7 13630.9 13790.7 13794.9 13799.9 13810.7 4847.5 5001.9 5009.0 5049.7 5122.4 10704.2 10712.5 10717.9 10725.4 10730.1 5787.1 5790.4 5791.7 5792.3 5793.2 4591.7 4750.7 4945.2 5302.1 5387.0 4224.2 4234.2 4320.9 4339.4 4365.3 1285.0 1290.2 1309.4 1314.3 1338.2 978.2 1019.0 1025.1 1030.8 1045.8 6339.1 6627.3 6753.9 6786.3 6893.1 1009.3 1034.5 1037.8 1070.9 1072.3 4415.3 4571.6 4589.2 4628.5 4654.5 11286.8 11762.2 11912.1 12032.8 12201.6 12195.9 12228.6 12234.2 12236.6 12243.8 8867.5 9074.0 9080.2 9095.9 9097.8 6635.5 6701.4 6749.7 6983.9 7006.0 10706.1 11252.2 11390.5 11404.9 11421.3 10960.4 11067.4 11095.2 11137.4 11484.8 8657.0 8736.0 8737.9 8810.1 8851.0 9426.3 9525.0 9528.5 9534.8 9539.1 8003.5 8112.7 8203.9 8392.2 8408.4 8066.2 8068.6 8140.1 8172.0 8193.0 7307.3 7600.4 7783.7 7870.9 8026.4 5537.1 5545.4 5551.7 5662.0 5787.2 5636.7 5703.0 5762.0 5776.8 5806.8 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | [#4] prototype.html Cycles:180 Average:4832.69 Median:5475.20 stddev:1374.06 (25.1%) stddev-sans-first:1336.23 09:08:29 INFO - PROCESS | 7061 | Values: 357.8 362.2 366.3 376.3 378.2 5430.9 5500.2 5520.2 5533.0 5534.4 5186.1 5296.0 5327.8 5393.2 5448.7 4325.1 4352.9 4360.9 4362.0 4373.5 4290.6 4362.4 4363.9 4368.7 4381.5 4277.6 4324.5 4328.8 4337.5 4350.1 4329.8 4333.6 4345.3 4345.9 4372.4 3883.9 3901.7 3903.7 3905.5 3924.7 5129.1 5288.5 5319.4 5338.4 5364.0 4920.6 4945.0 4974.9 4980.2 4990.9 5675.0 5677.5 5681.7 5691.9 5697.6 4162.8 4252.9 4283.4 4284.5 4294.0 5940.5 5943.7 5954.2 5955.9 5959.5 4391.4 4391.9 4392.9 4394.9 4424.2 4321.2 4423.8 4424.6 4425.4 4441.4 4901.4 4926.7 4927.7 4941.4 4957.8 5566.6 5727.0 5735.0 5746.3 5751.6 1462.7 1484.0 1492.3 1501.8 1539.1 925.9 943.0 1050.0 1085.0 1086.2 5469.6 5503.2 5516.3 5589.3 5611.5 5752.8 5782.3 5855.8 5898.6 5909.4 5438.2 5457.9 5470.9 5535.9 5544.4 3060.5 3064.6 3092.5 3101.3 3105.7 5751.1 5938.3 5941.7 5948.5 5953.9 5881.2 5884.5 5911.6 5912.5 5920.6 5810.5 5945.2 5973.3 5988.8 6003.5 5801.5 5803.7 5804.7 5813.4 5814.4 5548.4 5711.3 5726.0 5736.7 5751.6 5876.0 5935.3 5957.4 5969.6 5985.7 4363.9 4370.1 4370.6 4385.8 4413.5 5607.7 5767.3 5795.6 5848.6 5852.9 5632.3 5646.7 5662.7 5662.9 5663.5 5620.6 5647.6 5660.1 5665.7 5666.9 5231.2 5355.8 5358.3 5628.0 5663.8 5737.2 5750.8 5753.6 5755.9 5763.0 5479.5 5566.2 5588.1 5636.9 5642.6 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - PROCESS | 7061 | [#5] yui.html Cycles:180 Average:20889.91 Median:5010.96 stddev:28165.01 (562.1%) stddev-sans-first:28203.81 09:08:29 INFO - PROCESS | 7061 | Values: 848.5 872.9 884.3 918.7 923.8 16199.0 17251.4 17262.2 17271.7 17289.8 15683.4 15840.6 15842.5 15890.6 16284.4 729.2 742.6 832.2 874.0 887.1 868.3 883.6 885.7 885.9 886.2 924.7 926.4 927.4 927.6 927.8 753.3 776.4 888.9 920.4 922.8 677.3 681.8 682.0 682.2 682.9 16349.5 16383.7 16401.0 16452.7 16479.3 14644.1 14694.8 14798.5 14861.5 15082.5 14173.4 14235.2 14244.0 14338.7 14740.1 4818.2 4960.0 4969.6 5081.8 5092.2 81945.0 83079.6 83622.0 86235.1 86658.6 11962.5 12208.0 12210.5 12243.8 12351.7 23658.3 23694.9 23732.3 23920.0 23967.2 9014.3 9222.2 9266.7 9409.9 9525.6 5052.3 5099.8 5176.8 5225.5 5225.9 3570.7 3670.1 3674.7 3676.2 3679.8 639.1 647.5 656.9 658.6 662.0 633.6 687.2 700.0 750.5 784.9 10162.2 10252.1 10260.4 10415.5 10775.7 695.4 709.1 726.0 732.0 784.3 4830.0 5094.3 5164.4 5185.9 5241.3 76044.6 91598.5 95786.0 95850.5 127626.0 59681.7 80464.0 89845.0 96726.0 100157.0 65736.7 86175.1 87578.6 87742.0 89370.6 10390.6 10589.2 10630.6 10633.6 10868.3 51024.7 51046.2 51121.5 52294.2 54542.5 75825.6 77425.2 79042.0 79680.6 81801.6 12242.3 13293.2 13763.4 14096.2 14294.0 14363.8 14621.9 14778.9 14824.9 15331.8 14119.9 15445.2 15585.0 15656.1 16146.8 15522.8 15873.4 15970.2 16952.0 17002.3 14011.6 14131.8 14543.9 14850.9 15100.2 12649.2 12918.3 12969.9 13386.5 13410.3 10987.3 12220.8 12869.2 12998.4 13282.7 09:08:29 INFO - PROCESS | 7061 | -------- Summary: end -------- 09:08:29 INFO - PROCESS | 7061 | 09:08:29 INFO - TEST-INFO | 7061: exit 0 09:08:29 INFO - TEST-OK | dromaeo_css | took 1113736ms 09:08:29 INFO - TEST-START | kraken 09:08:29 INFO - operating with platform_type : linux_ 09:08:29 INFO - Initialising browser for kraken test... 09:08:29 INFO - TEST-INFO | started process 7375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEe3IdI/profile http://localhost:51411/getInfo.html) 09:08:33 INFO - PROCESS | 7375 | __metrics Screen width/height:1600/1200 09:08:33 INFO - PROCESS | 7375 | colorDepth:24 09:08:33 INFO - PROCESS | 7375 | Browser inner width/height: 1024/697 09:08:33 INFO - PROCESS | 7375 | __metrics 09:08:34 INFO - PROCESS | 7375 | 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" 09:08:34 INFO - PROCESS | 7375 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:35 INFO - PROCESS | 7375 | console.error: PushService: 09:08:35 INFO - PROCESS | 7375 | stateChangeProcessEnqueue: Error transitioning state 09:08:35 INFO - PROCESS | 7375 | UnknownError 09:08:35 INFO - TEST-INFO | 7375: exit 0 09:08:35 INFO - Browser initialized. 09:08:35 INFO - Running cycle 1/1 for kraken test... 09:08:35 INFO - TEST-INFO | started process 7466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEe3IdI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:47 INFO - PROCESS | 7466 | RSS: Main: 145543168 09:08:47 INFO - PROCESS | 7466 | 09:08:49 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51411/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:50 INFO - PROCESS | 7466 | RSS: Main: 186834944 09:08:50 INFO - PROCESS | 7466 | 09:08:53 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51411/tests/kraken/driver.html?testName=audio-dft) 09:08:53 INFO - PROCESS | 7466 | RSS: Main: 176181248 09:08:53 INFO - PROCESS | 7466 | 09:08:56 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51411/tests/kraken/driver.html?testName=audio-fft) 09:08:57 INFO - PROCESS | 7466 | RSS: Main: 268300288 09:08:57 INFO - PROCESS | 7466 | 09:08:59 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51411/tests/kraken/driver.html?testName=audio-oscillator) 09:08:59 INFO - PROCESS | 7466 | RSS: Main: 174182400 09:08:59 INFO - PROCESS | 7466 | 09:09:01 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51411/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:02 INFO - PROCESS | 7466 | RSS: Main: 176128000 09:09:02 INFO - PROCESS | 7466 | 09:09:05 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51411/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:06 INFO - PROCESS | 7466 | RSS: Main: 211795968 09:09:06 INFO - PROCESS | 7466 | 09:09:09 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51411/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:10 INFO - PROCESS | 7466 | RSS: Main: 210825216 09:09:10 INFO - PROCESS | 7466 | 09:09:12 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51411/tests/kraken/driver.html?testName=json-parse-financial) 09:09:13 INFO - PROCESS | 7466 | RSS: Main: 210313216 09:09:13 INFO - PROCESS | 7466 | 09:09:14 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51411/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:15 INFO - PROCESS | 7466 | RSS: Main: 172662784 09:09:15 INFO - PROCESS | 7466 | 09:09:17 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:17 INFO - PROCESS | 7466 | RSS: Main: 180256768 09:09:17 INFO - PROCESS | 7466 | 09:09:19 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:20 INFO - PROCESS | 7466 | RSS: Main: 188702720 09:09:20 INFO - PROCESS | 7466 | 09:09:22 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:23 INFO - PROCESS | 7466 | RSS: Main: 197459968 09:09:23 INFO - PROCESS | 7466 | 09:09:26 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:26 INFO - PROCESS | 7466 | RSS: Main: 194105344 09:09:26 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | Cycle 1(1): loaded http://localhost:51411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51411/tests/kraken/driver.html?testName=ai-astar) 09:09:28 INFO - PROCESS | 7466 | RSS: Main: 244662272 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | __start_tp_report 09:09:28 INFO - PROCESS | 7466 | _x_x_mozilla_page_load 09:09:28 INFO - PROCESS | 7466 | _x_x_mozilla_page_load_details 09:09:28 INFO - PROCESS | 7466 | |i|pagename|runs| 09:09:28 INFO - PROCESS | 7466 | |0;ai-astar;108;112;110;109;111;109;110;109;109;109 09:09:28 INFO - PROCESS | 7466 | |1;audio-beat-detection;146;121;122;158;119;124;124;117;149;117 09:09:28 INFO - PROCESS | 7466 | |2;audio-dft;160;159;161;162;161;160;161;160;159;161 09:09:28 INFO - PROCESS | 7466 | |3;audio-fft;75;75;76;111;75;74;75;75;104;74 09:09:28 INFO - PROCESS | 7466 | |4;audio-oscillator;82;81;113;83;82;82;99;81;82;114 09:09:28 INFO - PROCESS | 7466 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;118;118;117 09:09:28 INFO - PROCESS | 7466 | |6;imaging-darkroom;147;146;145;147;146;147;146;147;146;146 09:09:28 INFO - PROCESS | 7466 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;96;95 09:09:28 INFO - PROCESS | 7466 | |8;json-parse-financial;49;51;50;49;50;49;49;50;49;50 09:09:28 INFO - PROCESS | 7466 | |9;json-stringify-tinderbox;59;59;58;59;59;58;59;60;60;60 09:09:28 INFO - PROCESS | 7466 | |10;stanford-crypto-aes;74;74;73;83;87;74;74;79;75;73 09:09:28 INFO - PROCESS | 7466 | |11;stanford-crypto-ccm;122;120;123;122;122;121;132;119;155;129 09:09:28 INFO - PROCESS | 7466 | |12;stanford-crypto-pbkdf2;155;157;154;153;158;155;156;153;155;153 09:09:28 INFO - PROCESS | 7466 | |13;stanford-crypto-sha256-iterative;66;62;61;61;62;67;61;62;62;59 09:09:28 INFO - PROCESS | 7466 | __end_tp_report 09:09:28 INFO - PROCESS | 7466 | __start_cc_report 09:09:28 INFO - PROCESS | 7466 | _x_x_mozilla_cycle_collect,1579 09:09:28 INFO - PROCESS | 7466 | __end_cc_report 09:09:28 INFO - PROCESS | 7466 | __startTimestamp1478797768659__endTimestamp 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | ------- Summary: start ------- 09:09:28 INFO - PROCESS | 7466 | Number of tests: 14 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#0] ai-astar Cycles:10 Average:109.60 Median:109.50 stddev:1.17 (1.1%) stddev-sans-first:1.09 09:09:28 INFO - PROCESS | 7466 | Values: 108.0 112.0 110.0 109.0 111.0 109.0 110.0 109.0 109.0 109.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:124.00 stddev:15.19 (12.2%) stddev-sans-first:14.92 09:09:28 INFO - PROCESS | 7466 | Values: 146.0 121.0 122.0 158.0 119.0 124.0 124.0 117.0 149.0 117.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 09:09:28 INFO - PROCESS | 7466 | Values: 160.0 159.0 161.0 162.0 161.0 160.0 161.0 160.0 159.0 161.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.87 (18.5%) stddev-sans-first:14.51 09:09:28 INFO - PROCESS | 7466 | Values: 75.0 75.0 76.0 111.0 75.0 74.0 75.0 75.0 104.0 74.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:82.00 stddev:13.55 (16.5%) stddev-sans-first:14.07 09:09:28 INFO - PROCESS | 7466 | Values: 82.0 81.0 113.0 83.0 82.0 82.0 99.0 81.0 82.0 114.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 09:09:28 INFO - PROCESS | 7466 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:09:28 INFO - PROCESS | 7466 | Values: 147.0 146.0 145.0 147.0 146.0 147.0 146.0 147.0 146.0 146.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:09:28 INFO - PROCESS | 7466 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:0.70 (1.4%) stddev-sans-first:0.71 09:09:28 INFO - PROCESS | 7466 | Values: 49.0 51.0 50.0 49.0 50.0 49.0 49.0 50.0 49.0 50.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 09:09:28 INFO - PROCESS | 7466 | Values: 59.0 59.0 58.0 59.0 59.0 58.0 59.0 60.0 60.0 60.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:74.50 stddev:4.84 (6.5%) stddev-sans-first:5.04 09:09:28 INFO - PROCESS | 7466 | Values: 74.0 74.0 73.0 83.0 87.0 74.0 74.0 79.0 75.0 73.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:122.50 stddev:10.80 (8.8%) stddev-sans-first:11.34 09:09:28 INFO - PROCESS | 7466 | Values: 122.0 120.0 123.0 122.0 122.0 121.0 132.0 119.0 155.0 129.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.90 Median:155.00 stddev:1.73 (1.1%) stddev-sans-first:1.83 09:09:28 INFO - PROCESS | 7466 | Values: 155.0 157.0 154.0 153.0 158.0 155.0 156.0 153.0 155.0 153.0 09:09:28 INFO - PROCESS | 7466 | 09:09:28 INFO - PROCESS | 7466 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:2.41 (3.9%) stddev-sans-first:2.15 09:09:28 INFO - PROCESS | 7466 | Values: 66.0 62.0 61.0 61.0 62.0 67.0 61.0 62.0 62.0 59.0 09:09:28 INFO - PROCESS | 7466 | -------- Summary: end -------- 09:09:28 INFO - PROCESS | 7466 | 09:09:29 INFO - TEST-INFO | 7466: exit 0 09:09:29 INFO - TEST-OK | kraken | took 59864ms 09:09:29 INFO - SUITE-END | took 1173s 09:09:30 INFO - Completed test suite (00:19:34) 09:09:30 INFO - javascript benchmark 09:09:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3169.6513383527813, 3213.7107660310567, 3223.452013157763, 3231.676832316767, 3259.2666650003753, 5600.327960644723, 5678.630888992213, 5680.147977803332, 5701.971490142543, 5727.627704199229, 5645.125005624135, 5712.600117991742, 5718.142278658193, 5732.627379220347, 5743.0, 5120.8975820483565, 5140.1518749406305, 5164.328637277167, 5164.845054648366, 5223.112070947929, 5085.567726743231, 5246.449122842104, 5255.159174532076, 5259.500347466985, 5288.735563221842, 5050.3434553508, 5144.0, 5158.27784110223, 5168.483151684839, 5171.379434467869, 5189.0, 5216.400113986902, 5220.347456567929, 5282.286891269668, 5285.603579731497, 5134.0758663440565, 5168.586513078944, 5243.003829272426, 5249.711265880375, 5283.181106928432, 5520.447955204488, 5556.916646250269, 5561.610687251894, 5574.052411090124, 5644.604877658566, 5994.910076348815, 6005.729742161613, 6007.879842403171, 6084.513238940918, 6103.023516237381, 6128.877422451526, 6133.0, 6134.877302453926, 6150.138980542728, 6153.323134455206, 5513.696746678931, 5549.306336707952, 5619.044762390404, 5677.091665333527, 5697.0, 6195.310984315285, 6237.656928868956, 6274.623522588659, 6275.34108918557, 6280.434760871543, 5995.0806013041965, 5995.700214989233, 5997.7001149942325, 6005.459508644243, 6033.698315084228, 5845.035569131131, 5891.734871930774, 5893.381194974466, 6027.246594175728, 6083.604565703304, 6034.034554471351, 6041.879162416727, 6069.0592958091565, 6071.939280607226, 6077.665728384893, 5992.0, 5998.550108741862, 6053.001254792998, 6057.151998720183, 6075.635461872302, 4553.61294289989, 4597.4712908015335, 4639.512851150581, 4665.86002419928, 4667.0, 4155.16896620671, 4168.791560421967, 4171.416001759756, 4217.0511634881905, 4222.662187025031, 5855.560832937546, 5860.586358256808, 5865.648061116347, 5866.67733274674, 5906.143609176645, 5884.529237660977, 5925.792597259161, 6169.321374648785, 6184.536159788034, 6186.133941248228, 5679.949209396275, 5718.885622287614, 5723.513501352431, 5773.134029895466, 5801.535877129904, 5100.51545103222, 5116.232565115262, 5139.743012849342, 5152.175651895679, 5161.974190129101, 6118.388161184024, 6250.437460628475, 6313.210848643919, 6318.0, 6330.0, 5968.194293770459, 6013.368596297326, 6082.300535438481, 6105.267367915879, 6128.625347140727, 6305.684715764101, 6316.7473301069285, 6324.430801227912, 6328.335524769833, 6335.239771227482, 5969.76120955157, 5979.820605381846, 5980.491658209101, 5980.880382392241, 5989.8203053908455, 5899.705014749331, 5917.319508256433, 5990.400959903975, 6022.578419510767, 6051.515878729779, 5923.940760592339, 5938.554608404474, 5946.940530594638, 5948.196993406008, 5954.047352423591, 5917.437843404869, 5921.940780592139, 5929.110633404851, 5931.584789064724, 5936.554758393224, 5649.971750141387, 5753.741081651252, 5828.242328497268, 5836.562257830595, 5929.347771745019, 5996.0, 5996.580239383201, 6006.279246490274, 6009.819705408845, 6026.517878569616, 5814.825555233349, 5866.384029676993, 5868.911966320423, 5869.413058694096, 5879.0], "value": 5592.831702786417, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.7591225553649, 632.6773345593747, 640.8910485217513, 655.9704813283403, 667.4126768443768, 32656.346873062525, 36492.81753591232, 45938.3109253361, 46616.30075548869, 46801.0, 48779.0, 49286.50713492864, 49336.0, 49384.75307623457, 49392.2591161132, 9337.206337461324, 9487.430754154739, 9489.857652135224, 9493.952530237339, 9501.0, 8626.439281446717, 8829.95585022074, 11044.723881902937, 11281.492332845, 11288.0969522438, 11429.42852857358, 11501.654950351503, 11534.942325288404, 11537.077033837315, 11543.07655387567, 11341.886581134208, 11413.315201087962, 11418.771624567504, 11428.485718142703, 11428.599998999995, 11088.2238243323, 11095.667129986114, 11180.16148788836, 11184.32894026353, 11188.496517656726, 44691.10617787626, 45074.0, 45076.77461612704, 45115.32327015098, 45151.54848451539, 3763.5295588051495, 3804.6956243500454, 3887.611238876118, 4089.20260549193, 4382.255016647146, 34495.4825677615, 34570.48144277838, 34874.65125348739, 36395.272094557964, 36481.452778208346, 4565.885852853705, 4671.369365135697, 4688.320193571913, 4709.105269998723, 4716.151092803294, 352899.0, 354655.0, 355460.0, 356437.0, 357391.0, 39066.60933390687, 39908.80045599753, 40257.59742402597, 62068.0, 62778.37221627816, 41884.58115418868, 41908.0, 42047.57952420498, 42048.57951420508, 42098.57901420946, 17584.208710608054, 17827.286908523773, 17854.55363615894, 17855.0, 17861.642767144844, 12968.80546791799, 12989.41547630359, 12989.870101299055, 13271.336433178303, 13659.931700341434, 9362.0, 9619.711408657751, 9649.710508684751, 9678.17735492491, 9751.609935602497, 3594.6944509716445, 3655.798931058764, 3693.7229707772026, 3707.647773461517, 3719.367707489733, 1033.4367769565604, 1082.783443311341, 1094.9616763413242, 1128.537299707116, 1250.3435696259355, 34404.31191376209, 34914.65085349165, 34918.30163396718, 35182.12044698903, 35204.823975879954, 1161.9477123529462, 1167.5038108803724, 1190.5297407523979, 1196.1985469735298, 1305.7127431964952, 17754.644907101785, 18196.90901545484, 18201.544961376072, 18259.178336974866, 18323.90838045801, 545789.0, 553499.0, 554059.0, 554156.0, 554742.0, 62598.0, 62801.68599156975, 62857.68571157115, 62977.68511157415, 63278.367216328166, 1341.4164838295178, 1372.4853180057478, 1377.0635967542169, 1443.501991812823, 1477.253986736691, 31030.0, 35506.289874202885, 35577.82211088928, 35771.82114089517, 35950.460743089396, 52775.0, 52948.0, 53278.46721532735, 53370.0, 53411.19883201833, 380569.0, 381275.0, 381993.0, 383009.0, 383860.0, 30666.846665766527, 31042.37915241637, 31557.0, 31705.36589268248, 33310.83344583261, 50451.49548504468, 50696.98606027785, 50814.0, 51510.0, 51546.0, 42032.369514456695, 42277.78861105675, 42315.0, 42322.0, 44154.55845441505, 42286.788566056974, 42774.0, 42838.0, 43951.340729889715, 44093.0, 42523.787381062895, 43362.78318608387, 44503.332450012625, 44599.55400445954, 44700.0, 34737.47893781646, 37098.62901370952, 37769.0, 37915.54179566563, 38176.618233818415, 28754.424911501235, 29165.270868228465, 29342.41315173642, 29489.0, 29572.260693482836], "value": 20394.112747904623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.77213671796943, 444.6043021710678, 450.43245510656584, 451.4717780197169, 456.13334664138154, 18345.082745862703, 19517.902410487946, 20109.396718098444, 20247.39257822264, 20528.794712052873, 18541.721874171897, 19577.902110489464, 19701.901490492564, 19920.302789402373, 20253.59492810143, 5768.653880767147, 5985.730642121115, 5989.790357337494, 5997.160397544348, 6009.789657361993, 5602.915956260659, 5913.704314784265, 5945.702714864261, 5946.0, 5948.048312270038, 5752.597318187729, 5908.054711246202, 5908.084246941731, 5908.291005079375, 5911.083782013795, 5903.970480147615, 5924.200232968558, 5926.288845338544, 5937.376575459558, 5992.460678538931, 5294.263473647692, 5334.466553344673, 5365.222042803772, 5368.865778355573, 5378.1663842104535, 23228.41928951773, 23344.0, 23445.41386465335, 23470.295891123293, 23615.645765313533, 2511.208969174704, 2614.241869857758, 2690.9192724218306, 2873.655161380627, 2964.7183517566014, 15028.0, 15056.0, 15148.697026059417, 15185.848141518554, 15648.687026259411, 1984.4641946674317, 2010.8491863110326, 2020.666590012646, 2050.261905713942, 2071.5028393185676, 85081.57459212726, 85406.57296713539, 85638.0, 88139.0, 89320.0, 10569.0, 11134.719507256768, 11452.255603385753, 11510.079193664487, 11636.487256656581, 15616.297266623029, 16147.919260403623, 16662.666746665, 16743.33026678919, 16810.663786724195, 8908.821823563621, 9160.267178625694, 9217.124373184537, 9450.669226577174, 9498.95250523743, 8799.208071273617, 8915.0, 8975.37172397939, 9088.81822363549, 9414.246860251164, 6982.93017069833, 7160.10498687664, 7163.928360716326, 7185.820354491179, 7257.818554536178, 2000.2299114840891, 2074.68879668051, 2087.1755656515593, 2096.853220274566, 2175.401764514771, 1020.9336393134422, 1043.446973104256, 1094.0, 1114.1810769084716, 1140.8345789860423, 22200.22299219552, 22409.775902241094, 22511.437214069778, 22699.319020429415, 22908.198213062795, 1040.4069680282319, 1121.3103941076342, 1141.0529260713588, 1160.3096157786104, 1167.486306025346, 11855.585054523222, 12427.565035223904, 12520.31138287403, 12589.74820503603, 12602.684932876751, 469661.0, 493950.0, 510902.0, 513756.0, 514949.0, 93438.53280733689, 96597.5170124145, 97106.0, 98162.5091874536, 101541.49229253954, 1047.539082803564, 1072.3351522056219, 1110.9833352499718, 1134.1607210664215, 1142.1205671632797, 19269.22923083061, 19524.316648917218, 19786.604267914274, 19830.20679172817, 20079.49801254922, 42453.0, 43668.907805224786, 43716.781416092716, 43788.56211437845, 43943.56056439395, 60018.69990650019, 60427.395726043935, 61706.69146654417, 61799.0, 61864.0, 9233.0, 9296.302777291865, 9364.391314564531, 9459.39866536708, 9720.0, 14234.430622774973, 14249.715005700034, 14602.269886505845, 14604.269786510844, 14668.633284167981, 12079.27524348542, 12598.370081495705, 12626.116171867881, 12819.935900320439, 12968.35158242103, 11829.940850295694, 11849.644510664693, 11902.285862848257, 12635.810462842881, 12722.300273484678, 12129.818052729393, 12278.570250041563, 12719.93640031794, 12856.614301570968, 13069.15050521713, 8014.078380986261, 8274.58627068632, 9092.954535227545, 9135.132162444557, 9532.760741103612, 11482.36846973391, 11770.82343764827, 11809.940950295193, 11875.52497900109, 12177.208481448677], "value": 10039.340058763726, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.0827145812304, 1473.3222717549922, 1539.5612250508605, 1727.5249306440728, 1918.299820565493, 8500.48997060176, 9314.627414903403, 9650.613975440992, 9655.903440965589, 9953.751156221098, 9673.0, 9779.853302200472, 9789.95105024474, 9790.559424825882, 9797.412155270671, 2568.7174410814796, 2590.2488278399337, 2599.7270286619914, 2599.9350016249555, 2600.089968511025, 2537.8731063446844, 2609.2041927212194, 2610.2952202905203, 2611.281897478199, 2612.164107485605, 2541.3519552514085, 2546.910858119966, 2548.9490210195786, 2552.2853600991753, 2560.1295559509763, 2459.434330104068, 2482.6524286599893, 2494.9875250623813, 2501.9874900625473, 2506.410993416546, 2449.1795248591743, 2456.41046148923, 2458.5082983403213, 2460.360306320352, 2496.276079936816, 9502.382345147613, 9682.080202380763, 9730.12428881404, 9759.90240097597, 9797.216222702167, 5610.551155907518, 5777.913331300034, 5781.277340332458, 5785.0, 5794.188813566104, 3627.4196128619296, 3642.0894776305922, 3673.5959044505084, 3726.571444283915, 3729.6270372962485, 1336.859629738873, 1337.2778699502257, 1345.4752646467884, 1357.7963305504154, 1385.6882201504682, 13630.931845340809, 13790.724185516334, 13794.862051379458, 13799.862001379957, 13810.65473363164, 4847.466778654345, 5001.874953126128, 5008.974955125274, 5049.747512624354, 5122.436531981479, 10704.19718521114, 10712.517936692868, 10717.892821071846, 10725.410102444284, 10730.141588672888, 5787.102999035156, 5790.36306006339, 5791.739371728282, 5792.275965504312, 5793.15999180125, 4591.701539399995, 4750.714957102585, 4945.23348880924, 5302.098643230583, 5387.0, 4224.197402493516, 4234.195502854448, 4320.913581728348, 4339.392485052095, 4365.257906155961, 1284.9550265740654, 1290.1549485087282, 1309.3845892430543, 1314.2574445438297, 1338.1903948111446, 978.1587834462357, 1018.954147063384, 1025.0825511167614, 1030.7577719235974, 1045.764702941832, 6339.11252424661, 6627.304133066055, 6753.85184518633, 6786.321367863175, 6893.0694356261265, 1009.268280496634, 1034.5448002878709, 1037.8183817832, 1070.8714954205388, 1072.2815713471837, 4415.2935530315635, 4571.588557029883, 4589.151007063704, 4628.490866004737, 4654.511276316006, 11286.830697539543, 11762.176647634584, 11912.10659200546, 12032.759344813056, 12201.572944947062, 12195.939020304842, 12228.571999979958, 12234.204776689487, 12236.592791828876, 12243.816342754866, 8867.512286824287, 9074.0, 9080.18278354938, 9095.909040909506, 9097.81804363909, 6635.502337324817, 6701.396874281338, 6749.696263668245, 6983.895241571482, 7005.964970175117, 10706.089982351587, 11252.212345135762, 11390.544378224778, 11404.885951140383, 11421.314721116427, 10960.397178155277, 11067.446627668745, 11095.16786241019, 11137.387443690675, 11484.827727583926, 8656.956715216384, 8735.956320218358, 8737.868931966153, 8810.118988101138, 8851.0, 9426.340156189, 9524.95237523808, 9528.523573821143, 9534.809303814023, 9539.14147726708, 8003.519788812457, 8112.675492980215, 8203.917960820316, 8392.244697977212, 8408.369372297224, 8066.153053929253, 8068.636911339121, 8140.104588495379, 8172.019357677117, 8192.959035204785, 7307.3423391894985, 7600.391968642606, 7783.727569535039, 7870.881936770838, 8026.398020148629, 5537.1140617501005, 5545.365311970196, 5551.694656793915, 5661.971690141523, 5787.247657804627, 5636.718164091861, 5703.0, 5762.049261871744, 5776.768929242951, 5806.82579522615], "value": 5081.921112661521, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.77638975640224, 362.21941715602884, 366.2711204702642, 376.2662807525326, 378.23030133677935, 5430.881956037144, 5500.202470641754, 5520.199571062193, 5533.004059269335, 5534.391216966131, 5186.095786359632, 5296.020236256286, 5327.760250788714, 5393.217983392406, 5448.700321482317, 4325.134973005396, 4352.869413917571, 4360.934585981213, 4362.018545827195, 4373.453318335216, 4290.6138447539715, 4362.422813293101, 4363.934540981888, 4368.672349573779, 4381.452318460192, 4277.572242775728, 4324.502682191557, 4328.84849030284, 4337.457817772778, 4350.129974005195, 4329.848455304049, 4333.609975102256, 4345.283028300327, 4345.869623911272, 4372.409724687158, 3883.864064757748, 3901.7463864848696, 3903.7072219583647, 3905.49228600283, 3924.7056470764524, 5129.076426436933, 5288.471152884719, 5319.414864364917, 5338.412774594792, 5363.973180134074, 4920.630952678528, 4945.0, 4974.850754477371, 4980.153373926405, 4990.850274491735, 5674.9716251418895, 5677.489025987681, 5681.744321505542, 5691.943080569225, 5697.6296540724725, 4162.83348666053, 4252.851150209728, 4283.421738065353, 4284.528701842794, 4293.969447332649, 5940.495057920082, 5943.732532036069, 5954.225950626391, 5955.940440595582, 5959.463648271676, 4391.407160033355, 4391.8682439526865, 4392.9121417572105, 4394.934075988863, 4424.225760491901, 4321.157374312042, 4423.778811059434, 4424.557544245549, 4425.380446737459, 4441.378207051015, 4901.358045054875, 4926.655134140576, 4927.679700819435, 4941.357623508921, 4957.752112394366, 5566.610337276352, 5726.99777538929, 5734.971325143348, 5746.339170995306, 5751.59738818287, 1462.722082804285, 1483.9669652575824, 1492.3359105198083, 1501.7522108851917, 1539.0611726846614, 925.9259259259378, 942.9717108486757, 1049.9972526237577, 1084.9728756781144, 1086.1853609792656, 5469.617126801086, 5503.174523821379, 5516.3380394352935, 5589.267327128603, 5611.5230205433, 5752.769889204469, 5782.277215348082, 5855.765769369263, 5898.587098903035, 5909.433999990006, 5438.184272359178, 5457.863553411196, 5470.9452905471235, 5535.861603459865, 5544.390117087117, 3060.4797184478693, 3064.5709600655928, 3092.5361195820806, 3101.3177101037736, 3105.6583775784648, 5751.079827227623, 5938.317093534212, 5941.673207973603, 5948.494377977834, 5953.940460595425, 5881.176635271066, 5884.529237660977, 5911.615744976562, 5912.526997840162, 5920.585559010828, 5810.477057064884, 5945.227120474484, 5973.283206015306, 5988.760449581968, 6003.489703375175, 5801.477866991991, 5803.709814509342, 5804.709764511673, 5813.418658134153, 5814.360420353673, 5548.361938377138, 5711.343195532567, 5725.969325521446, 5736.741846616829, 5751.597388182954, 5875.970620146872, 5935.2877654681715, 5957.374475680164, 5969.597144670912, 5985.730642121202, 4363.934540981952, 4370.147821174841, 4370.562943705731, 4385.846495372647, 4413.470383553994, 5607.663540187602, 5767.336756273083, 5795.594308398372, 5848.619839710405, 5852.853678658068, 5632.2678051853, 5646.689432081275, 5662.66024038559, 5662.858428539154, 5663.470862867085, 5620.550355971431, 5647.576431767716, 5660.056770479246, 5665.66006039639, 5666.858328541654, 5231.189165679326, 5355.839324820262, 5358.285348688382, 5627.971860140673, 5663.830085097454, 5737.196792449144, 5750.827475175752, 5753.626014309057, 5755.920582488348, 5763.011760942468, 5479.4520547944885, 5566.22072909801, 5588.050031494737, 5636.85907852307, 5642.576806739429], "value": 4402.577722525534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [848.5036253791532, 872.8516152254113, 884.3058199313538, 918.742752029432, 923.845193508115, 16199.0, 17251.396201132964, 17262.223199956, 17271.74092388615, 17289.827101728977, 15683.372665093411, 15840.603984900383, 15842.52472425829, 15890.602734931632, 16284.430044948429, 729.1541811498663, 742.6064185981438, 832.2177153475744, 873.9999999999984, 887.0818702642766, 868.3443999780158, 883.6067949762358, 885.6678745470449, 885.8715486254489, 886.2068448738376, 924.7225832250332, 926.4256161180044, 927.4388994658202, 927.6150397584994, 927.7680579855037, 753.3333000294751, 776.3827756933196, 888.8622263549161, 920.4201353147507, 922.7923717163618, 677.2854638356532, 681.8465845184843, 682.0349205873732, 682.1643486728767, 682.8566001139767, 16349.509514714578, 16383.672326553402, 16401.0, 16452.670946581, 16479.258433370527, 14644.121352718765, 14694.779578306334, 14798.482053128197, 14861.479848205261, 15082.472113475975, 14173.362198701083, 14235.217063061527, 14244.0, 14338.713225735426, 14740.115593064344, 4818.204996175627, 4959.950400496021, 4969.602431805447, 5081.771320290596, 5092.159793634046, 81945.0, 83079.5846020766, 83622.0, 86235.13764862333, 86658.56670716606, 11962.461689224006, 12208.0, 12210.450529726184, 12243.816342754866, 12351.69120771988, 23658.29025129249, 23694.88152559226, 23732.288031359087, 23920.0, 23967.16114935969, 9014.323925705598, 9222.170004699476, 9266.675666351646, 9409.858852117224, 9525.618975241052, 5052.317937078521, 5099.821506247264, 5176.767045482962, 5225.4774522548205, 5225.921611175835, 3570.7143428525656, 3670.137517683342, 3674.669279764834, 3676.246369494247, 3679.816009199529, 639.11163482759, 647.5175993884586, 656.8686262747469, 658.6180015590945, 662.0102946095618, 633.6261605652687, 687.1856849633201, 700.0234672631752, 750.4709180028067, 784.8822676598556, 10162.18702503798, 10252.077313041862, 10260.435676037889, 10415.531301091469, 10775.676729698122, 695.4158506854267, 709.0640354731808, 725.9528130671489, 732.0300601702659, 784.3254800871246, 4829.975850120797, 5094.286799848024, 5164.354455693038, 5185.870353241199, 5241.344831896013, 76044.61977690076, 91598.54200728954, 95786.0, 95850.52074739581, 127626.0, 59681.70159149263, 80464.0, 89845.0, 96726.0, 100157.0, 65736.67131664311, 86175.13824861671, 87578.56210718778, 87742.0, 89370.55314723385, 10390.636327728493, 10589.205809564468, 10630.574777009142, 10633.574657013633, 10868.347899126078, 51024.74487627538, 51046.23430648469, 51121.48878511316, 52294.21558676699, 54542.45457545374, 75825.6208718975, 77425.2257477418, 79042.0, 79680.60159699165, 81801.59099204704, 12242.265464072185, 13293.20240785556, 13763.449462021808, 14096.154230746188, 14293.999420040498, 14363.784543231652, 14621.92689036548, 14778.852211477748, 14824.888133389823, 15331.846681533489, 14119.929400352932, 15445.227738612799, 15585.0, 15656.13891235993, 16146.838531614534, 15522.767158492858, 15873.365065397255, 15970.201489925224, 16952.0, 17002.31990720407, 14011.579652610439, 14131.788023179866, 14543.854561454673, 14850.925745371203, 15100.169490678118, 12649.241045537297, 12918.289494077915, 12969.870301297244, 13386.464541418234, 13410.32948352598, 10987.325381608043, 12220.755584888075, 12869.22784632925, 12998.415071322002, 13282.667933301746], "value": 8072.2117049191675, "unit": "score"}], "name": "dromaeo_css", "value": 7690.15477177674, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 110.0, 109.0, 111.0, 109.0, 110.0, 109.0, 109.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 121.0, 122.0, 158.0, 119.0, 124.0, 124.0, 117.0, 149.0, 117.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 162.0, 161.0, 160.0, 161.0, 160.0, 159.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 111.0, 75.0, 74.0, 75.0, 75.0, 104.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 113.0, 83.0, 82.0, 82.0, 99.0, 81.0, 82.0, 114.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 50.0, 49.0, 50.0, 49.0, 49.0, 50.0, 49.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 58.0, 59.0, 60.0, 60.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 83.0, 87.0, 74.0, 74.0, 79.0, 75.0, 73.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 123.0, 122.0, 122.0, 121.0, 132.0, 119.0, 155.0, 129.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 154.0, 153.0, 158.0, 155.0, 156.0, 153.0, 155.0, 153.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 61.0, 61.0, 62.0, 67.0, 61.0, 62.0, 62.0, 59.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1458.6000000000001, "alertThreshold": 2.0}]} 09:09:30 INFO - Return code: 0 09:09:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:30 INFO - # TBPL SUCCESS # 09:09:30 INFO - Running post-action listener: _resource_record_post_action 09:09:30 INFO - [mozharness: 2016-11-10 17:09:30.491313Z] Finished run-tests step (success) 09:09:30 INFO - Running post-run listener: _resource_record_post_run 09:09:30 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 2129920; Write bytes: 357814272; Read time: 372; Write time: 167232 09:09:30 INFO - TinderboxPrint: CPU usage
12.9% 09:09:30 INFO - TinderboxPrint: I/O read bytes / time
2,129,920 / 372 09:09:30 INFO - TinderboxPrint: I/O write bytes / time
357,814,272 / 167,232 09:09:30 INFO - TinderboxPrint: CPU idle
8,275.8 (87.1%) 09:09:30 INFO - TinderboxPrint: CPU user
1,184.0 (12.5%) 09:09:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:09:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108302336; Read time: 0; Write time: 43404 09:09:30 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249511936; Read time: 92; Write time: 123828 09:09:31 INFO - Running post-run listener: _upload_blobber_files 09:09:31 INFO - Blob upload gear active. 09:09:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:31 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 09:09:31 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'] 09:09:31 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 09:09:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:32 INFO - (blobuploader) - INFO - Done attempting. 09:09:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:35 INFO - (blobuploader) - INFO - Done attempting. 09:09:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:36 INFO - (blobuploader) - INFO - Done attempting. 09:09:36 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:36 INFO - Return code: 0 09:09:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0256051a453cbc361d3144b3f6668f88cc5f4e2c3399212761ee68995d13f1c537607ea0a33602240a27b6673dae46af1589945a9a00bb3fee3989413ae07a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2e62bc186704b1b550e373ea55f4577de0b425b30ab8d5ae7e4031ff8c25e30aa2a5c5df6138bc0aabf4dc60e3b3d0c02a96f13cc503b176ea81cbbc980c68f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a48f22c9d984db5c3342559c494d0feef7f7260143a4429c40dd4ef6abd68956c2ef3814df4e25cea2f5360b7b12a87bc22ee1baeffaff18f93594407bd2f51"} 09:09:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:36 INFO - Contents: 09:09:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0256051a453cbc361d3144b3f6668f88cc5f4e2c3399212761ee68995d13f1c537607ea0a33602240a27b6673dae46af1589945a9a00bb3fee3989413ae07a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2e62bc186704b1b550e373ea55f4577de0b425b30ab8d5ae7e4031ff8c25e30aa2a5c5df6138bc0aabf4dc60e3b3d0c02a96f13cc503b176ea81cbbc980c68f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a48f22c9d984db5c3342559c494d0feef7f7260143a4429c40dd4ef6abd68956c2ef3814df4e25cea2f5360b7b12a87bc22ee1baeffaff18f93594407bd2f51"} 09:09:36 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:36 INFO - Copying logs to upload dir... 09:09:36 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.489422 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-11-10 09:09:36.378064) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 09:09:36.381707) ========= 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=00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336 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/c0256051a453cbc361d3144b3f6668f88cc5f4e2c3399212761ee68995d13f1c537607ea0a33602240a27b6673dae46af1589945a9a00bb3fee3989413ae07a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2e62bc186704b1b550e373ea55f4577de0b425b30ab8d5ae7e4031ff8c25e30aa2a5c5df6138bc0aabf4dc60e3b3d0c02a96f13cc503b176ea81cbbc980c68f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a48f22c9d984db5c3342559c494d0feef7f7260143a4429c40dd4ef6abd68956c2ef3814df4e25cea2f5360b7b12a87bc22ee1baeffaff18f93594407bd2f51"} build_url:https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011423 build_url: 'https://queue.taskcluster.net/v1/task/O9noohJpQLa8nWFqV_Tdyw/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/c0256051a453cbc361d3144b3f6668f88cc5f4e2c3399212761ee68995d13f1c537607ea0a33602240a27b6673dae46af1589945a9a00bb3fee3989413ae07a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2e62bc186704b1b550e373ea55f4577de0b425b30ab8d5ae7e4031ff8c25e30aa2a5c5df6138bc0aabf4dc60e3b3d0c02a96f13cc503b176ea81cbbc980c68f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a48f22c9d984db5c3342559c494d0feef7f7260143a4429c40dd4ef6abd68956c2ef3814df4e25cea2f5360b7b12a87bc22ee1baeffaff18f93594407bd2f51"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 09:09:36.407388) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:09:36.407715) ========= 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=00204705a9daa57ed339a427000001b9-1478796055.670505-2046412336 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:09:36.491066) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 09:09:36.491425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 09:09:36.491723) ========= ========= Total master_lag: 0.18 =========