builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1474680293.17 results: success (0) buildid: 20160923153004 builduid: b98dd9a37e9c4fedaebd454d86a34473 revision: b451b91d4b4687f248bc7b2a4b959c7bd7459790 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.171422) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.171897) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.172215) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.208032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.208308) ========= 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=62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-23 18:24:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-09-23 18:24:53 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163580 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.387121) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.387482) ========= 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=62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014509 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:53.437413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 18:24:53.437805) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b451b91d4b4687f248bc7b2a4b959c7bd7459790 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev b451b91d4b4687f248bc7b2a4b959c7bd7459790 --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=62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-23 18:24:53,735 truncating revision to first 12 chars 2016-09-23 18:24:53,735 Setting DEBUG logging. 2016-09-23 18:24:53,735 attempt 1/10 2016-09-23 18:24:53,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b451b91d4b46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-23 18:24:55,032 unpacking tar archive at: autoland-b451b91d4b46/testing/mozharness/ program finished with exit code 0 elapsedTime=1.724565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 18:24:55.178465) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:55.178796) ========= script_repo_revision: b451b91d4b4687f248bc7b2a4b959c7bd7459790 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:55.179219) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:55.179554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 18:24:55.191014) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-23 18:24:55.191369) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:24:55 INFO - MultiFileLogger online at 20160923 18:24:55 in /builds/slave/test 18:24:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:24:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:24:55 INFO - {'append_to_log': False, 18:24:55 INFO - 'base_work_dir': '/builds/slave/test', 18:24:55 INFO - 'blob_upload_branch': 'Autoland', 18:24:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:24:55 INFO - 'branch': 'Autoland', 18:24:55 INFO - 'buildbot_json_path': 'buildprops.json', 18:24:55 INFO - 'config_files': ('talos/linux_config.py',), 18:24:55 INFO - 'default_actions': ('clobber', 18:24:55 INFO - 'read-buildbot-config', 18:24:55 INFO - 'download-and-extract', 18:24:55 INFO - 'populate-webroot', 18:24:55 INFO - 'create-virtualenv', 18:24:55 INFO - 'install', 18:24:55 INFO - 'run-tests'), 18:24:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:24:55 INFO - 'download_minidump_stackwalk': True, 18:24:55 INFO - 'download_symbols': 'ondemand', 18:24:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:24:55 INFO - 'tooltool.py': '/tools/tooltool.py', 18:24:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:24:55 INFO - '/tools/misc-python/virtualenv.py')}, 18:24:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:24:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:24:55 INFO - 'installer_path': 'installer.exe', 18:24:55 INFO - 'log_level': 'info', 18:24:55 INFO - 'log_name': 'talos', 18:24:55 INFO - 'log_to_console': True, 18:24:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:24:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:24:55 INFO - 'opt_config_files': (), 18:24:55 INFO - 'pip_index': False, 18:24:55 INFO - 'sps_profile': False, 18:24:55 INFO - 'sps_profile_interval': 0, 18:24:55 INFO - 'suite': 'dromaeojs', 18:24:55 INFO - 'system_bits': '32', 18:24:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:24:55 INFO - 'title': 'talos-linux64-ix-018', 18:24:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:24:55 INFO - 'use_talos_json': True, 18:24:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:24:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:24:55 INFO - 'work_dir': 'build'} 18:24:55 INFO - [mozharness: 2016-09-24 01:24:55.580219Z] Running clobber step. 18:24:55 INFO - Running pre-action listener: _resource_record_pre_action 18:24:55 INFO - Running main action method: clobber 18:24:55 INFO - rmtree: /builds/slave/test/build 18:24:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:24:56 INFO - Running post-action listener: _resource_record_post_action 18:24:56 INFO - [mozharness: 2016-09-24 01:24:56.040647Z] Finished clobber step (success) 18:24:56 INFO - [mozharness: 2016-09-24 01:24:56.040748Z] Running read-buildbot-config step. 18:24:56 INFO - Running pre-action listener: _resource_record_pre_action 18:24:56 INFO - Running main action method: read_buildbot_config 18:24:56 INFO - Using buildbot properties: 18:24:56 INFO - { 18:24:56 INFO - "project": "", 18:24:56 INFO - "product": "firefox", 18:24:56 INFO - "script_repo_revision": "production", 18:24:56 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 18:24:56 INFO - "repository": "", 18:24:56 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 18:24:56 INFO - "buildid": "20160923153004", 18:24:56 INFO - "pgo_build": "True", 18:24:56 INFO - "basedir": "/builds/slave/test", 18:24:56 INFO - "buildnumber": 253, 18:24:56 INFO - "slavename": "talos-linux64-ix-018", 18:24:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:24:56 INFO - "platform": "ubuntu64_hw", 18:24:56 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 18:24:56 INFO - "branch": "autoland", 18:24:56 INFO - "repo_path": "integration/autoland", 18:24:56 INFO - "revision": "b451b91d4b4687f248bc7b2a4b959c7bd7459790", 18:24:56 INFO - "stage_platform": "linux64-pgo", 18:24:56 INFO - "builduid": "b98dd9a37e9c4fedaebd454d86a34473", 18:24:56 INFO - "slavebuilddir": "test" 18:24:56 INFO - } 18:24:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 18:24:56 INFO - Running post-action listener: _resource_record_post_action 18:24:56 INFO - [mozharness: 2016-09-24 01:24:56.043162Z] Finished read-buildbot-config step (success) 18:24:56 INFO - [mozharness: 2016-09-24 01:24:56.043250Z] Running download-and-extract step. 18:24:56 INFO - Running pre-action listener: _resource_record_pre_action 18:24:56 INFO - Running main action method: download_and_extract 18:24:56 INFO - mkdir: /builds/slave/test/build/tests 18:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:56 INFO - trying https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:24:56 INFO - Downloading https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:24:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:24:56 INFO - Downloaded 1361 bytes. 18:24:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:24:56 INFO - Using the following test package requirements: 18:24:56 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:24:56 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:24:56 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:24:56 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'jsshell-linux-x86_64.zip'], 18:24:56 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:24:56 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:24:56 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:24:56 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:24:56 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:24:56 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:24:56 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:24:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:24:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:24:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:24:56 INFO - Fetch https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:24:57 INFO - Expected file size: 21948247 18:25:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:25:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:25:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:25:01 INFO - Fetch https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:25:02 INFO - Expected file size: 21948247 18:25:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 18:25:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:25:06 INFO - Fetch https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:25:07 INFO - Expected file size: 14414059 18:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:07 INFO - trying https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:25:07 INFO - Downloading https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:25:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:25:09 INFO - Downloaded 59691389 bytes. 18:25:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:25:09 INFO - mkdir: /builds/slave/test/properties 18:25:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:25:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:25:09 INFO - Contents: 18:25:09 INFO - build_url:https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:25:09 INFO - Running post-action listener: _resource_record_post_action 18:25:09 INFO - Running post-action listener: set_extra_try_arguments 18:25:09 INFO - [mozharness: 2016-09-24 01:25:09.668567Z] Finished download-and-extract step (success) 18:25:09 INFO - [mozharness: 2016-09-24 01:25:09.668794Z] Running populate-webroot step. 18:25:09 INFO - Running pre-action listener: _resource_record_pre_action 18:25:09 INFO - Running main action method: populate_webroot 18:25:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:25:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:25:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:25:09 INFO - '--tppagecycles', 18:25:09 INFO - '7'], 18:25:09 INFO - 'tests': ['tsvgm']}}, 18:25:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:25:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:25:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:25:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:25:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['damp', 'tps']}, 18:25:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'tests': ['damp', 'tps']}, 18:25:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['dromaeo_dom']}, 18:25:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:25:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['basic_compositor_video']}, 18:25:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:25:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['a11yr', 18:25:09 INFO - 'ts_paint', 18:25:09 INFO - 'tpaint', 18:25:09 INFO - 'sessionrestore', 18:25:09 INFO - 'sessionrestore_no_auto_restore', 18:25:09 INFO - 'tabpaint']}, 18:25:09 INFO - 'other-e10s': {'tests': ['a11yr', 18:25:09 INFO - 'ts_paint', 18:25:09 INFO - 'tpaint', 18:25:09 INFO - 'sessionrestore', 18:25:09 INFO - 'sessionrestore_no_auto_restore', 18:25:09 INFO - 'tabpaint']}, 18:25:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:25:09 INFO - 'ts_paint', 18:25:09 INFO - 'tpaint', 18:25:09 INFO - 'sessionrestore', 18:25:09 INFO - 'sessionrestore_no_auto_restore', 18:25:09 INFO - 'tabpaint']}, 18:25:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:25:09 INFO - 'ts_paint', 18:25:09 INFO - 'tpaint', 18:25:09 INFO - 'sessionrestore', 18:25:09 INFO - 'sessionrestore_no_auto_restore', 18:25:09 INFO - 'tabpaint']}, 18:25:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['a11yr', 18:25:09 INFO - 'ts_paint', 18:25:09 INFO - 'tpaint', 18:25:09 INFO - 'sessionrestore', 18:25:09 INFO - 'sessionrestore_no_auto_restore', 18:25:09 INFO - 'tabpaint']}, 18:25:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['a11yr', 18:25:09 INFO - 'ts_paint', 18:25:09 INFO - 'tpaint', 18:25:09 INFO - 'sessionrestore', 18:25:09 INFO - 'sessionrestore_no_auto_restore', 18:25:09 INFO - 'tabpaint']}, 18:25:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['tsvgx', 18:25:09 INFO - 'tsvgr_opacity', 18:25:09 INFO - 'tart', 18:25:09 INFO - 'tscrollx', 18:25:09 INFO - 'cart']}, 18:25:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:25:09 INFO - 'tsvgr_opacity', 18:25:09 INFO - 'tart', 18:25:09 INFO - 'tscrollx', 18:25:09 INFO - 'cart']}, 18:25:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'talos_options': ['--disable-e10s'], 18:25:09 INFO - 'tests': ['tp5o']}, 18:25:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'tests': ['tp5o']}, 18:25:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'talos_options': ['--disable-e10s', 18:25:09 INFO - '--xperf_path', 18:25:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:09 INFO - 'tests': ['tp5n']}, 18:25:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:09 INFO - 'talos_options': ['--xperf_path', 18:25:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:09 INFO - 'tests': ['tp5n']}}, 18:25:09 INFO - 'talos.zip': {'path': '', 18:25:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:25:09 INFO - Running post-action listener: _resource_record_post_action 18:25:09 INFO - [mozharness: 2016-09-24 01:25:09.687464Z] Finished populate-webroot step (success) 18:25:09 INFO - [mozharness: 2016-09-24 01:25:09.687553Z] Running create-virtualenv step. 18:25:09 INFO - Running pre-action listener: _resource_record_pre_action 18:25:09 INFO - Running main action method: create_virtualenv 18:25:09 INFO - Creating virtualenv /builds/slave/test/build/venv 18:25:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:25:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:25:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:25:09 INFO - Using real prefix '/usr' 18:25:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:25:10 INFO - Installing distribute.............................................................................................................................................................................................done. 18:25:12 INFO - Installing pip.................done. 18:25:12 INFO - Return code: 0 18:25:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:25:12 INFO - Reading from file tmpfile_stdout 18:25:12 INFO - Output received: 18:25:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:25:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:25:12 INFO - Using env: {'DISPLAY': ':0', 18:25:12 INFO - 'HOME': '/home/cltbld', 18:25:12 INFO - 'LANG': 'en_US.UTF-8', 18:25:12 INFO - 'LANGUAGE': 'en_US:en', 18:25:12 INFO - 'LOGNAME': 'cltbld', 18:25:12 INFO - 'MAIL': '/var/mail/cltbld', 18:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:12 INFO - 'MOZ_NO_REMOTE': '1', 18:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:12 INFO - 'NO_EM_RESTART': '1', 18:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:12 INFO - 'PWD': '/builds/slave/test', 18:25:12 INFO - 'SHELL': '/bin/bash', 18:25:12 INFO - 'SHLVL': '1', 18:25:12 INFO - 'TERM': 'linux', 18:25:12 INFO - 'TMOUT': '86400', 18:25:12 INFO - 'USER': 'cltbld', 18:25:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:12 INFO - '_': '/tools/buildbot/bin/python'} 18:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:25:12 INFO - Cleaning up... 18:25:12 INFO - Return code: 0 18:25:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:25:12 INFO - Using env: {'DISPLAY': ':0', 18:25:12 INFO - 'HOME': '/home/cltbld', 18:25:12 INFO - 'LANG': 'en_US.UTF-8', 18:25:12 INFO - 'LANGUAGE': 'en_US:en', 18:25:12 INFO - 'LOGNAME': 'cltbld', 18:25:12 INFO - 'MAIL': '/var/mail/cltbld', 18:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:12 INFO - 'MOZ_NO_REMOTE': '1', 18:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:12 INFO - 'NO_EM_RESTART': '1', 18:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:12 INFO - 'PWD': '/builds/slave/test', 18:25:12 INFO - 'SHELL': '/bin/bash', 18:25:12 INFO - 'SHLVL': '1', 18:25:12 INFO - 'TERM': 'linux', 18:25:12 INFO - 'TMOUT': '86400', 18:25:12 INFO - 'USER': 'cltbld', 18:25:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:12 INFO - '_': '/tools/buildbot/bin/python'} 18:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:12 INFO - Downloading/unpacking psutil>=3.1.1 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:25:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:14 INFO - Installing collected packages: psutil 18:25:14 INFO - Running setup.py install for psutil 18:25:14 INFO - building 'psutil._psutil_linux' extension 18:25:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:25:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:25:15 INFO - building 'psutil._psutil_posix' extension 18:25:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:25:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:25:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:15 INFO - Successfully installed psutil 18:25:15 INFO - Cleaning up... 18:25:15 INFO - Return code: 0 18:25:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:25:15 INFO - Using env: {'DISPLAY': ':0', 18:25:15 INFO - 'HOME': '/home/cltbld', 18:25:15 INFO - 'LANG': 'en_US.UTF-8', 18:25:15 INFO - 'LANGUAGE': 'en_US:en', 18:25:15 INFO - 'LOGNAME': 'cltbld', 18:25:15 INFO - 'MAIL': '/var/mail/cltbld', 18:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:15 INFO - 'MOZ_NO_REMOTE': '1', 18:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:15 INFO - 'NO_EM_RESTART': '1', 18:25:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:15 INFO - 'PWD': '/builds/slave/test', 18:25:15 INFO - 'SHELL': '/bin/bash', 18:25:15 INFO - 'SHLVL': '1', 18:25:15 INFO - 'TERM': 'linux', 18:25:15 INFO - 'TMOUT': '86400', 18:25:15 INFO - 'USER': 'cltbld', 18:25:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:15 INFO - '_': '/tools/buildbot/bin/python'} 18:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:25:17 INFO - Installing collected packages: mozsystemmonitor 18:25:17 INFO - Running setup.py install for mozsystemmonitor 18:25:17 INFO - Successfully installed mozsystemmonitor 18:25:17 INFO - Cleaning up... 18:25:17 INFO - Return code: 0 18:25:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:25:17 INFO - Using env: {'DISPLAY': ':0', 18:25:17 INFO - 'HOME': '/home/cltbld', 18:25:17 INFO - 'LANG': 'en_US.UTF-8', 18:25:17 INFO - 'LANGUAGE': 'en_US:en', 18:25:17 INFO - 'LOGNAME': 'cltbld', 18:25:17 INFO - 'MAIL': '/var/mail/cltbld', 18:25:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:17 INFO - 'MOZ_NO_REMOTE': '1', 18:25:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:17 INFO - 'NO_EM_RESTART': '1', 18:25:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:17 INFO - 'PWD': '/builds/slave/test', 18:25:17 INFO - 'SHELL': '/bin/bash', 18:25:17 INFO - 'SHLVL': '1', 18:25:17 INFO - 'TERM': 'linux', 18:25:17 INFO - 'TMOUT': '86400', 18:25:17 INFO - 'USER': 'cltbld', 18:25:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:17 INFO - '_': '/tools/buildbot/bin/python'} 18:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:17 INFO - Downloading/unpacking jsonschema==2.5.1 18:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:25:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:25: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 18:25: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 18:25: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 18:25: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 18:25: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 18:25: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 18:25:20 INFO - Downloading functools32-3.2.3-2.tar.gz 18:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:25:20 INFO - warning: no files found matching '*.txt' 18:25:20 INFO - no previously-included directories found matching 'build' 18:25:20 INFO - no previously-included directories found matching 'dist' 18:25:20 INFO - no previously-included directories found matching '.git*' 18:25:20 INFO - Installing collected packages: jsonschema, functools32 18:25:20 INFO - Running setup.py install for functools32 18:25:20 INFO - warning: no files found matching '*.txt' 18:25:20 INFO - no previously-included directories found matching 'build' 18:25:20 INFO - no previously-included directories found matching 'dist' 18:25:20 INFO - no previously-included directories found matching '.git*' 18:25:20 INFO - Successfully installed jsonschema functools32 18:25:20 INFO - Cleaning up... 18:25:20 INFO - Return code: 0 18:25:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:25:20 INFO - Using env: {'DISPLAY': ':0', 18:25:20 INFO - 'HOME': '/home/cltbld', 18:25:20 INFO - 'LANG': 'en_US.UTF-8', 18:25:20 INFO - 'LANGUAGE': 'en_US:en', 18:25:20 INFO - 'LOGNAME': 'cltbld', 18:25:20 INFO - 'MAIL': '/var/mail/cltbld', 18:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:20 INFO - 'MOZ_NO_REMOTE': '1', 18:25:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:20 INFO - 'NO_EM_RESTART': '1', 18:25:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:20 INFO - 'PWD': '/builds/slave/test', 18:25:20 INFO - 'SHELL': '/bin/bash', 18:25:20 INFO - 'SHLVL': '1', 18:25:20 INFO - 'TERM': 'linux', 18:25:20 INFO - 'TMOUT': '86400', 18:25:20 INFO - 'USER': 'cltbld', 18:25:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:20 INFO - '_': '/tools/buildbot/bin/python'} 18:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:25:20 INFO - Cleaning up... 18:25:20 INFO - Return code: 0 18:25:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:25:20 INFO - Using env: {'DISPLAY': ':0', 18:25:20 INFO - 'HOME': '/home/cltbld', 18:25:20 INFO - 'LANG': 'en_US.UTF-8', 18:25:20 INFO - 'LANGUAGE': 'en_US:en', 18:25:20 INFO - 'LOGNAME': 'cltbld', 18:25:20 INFO - 'MAIL': '/var/mail/cltbld', 18:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:20 INFO - 'MOZ_NO_REMOTE': '1', 18:25:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:20 INFO - 'NO_EM_RESTART': '1', 18:25:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:20 INFO - 'PWD': '/builds/slave/test', 18:25:20 INFO - 'SHELL': '/bin/bash', 18:25:20 INFO - 'SHLVL': '1', 18:25:20 INFO - 'TERM': 'linux', 18:25:20 INFO - 'TMOUT': '86400', 18:25:20 INFO - 'USER': 'cltbld', 18:25:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:20 INFO - '_': '/tools/buildbot/bin/python'} 18:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:20 INFO - Downloading/unpacking blobuploader==1.2.4 18:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:22 INFO - Downloading blobuploader-1.2.4.tar.gz 18:25:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:25:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:25: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 18:25: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 18:25: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 18:25: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 18:25: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 18:25: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 18:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:25:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:23 INFO - Downloading docopt-0.6.1.tar.gz 18:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:25:23 INFO - Installing collected packages: blobuploader, requests, docopt 18:25:23 INFO - Running setup.py install for blobuploader 18:25:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:25:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:25:23 INFO - Running setup.py install for requests 18:25:23 INFO - Running setup.py install for docopt 18:25:23 INFO - Successfully installed blobuploader requests docopt 18:25:23 INFO - Cleaning up... 18:25:24 INFO - Return code: 0 18:25:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25: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')]} 18:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:24 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 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:25:24 INFO - Using env: {'DISPLAY': ':0', 18:25:24 INFO - 'HOME': '/home/cltbld', 18:25:24 INFO - 'LANG': 'en_US.UTF-8', 18:25:24 INFO - 'LANGUAGE': 'en_US:en', 18:25:24 INFO - 'LOGNAME': 'cltbld', 18:25:24 INFO - 'MAIL': '/var/mail/cltbld', 18:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:24 INFO - 'MOZ_NO_REMOTE': '1', 18:25:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:24 INFO - 'NO_EM_RESTART': '1', 18:25:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:24 INFO - 'PWD': '/builds/slave/test', 18:25:24 INFO - 'SHELL': '/bin/bash', 18:25:24 INFO - 'SHLVL': '1', 18:25:24 INFO - 'TERM': 'linux', 18:25:24 INFO - 'TMOUT': '86400', 18:25:24 INFO - 'USER': 'cltbld', 18:25:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:24 INFO - '_': '/tools/buildbot/bin/python'} 18:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:24 INFO - Running setup.py (path:/tmp/pip-szqVbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:24 INFO - Running setup.py (path:/tmp/pip-Onf7hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:24 INFO - Running setup.py (path:/tmp/pip-jZmf_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:24 INFO - Running setup.py (path:/tmp/pip-XbSwao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:24 INFO - Running setup.py (path:/tmp/pip-UnKmoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:24 INFO - Running setup.py (path:/tmp/pip-RyOQz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:24 INFO - Running setup.py (path:/tmp/pip-5KjPac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:24 INFO - Running setup.py (path:/tmp/pip-x1xnIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:24 INFO - Running setup.py (path:/tmp/pip-ZwHtBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:24 INFO - Running setup.py (path:/tmp/pip-luW6_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:25 INFO - Running setup.py (path:/tmp/pip-jR_JA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:25 INFO - Running setup.py (path:/tmp/pip-qYLfWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:25 INFO - Running setup.py (path:/tmp/pip-jVc9qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:25 INFO - Running setup.py (path:/tmp/pip-LgyYVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:25 INFO - Running setup.py (path:/tmp/pip-QewHQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:25 INFO - Running setup.py (path:/tmp/pip-7MA9Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:25 INFO - Running setup.py (path:/tmp/pip-TYRBmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:25:25 INFO - Running setup.py install for manifestparser 18:25:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:25:25 INFO - Running setup.py install for mozcrash 18:25:25 INFO - Running setup.py install for mozdebug 18:25:25 INFO - Running setup.py install for mozdevice 18:25:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:25:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:25:25 INFO - Running setup.py install for mozfile 18:25:26 INFO - Running setup.py install for mozhttpd 18:25:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for mozinfo 18:25:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for mozInstall 18:25:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for mozleak 18:25:26 INFO - Running setup.py install for mozlog 18:25:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for moznetwork 18:25:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for mozprocess 18:25:26 INFO - Running setup.py install for mozprofile 18:25:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for mozrunner 18:25:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:25:26 INFO - Running setup.py install for mozscreenshot 18:25:27 INFO - Running setup.py install for moztest 18:25:27 INFO - Running setup.py install for mozversion 18:25:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:25:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:25:27 INFO - Cleaning up... 18:25:27 INFO - Return code: 0 18:25:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25: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')]} 18:25:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:25:27 INFO - Using env: {'DISPLAY': ':0', 18:25:27 INFO - 'HOME': '/home/cltbld', 18:25:27 INFO - 'LANG': 'en_US.UTF-8', 18:25:27 INFO - 'LANGUAGE': 'en_US:en', 18:25:27 INFO - 'LOGNAME': 'cltbld', 18:25:27 INFO - 'MAIL': '/var/mail/cltbld', 18:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:27 INFO - 'MOZ_NO_REMOTE': '1', 18:25:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:27 INFO - 'NO_EM_RESTART': '1', 18:25:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:27 INFO - 'PWD': '/builds/slave/test', 18:25:27 INFO - 'SHELL': '/bin/bash', 18:25:27 INFO - 'SHLVL': '1', 18:25:27 INFO - 'TERM': 'linux', 18:25:27 INFO - 'TMOUT': '86400', 18:25:27 INFO - 'USER': 'cltbld', 18:25:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:27 INFO - '_': '/tools/buildbot/bin/python'} 18:25:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:27 INFO - Running setup.py (path:/tmp/pip-CbPYb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:27 INFO - Running setup.py (path:/tmp/pip-n9yifn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:27 INFO - Running setup.py (path:/tmp/pip-F2Zw88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:27 INFO - Running setup.py (path:/tmp/pip-60696v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:27 INFO - Running setup.py (path:/tmp/pip-AgwwOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:27 INFO - Running setup.py (path:/tmp/pip-9Eol1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:27 INFO - Running setup.py (path:/tmp/pip-ZfT8zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:28 INFO - Running setup.py (path:/tmp/pip-vWkGgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:28 INFO - Running setup.py (path:/tmp/pip-ZWffhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:28 INFO - Running setup.py (path:/tmp/pip-RKSB5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:28 INFO - Running setup.py (path:/tmp/pip-E9N6NZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:28 INFO - Running setup.py (path:/tmp/pip-cn8vYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:28 INFO - Running setup.py (path:/tmp/pip-UJ21uL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:28 INFO - Running setup.py (path:/tmp/pip-n9mHTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:28 INFO - Running setup.py (path:/tmp/pip-cYyD8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:28 INFO - Running setup.py (path:/tmp/pip-79UJcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:28 INFO - Running setup.py (path:/tmp/pip-yVeR7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:25:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:25: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 18:25: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 18:25: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 18:25: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 18:25: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 18:25: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 18:25:30 INFO - Downloading blessings-1.6.tar.gz 18:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:25:30 INFO - Installing collected packages: blessings 18:25:30 INFO - Running setup.py install for blessings 18:25:30 INFO - Successfully installed blessings 18:25:30 INFO - Cleaning up... 18:25:30 INFO - Return code: 0 18:25:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:25:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:31 INFO - Reading from file tmpfile_stdout 18:25:31 INFO - Current package versions: 18:25:31 INFO - argparse == 1.2.1 18:25:31 INFO - blessings == 1.6 18:25:31 INFO - blobuploader == 1.2.4 18:25:31 INFO - docopt == 0.6.1 18:25:31 INFO - functools32 == 3.2.3-2 18:25:31 INFO - jsonschema == 2.5.1 18:25:31 INFO - manifestparser == 1.1 18:25:31 INFO - mozInstall == 1.12 18:25:31 INFO - mozcrash == 0.17 18:25:31 INFO - mozdebug == 0.1 18:25:31 INFO - mozdevice == 0.48 18:25:31 INFO - mozfile == 1.2 18:25:31 INFO - mozhttpd == 0.7 18:25:31 INFO - mozinfo == 0.9 18:25:31 INFO - mozleak == 0.1 18:25:31 INFO - mozlog == 3.4 18:25:31 INFO - moznetwork == 0.27 18:25:31 INFO - mozprocess == 0.23 18:25:31 INFO - mozprofile == 0.28 18:25:31 INFO - mozrunner == 6.12 18:25:31 INFO - mozscreenshot == 0.1 18:25:31 INFO - mozsystemmonitor == 0.3 18:25:31 INFO - moztest == 0.7 18:25:31 INFO - mozversion == 1.4 18:25:31 INFO - psutil == 3.1.1 18:25:31 INFO - requests == 1.2.3 18:25:31 INFO - wsgiref == 0.1.2 18:25:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25: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')]} 18:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:31 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 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:25:31 INFO - Using env: {'DISPLAY': ':0', 18:25:31 INFO - 'HOME': '/home/cltbld', 18:25:31 INFO - 'LANG': 'en_US.UTF-8', 18:25:31 INFO - 'LANGUAGE': 'en_US:en', 18:25:31 INFO - 'LOGNAME': 'cltbld', 18:25:31 INFO - 'MAIL': '/var/mail/cltbld', 18:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:31 INFO - 'MOZ_NO_REMOTE': '1', 18:25:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:31 INFO - 'NO_EM_RESTART': '1', 18:25:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:31 INFO - 'PWD': '/builds/slave/test', 18:25:31 INFO - 'SHELL': '/bin/bash', 18:25:31 INFO - 'SHLVL': '1', 18:25:31 INFO - 'TERM': 'linux', 18:25:31 INFO - 'TMOUT': '86400', 18:25:31 INFO - 'USER': 'cltbld', 18:25:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:31 INFO - '_': '/tools/buildbot/bin/python'} 18:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:25:31 INFO - Cleaning up... 18:25:31 INFO - Return code: 0 18:25:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25: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')]} 18:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91aca23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e8790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, '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': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:25:31 INFO - Using env: {'DISPLAY': ':0', 18:25:31 INFO - 'HOME': '/home/cltbld', 18:25:31 INFO - 'LANG': 'en_US.UTF-8', 18:25:31 INFO - 'LANGUAGE': 'en_US:en', 18:25:31 INFO - 'LOGNAME': 'cltbld', 18:25:31 INFO - 'MAIL': '/var/mail/cltbld', 18:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:31 INFO - 'MOZ_NO_REMOTE': '1', 18:25:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:31 INFO - 'NO_EM_RESTART': '1', 18:25:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:31 INFO - 'PWD': '/builds/slave/test', 18:25:31 INFO - 'SHELL': '/bin/bash', 18:25:31 INFO - 'SHLVL': '1', 18:25:31 INFO - 'TERM': 'linux', 18:25:31 INFO - 'TMOUT': '86400', 18:25:31 INFO - 'USER': 'cltbld', 18:25:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:31 INFO - '_': '/tools/buildbot/bin/python'} 18:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:25:31 INFO - Cleaning up... 18:25:31 INFO - Return code: 0 18:25:31 INFO - Running post-action listener: _resource_record_post_action 18:25:31 INFO - Running post-action listener: _start_resource_monitoring 18:25:31 INFO - Starting resource monitoring. 18:25:31 INFO - [mozharness: 2016-09-24 01:25:31.560865Z] Finished create-virtualenv step (success) 18:25:31 INFO - [mozharness: 2016-09-24 01:25:31.561343Z] Running install step. 18:25:31 INFO - Running pre-action listener: _resource_record_pre_action 18:25:31 INFO - Running main action method: install 18:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:31 INFO - Reading from file tmpfile_stdout 18:25:31 INFO - Detecting whether we're running mozinstall >=1.0... 18:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:25:31 INFO - Reading from file tmpfile_stdout 18:25:31 INFO - Output received: 18:25:31 INFO - Usage: mozinstall [options] installer 18:25:31 INFO - Options: 18:25:31 INFO - -h, --help show this help message and exit 18:25:31 INFO - -d DEST, --destination=DEST 18:25:31 INFO - Directory to install application into. [default: 18:25:31 INFO - "/builds/slave/test"] 18:25:31 INFO - --app=APP Application being installed. [default: firefox] 18:25:31 INFO - mkdir: /builds/slave/test/build/application 18:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:25:48 INFO - Reading from file tmpfile_stdout 18:25:48 INFO - Output received: 18:25:48 INFO - /builds/slave/test/build/application/firefox/firefox 18:25:48 INFO - Running post-action listener: _resource_record_post_action 18:25:48 INFO - [mozharness: 2016-09-24 01:25:48.834551Z] Finished install step (success) 18:25:48 INFO - [mozharness: 2016-09-24 01:25:48.834797Z] Running run-tests step. 18:25:48 INFO - Running pre-action listener: _resource_record_pre_action 18:25:48 INFO - Running main action method: run_tests 18:25:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:25:48 INFO - Python 2.7.3 18:25:48 INFO - Return code: 0 18:25:48 INFO - grabbing minidump binary from tooltool 18:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e5930>, '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 0x23e6830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23e6cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:25:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:25:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:25:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:25:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:25:48 INFO - Return code: 0 18:25:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:25:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:25:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:25:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:25:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:25:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:25:48 INFO - Using env: {'DISPLAY': ':0', 18:25:48 INFO - 'HOME': '/home/cltbld', 18:25:48 INFO - 'LANG': 'en_US.UTF-8', 18:25:48 INFO - 'LANGUAGE': 'en_US:en', 18:25:48 INFO - 'LOGNAME': 'cltbld', 18:25:48 INFO - 'MAIL': '/var/mail/cltbld', 18:25:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:48 INFO - 'MOZ_NO_REMOTE': '1', 18:25:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:48 INFO - 'NO_EM_RESTART': '1', 18:25:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:48 INFO - 'PWD': '/builds/slave/test', 18:25:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:48 INFO - 'SHELL': '/bin/bash', 18:25:48 INFO - 'SHLVL': '1', 18:25:48 INFO - 'TERM': 'linux', 18:25:48 INFO - 'TMOUT': '86400', 18:25:48 INFO - 'USER': 'cltbld', 18:25:48 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656', 18:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:48 INFO - '_': '/tools/buildbot/bin/python'} 18:25:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:25:49 INFO - mozversion application_buildid: 20160923153004 18:25:49 INFO - mozversion application_changeset: b451b91d4b4687f248bc7b2a4b959c7bd7459790 18:25:49 INFO - mozversion application_display_name: Nightly 18:25:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:25:49 INFO - mozversion application_name: Firefox 18:25:49 INFO - mozversion application_remotingname: firefox 18:25:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:25:49 INFO - mozversion application_vendor: Mozilla 18:25:49 INFO - mozversion application_version: 52.0a1 18:25:49 INFO - mozversion platform_buildid: 20160923153004 18:25:49 INFO - mozversion platform_changeset: b451b91d4b4687f248bc7b2a4b959c7bd7459790 18:25:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:25:49 INFO - mozversion platform_version: 52.0a1 18:25:49 INFO - using testdate: 1474680349 18:25:49 INFO - actual date: 1474680349 18:25:49 INFO - starting webserver on 'localhost:49479' 18:25:49 INFO - SUITE-START | Running 2 tests 18:25:49 INFO - TEST-START | dromaeo_css 18:25:49 INFO - operating with platform_type : linux_ 18:25:49 INFO - Initialising browser for dromaeo_css test... 18:25:49 INFO - TEST-INFO | started process 1793 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNMWpo/profile http://localhost:49479/getInfo.html) 18:25:53 INFO - PROCESS | 1793 | __metrics Screen width/height:1600/1200 18:25:53 INFO - PROCESS | 1793 | colorDepth:24 18:25:53 INFO - PROCESS | 1793 | Browser inner width/height: 1024/697 18:25:53 INFO - PROCESS | 1793 | __metrics 18:25:53 INFO - PROCESS | 1793 | 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" 18:25:53 INFO - PROCESS | 1793 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:25:54 INFO - PROCESS | 1793 | console.error: PushService: 18:25:54 INFO - PROCESS | 1793 | stateChangeProcessEnqueue: Error transitioning state 18:25:54 INFO - PROCESS | 1793 | UnknownError 18:25:54 INFO - TEST-INFO | 1793: exit 0 18:25:54 INFO - Browser initialized. 18:25:54 INFO - Running cycle 1/1 for dromaeo_css test... 18:25:54 INFO - TEST-INFO | started process 1874 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNMWpo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:06 INFO - PROCESS | 1874 | RSS: Main: 173969408 18:26:06 INFO - PROCESS | 1874 | 18:26:58 INFO - PROCESS | 1874 | 1474680418242 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:29:07 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49479/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49479/tests/dromaeo/cssquery-ext.html) 18:29:08 INFO - PROCESS | 1874 | RSS: Main: 319385600 18:29:08 INFO - PROCESS | 1874 | 18:32:11 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49479/tests/dromaeo/cssquery-ext.html (next: http://localhost:49479/tests/dromaeo/cssquery-jquery.html) 18:32:11 INFO - PROCESS | 1874 | RSS: Main: 270417920 18:32:11 INFO - PROCESS | 1874 | 18:35:13 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49479/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49479/tests/dromaeo/cssquery-mootools.html) 18:35:14 INFO - PROCESS | 1874 | RSS: Main: 255995904 18:35:14 INFO - PROCESS | 1874 | 18:38:16 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49479/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49479/tests/dromaeo/cssquery-prototype.html) 18:38:17 INFO - PROCESS | 1874 | RSS: Main: 241004544 18:38:17 INFO - PROCESS | 1874 | 18:41:19 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49479/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49479/tests/dromaeo/cssquery-yui.html) 18:41:20 INFO - PROCESS | 1874 | RSS: Main: 217165824 18:41:20 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49479/tests/dromaeo/cssquery-yui.html (next: http://localhost:49479/tests/dromaeo/cssquery-dojo.html) 18:44:23 INFO - PROCESS | 1874 | RSS: Main: 306978816 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | __start_tp_report 18:44:23 INFO - PROCESS | 1874 | _x_x_mozilla_page_load 18:44:23 INFO - PROCESS | 1874 | _x_x_mozilla_page_load_details 18:44:23 INFO - PROCESS | 1874 | |i|pagename|runs| 18:44:23 INFO - PROCESS | 1874 | |0;dojo.html;2909.9272518187045;2945.7790665700077;2955.8669859856304;2972.242078270041;2998.4902566563683;5165.63840531163;5213.791448342066;5229.50319719627;5289.497497737713;5341.973290133549;5283.339582552181;5291.391489978653;5383.4616538346145;5400.405955344909;5419.729013549327;4757.8810529736775;4762.333273341735;4817.590504807095;4820.831270905519;4840.128776820171;4879.414470263573;4922.458529561745;4950.232713929347;4982.377202849644;5034.874128146807;4736.313234581001;4911.8772030699165;4917.286993385939;4953.777080031389;5026.2209357549455;4791.113643975876;4804.8798780030775;4852.078105160008;4854.150523658345;4908.950910490885;4775.928361074587;4790.904181916377;4799.880002999918;4803.063402636487;4869.342638743795;5271.789128434858;5291.523762861361;5304.893902121936;5310.654807437504;5321.49445802652;5474.945250547484;5532.917006244909;5538.972305138448;5576.86057848553;5597.356304025048;5622.57830662702;5641.52047075995;5646.57650676201;5646.830595082154;5706.914396284059;5259.158534634478;5260.132078207092;5260.316158899357;5260.473952604709;5330.200469929541;5630.521405680481;5693.28833895763;5723.8855222896145;5729.541636669056;5761.3950535194035;5518.448155184449;5520.861978450571;5540.445955404508;5580.637258578261;5590.860228494239;5563.248961390242;5569.248151499496;5605.32736071674;5618.69097199658;5619.129034999582;5398.271233383443;5488.9725551371985;5512.118061110203;5598.300212473441;5678.630888992202;5549.361823390362;5581.190727344512;5588.0779671354585;5646;5653.067243904792;4334.804933777988;4340.088581397942;4340.175366680383;4340.913181736348;4392.363107349481;3866.2654095721723;3870.7096967727534;3870.7290489666016;3904.1801221743185;3924.234774219057;5331;5347.839564813062;5411.458854114636;5415.296011518556;5419.512243897989;5629.183768353566;5641.52047075995;5643.01247281724;5644.463775941279;5649.322081350264;5274.630775845731;5317.73411329432;5338.946610533845;5361.892762144736;5424.62027658068;4767.237242041326;4800.015996720664;4816.301636262722;4854.927176092361;4864.927026094611;5831.212786273968;5838.211841401441;5842.357340692605;5842.737076831468;5901.822945311647;5657.43425657431;5665.971670141623;5669.801556945487;5670.489655930986;5676.035074037341;5634.211210430461;5704.030314846569;5767.307923049261;5817.360090389969;5840.036393994943;5523.143912693539;5553.805616803393;5556.277683901067;5620.494155526022;5639.295088113986;5626;5668.064769313182;5673.773049078156;5675.432456754292;5678.6876721779045;5428.430014848384;5437.673739575638;5496.340439147328;5527.143292789624;5542.445755424425;5447.4824891635235;5524.58565607573;5528.640638358494;5586.301712285965;5595.496405323541;5432.266644003009;5466.480684334982;5474.507294343528;5474.644148130359;5475.315585551806;5361.865953351197;5455.099908515051;5479.04116779554;5511.311086114236;5576.079946808731;5319.281896943863;5335.013022590833;5373.167159090347;5382.515573598395;5440.238366628754 18:44:23 INFO - PROCESS | 1874 | |1;ext.html;553.7397423211091;588.991165132523;598.0789584040575;613.8281281241249;626.0984182776801;31828.36343273136;42290.77092290773;43087.78456107723;43221.135577288434;43263.78368108159;45019.54980450195;45328.3200751989;45418.772906135426;45479.54520454795;45481;8137.064237612691;8355.707550235744;8998.100189980982;9029.277657787361;9150.725478235663;10556.577736890553;10832.891671083267;10838.945805270963;11152.888471115306;11186.384748838811;10879.347239165636;10996.835047474295;11006;11010.614628488005;11014.44927753613;8355.206255405727;8632.956835215846;10685.572577096907;10810.945945270263;10877.07544858684;10390.116840068604;10824.350538967608;10827.783444331148;10829.566817327297;10834.566617335297;41871.7906410466;42278.40633546152;42334.57665423337;42341;42342.882855857395;3313.718333941618;3638.0359204810748;3658.2500587379523;3708.35103856824;3985.7608543487486;31236.531452028237;32128.839355803306;32162.195945101324;32273.35453290921;32311.838440807645;3963.5869619110886;4138.730982486159;4185.414042034117;4220.388043733641;4304.870853874389;324861;327624;327668;327678;328468;34395.31209375799;56802.715986419804;56850.14724779132;56912;57069.714651426475;34669.65330346664;36269.45595816065;36405.27189456196;36461.63538364635;36495.81752091223;15287.847121528643;15392.30734616945;15400.922995385175;15407.537773866577;15409.229538523028;12025.398730063462;12313.938430307791;12876.420561074774;12994.220346779224;13237.867621323663;8576.313894888393;8599.828003439896;8637.870431943526;8692.391532592785;8714.21572058505;3325.2684409429885;3361.3613413451853;3376.0547046826928;3398.6261511233743;3404.4042292598697;1013.2046343620223;1031.3863251365426;1036.7356324137447;1076.725435014082;1147.8106112491346;31612.36775264482;31871.681283187336;32373.838130809196;32634.510482342783;32639.67360326414;1080.810858099845;1095.2880627592128;1106.2145876427826;1151.1884121694186;1154.5035634677001;16706.665866682597;17119.052008949795;17215;17325.7401138983;17621;528223;535703;535907;535958;536002;57400;57831;58021;58027.709861451265;58037;1194.3371428856894;1205.1744554980041;1262.5517941130765;1290.909181741427;1349.750296195207;30101.087600950836;33406.665933341326;33416.16459588432;33438.49842252417;33473.66526334705;49620.503794963035;50117.49882501274;50134.24798627951;50211.47250857365;50346;382009;382768;383009;384148;384482;27695.169144925683;28574.714252857204;28638.856805715837;28914.855425723577;29776.702232977394;43522.34716479192;43724.56275437332;43828.34257486076;44046.33930490981;45194.548054520346;36885.72942599711;37375.252494950495;37452.62547374491;37990.62009379871;39069.41395879007;37399.252014959005;37699;37807;37926.62073379231;37939.24121517499;37749.4337584931;38021.61978380291;38462.807685961394;39497.60502394939;39585.406218907316;31700.682993170696;33551;34362.312753744285;34521.65478345184;35026.299474009866;26646.600300995113;26881.596776048766;27159.456810864067;27194.864025680537;27224.455510890068 18:44:23 INFO - PROCESS | 1874 | |2;jquery.html;373.0859394483516;395.4187344603432;399.22749479756675;406.8474322129202;419.0550309053087;13158.5394511192;15139.924300378496;18517.44447666569;18908.243670253207;19269.229230830784;18604.534886627807;18691.81308186918;19217.519562010955;19235.615287694236;19375.70936435951;5271.26202331672;5550.750216240269;5698.116791897262;5742.569307301948;5750.137479378085;5586.469285417878;5724.370319264877;5749.453801888835;5753.309602847664;5758.395368486312;5574.470425309589;5667.433256674341;5703.914441283384;5711.314642242936;5711.628744131639;5810.389909059551;5875.059990401515;5890.204822348971;5892.175095486636;5894.2632170978195;5609.270794796691;5724.112762521816;5727.828165155052;5734.168545560912;5786.47921687046;13958;14144.858551414458;14159.78760318596;17060.744088838677;22399.77600223993;2146.3239079689847;2261.9321420357414;2356.2821779474602;2719.619253304539;2795.8397265134945;9941.75145621358;10119;10518.053375196268;10564.788704225872;11535.942320288428;1757.2268201991083;1805.8103899090538;1809.4752521768671;1810.6921823290072;1814.5917168637084;79754;84373;84465.57767211125;84512;85987.57006214991;10820.13438924884;10890.891091089146;10896.509657065451;11258.549658013588;11476.19666623345;15536.300866461037;15567.455139070078;15582.376704931903;15594.844051559567;15606.9219653901;8033.277005069572;8271.751847444586;8362.45644033136;8391.958040209882;8477.406581539357;7894.21057894206;7998.3201427879285;8206.261436470746;8247.711330103417;8296.087430382653;4628.83799067031;4677.391939047902;4710.4347478302825;4755.643326750507;4900.730459824744;1933.7776155741988;1936.4577918182931;1947.279506582545;1964.7445832041742;1992.810682985114;854.5513605357113;963.1812958984947;966.9613215471302;985.9260555458369;990.1979396688705;18950;19353.8064619352;19399.3210237641;19469.221231150594;19881.403557893285;921.9216366608779;981.4798156976814;1012.448215722433;1025.7692019295598;1040.1678657074463;9994.600215991424;10741.570337186426;10762.612825422055;10782.06093260965;10847.143469292114;467775;469143;475397;483800;484674;74019.62990185014;89745.55127224322;90228;90333;90379;963.8216929867857;997.3816233934862;1026.5893642543042;1030.1964467715195;1032.0505135275564;15993.200339983186;16553.751693724364;16836.663266734842;16933.15334233259;17265.82734172642;40741.796291018356;41056;41204.175916482105;41494;41666.58333416627;52185.47814521806;56237.718811407314;56656;56865;57316.14025789533;8185.181481852005;8249.175082491944;8583.141685831617;8594.914050859412;8737.519436430803;11391.202615816814;12165.270083795001;12338.321392323509;12518.186317889307;12978.740425191254;10794.73013174677;11149.052330551995;11511.42442877836;11521.135914806255;11612.12909031809;10788.352698838095;10858.2399232053;10868.61959831402;11097.33415995043;11202.607908723156;10339.84490232632;11234.662960111209;11302.604408845651;11304.660860174208;11704;8539.701110461105;8711.956440217758;8728.170823771985;8825.911740882766;8882.600282987152;8685.305175585812;10360.637377691744;11247.325160490396;11296.910071636774;11748.70628234301 18:44:23 INFO - PROCESS | 1874 | |3;mootools.html;1341.1081630715576;1462.875655569277;1492.7238460884735;1603.3826976614007;1761.55080454484;7715.922840771591;8352.665893364267;8566.700165494209;8638.697645582399;8838.558072096386;8593.742187734362;8614.52620105894;8698.304135669146;8704.738857834274;8713.695020674279;2297.5290065536524;2301.252093069759;2314.513952070059;2319.69843920291;2324.523472688095;2274.79526842585;2323.7211534615785;2332.183735692511;2332.3236261484235;2333.94165145871;2131.6909048188004;2152;2155.2133471282946;2196.2203417786695;2198.263581700124;2060.2994981706292;2075.906584203707;2079.064421010551;2080.126346934291;2080.396684961367;2234.5866014787316;2244.046280330869;2244.450109723112;2244.887755612213;2253.6506841439605;8565.571721413904;8811.206991370807;8932.19610235082;8932.240759535382;8935.195832375117;5070.797168113271;5113.795448182068;5197.116490196676;5250.107481728115;5280.3663560372615;3173.6953582039537;3303.96696033039;3330.283988942389;3334.916627084318;3352.430086885235;1051.5005372448102;1100.944952752359;1103.332483847277;1124.1344164992909;1125.5047778977305;12117.33354665493;12126.211796233216;12186.81719774204;12226.266424014588;12227.572034978823;4385.495667998157;4561.840335588238;4648.83729069481;4713.434387873477;4731.905361892743;9624.903750962401;9625.374350667184;9630.422174669542;9630.807383852283;9633.08485693858;5339.5728341732565;5347.625666203328;5356.250124982505;5356.758945847446;5361.919571206435;4168.583141685868;4280.89297767552;4792.400949881265;4926.433460152057;4999.425066117443;3622.239329740731;3684.6315368463474;3748.4564738113368;3771.641694039062;3780.4896338994436;1126.7239526316105;1142.4744617475887;1161.488944864257;1167.9474423650956;1170.1867202294325;894.9015608283083;928.9317285122164;931.3527098666498;937.6436953957589;938.1299810293657;5775.613852675412;5872.794452194153;5880.529557635465;6035.637861728311;6085.330613632497;438.9846355377546;444.8798824317481;464.7745843266013;465.5902805531111;480.91343558159167;4072.6741860651077;4189.748615083044;4223.788810559459;4226;4229.97885010573;10453.320534165256;11015.173861960226;11126.388048657402;11130.888691113147;11134.220604557766;10986.395748233765;10998.890011099948;11010.119190464744;11013.063889569316;11056.723581910517;7668.463207575416;8199.877001844978;8291.792705182297;8309;8312.044114926739;5952.315483719254;6047.455728984412;6089;6118.816435506942;6217.720202590985;9989.500524973868;10138.493075346056;10233.437160956219;10285.125764310118;10311.535980881028;10131.7467063324;10297.588096476056;10334;10376.896231037594;10393.116585090353;7132.750353737594;7350.77947661571;7511;7678;7690.9230907692445;8559.486430814171;8755.430896991675;8824.64701411962;8859.202671759573;8866.42368246062;7744.419168562493;7756.664564724072;7880.645370958436;8018.879716804369;8057.113717491188;7472.738454154079;7608.429367797326;7616.543007419573;7688.500247484119;7761.9611901940125;6784.525083244125;6886.414654754201;6891.75878844238;6903.723851045902;6906.240313565403;4874.098291816025;4962.429320628174;4965;5105.106606343949;5105.591552675852;5156.922646160236;5234.555062819703;5241.790328386822;5299.49654782795;5338.172583249602 18:44:23 INFO - PROCESS | 1874 | |4;prototype.html;327.147780033014;334.00133600534406;338.7137868501117;340.50286581590876;342.69671340863357;5320.669832541865;5399.1091469907515;5455.5908306876945;5472.151816468444;5478.835634930953;5068.72122033288;5199.896002079956;5204.895902081956;5208.348956380452;5389.272448219499;4071.6131967463043;4146.087860670647;4200.138971510848;4209.379933257396;4222.1766755482695;4219.746815191083;4225.471816022997;4228.175505776375;4230.851920182794;4233.555476674951;4086.652634526069;4131.917361652765;4154.335306350985;4170.124273902484;4215.957840421603;4134.462519872397;4136.31750761124;4157.625813676784;4160.750354978696;4185.246655601991;3699.8705045323554;3703.0742314421395;3703.407454807245;3707.8516859325596;3708.554973403182;5053.837617347994;5211.140161873286;5257.605679574047;5272.261883336336;5304.310439642822;4771.332013518076;4800.967791924718;4820.325154478376;4839.080574690833;4841.927371089437;5527.281453411071;5533.339998000583;5548.639338442988;5597.264847897165;5599.300087489064;3799.468074469549;4064.7764372959477;4086.060206152572;4100.200460910124;4105.651019663333;5796.391378905197;5832.416758324133;5838.314375387327;5845.152452894306;5847.619904706223;4107.507099148122;4119;4120.340745480768;4136.110736191733;4146.730462519927;4086.9182616347507;4152.273352163359;4164.167166566699;4197.97901010493;4199.538050814469;4780.928286075712;4794.232922732346;4800.73595952219;4813.855584332406;4824.252240902666;5673.262475878109;5710.571707121982;5725.396888871122;5738.507987923086;5745.85635359111;1406.1071219775313;1432.2910159471128;1444.7905053767145;1445.1329202478428;1447.9791746818469;903.701778413122;917.5183028909876;921.5254144115786;929.4516235420999;930.6277489004452;4818.8795280118275;4838.685485443435;4839.829911408034;4934.407871055496;4948.579370753526;4636.44362676481;5020.774065167003;5051.898962020739;5085.533516244618;5088.847334579968;4733.739644319527;4761.880952976134;4793.736344501016;4846.442659094178;4848.684835485682;2654.681438227425;2659.630290400445;2660.068975858434;2716.8098233123887;2734.904278350248;4828.66199366048;5118.309028281209;5174.534291913746;5223.817166399158;5250.396204436539;5149.304843846107;5153.458886816905;5154.381474223117;5155.484451554889;5166.346769033873;5123.2827404163445;5192.273081768555;5198.506141916588;5236.690827293177;5250.8424747258405;4801.783919723551;5049.621278404208;5060.3927528696795;5080.288759573737;5123.718195499283;4991;5002.374703162105;5019.698818070928;5036.269740887624;5050.898982020412;5165.767540460613;5172.120739474373;5174.870628234325;5216.426193118678;5257.947420525898;3589.515415418885;3741.5323084614424;3779.4708740776864;3779.5464544253764;3783.735138540276;5090.643654944119;5500.642458240201;5653.568965344941;5657.151427285776;5660.377358490645;5499.725013749377;5576.776928922797;5584.553235741049;5627.606067575394;5688.127495614688;5507.476789704971;5585.050541408052;5617.045102332531;5625.690587017753;5686.772529098956;5531;5545.528630066489;5552.167174923632;5552.611317207919;5556.416576259435;5639.464250896158;5648.63283886546;5659.773609055592;5706.546185140263;5722.197507785035;5298.28473156119;5489.862753431196;5529.668219906818;5547.611667183259;5553.555715542828 18:44:23 INFO - PROCESS | 1874 | |5;yui.html;738.9371903388209;744.6686224630035;748.5994992678917;787.9251471110246;808.793757591814;16641.084740339276;17475.825241747578;17512.211950462228;17512.387066452677;17555;16950.406735764253;17071.743923841153;17092.059936703477;17092.572685682862;17117.40089096882;674.3256743256743;798.385243362609;818.0224631565281;819.8934138561979;820.4216027700487;687.2474640268897;782.8356045230508;795.8209402884363;803.602216902634;806.1495122645606;852.5992783391796;852.6333676519095;852.7953291210127;854.1031916487694;855.3456605696647;698.905647737554;855.7903313688124;859.0292968945083;859.3340161374922;859.4928991894812;521.8330134357008;597.4801922327603;611.3458599298752;613.239582917184;614.6220074654055;15575.221238938007;15590.454334098366;15633.765493517607;15639.296231669603;15639.68720625581;14122.788158177533;14280.928595356956;14563;14627;14709.779353309708;12943.741125177443;12984.805227921588;13104;13250.86749132506;13300.334983250896;4391.928776314358;4423.292273236299;4444.400005999181;4468.865934021985;4492.550744925481;78084;78363;79452.60273698652;80120;81445.59277203635;10882.673519794418;10933.945330273456;10937.343759374464;11114.444277786079;11403.42982850854;20835.062422191037;20836.583268334547;20854;20868.79131208699;20872;8376.706815261437;8384.622691978875;8616.13838616146;8688.478691278544;8709.129087091367;4733.479317275097;4936.185529387611;5083.491650834961;5185.585153187736;5186.740662966837;3452.1197094740683;3536.770109942897;3542.3977923753023;3547.255076433926;3549.6982756465472;617.1421723803916;620.1472974659843;622.9377062293735;630.6342321453546;631.2961048430966;549.3984087424265;593.6538404456434;631.4317114596807;655.3151956205681;709.3367701199395;8797.560121993874;9395.718128456158;9521.28590355726;9647.614095436104;9789.874164471177;563.3802816901376;601.4737105033096;657.86518256008;678.5216422422279;684.4148253243504;4514.187446259639;4562.543745625411;4696.2955556666775;4709.62323014158;4740.952590474051;119803;121808;123020.38489807493;125503;127736;105741;105904;107014;108031;111976;93381.53309233411;94062;95185.52407238058;96152;96947;8996.370254082152;9038.412503187272;9043.547822608974;9095;9101.271898248257;53128.468715313895;54114;54341;55461.44538554563;56069.832318445755;64541.67729161324;66159.66920165368;73570.63214683892;82185.58907205425;82890.58554707188;13091.541796037474;13449.394777234853;13721.725565488434;13923.234222117475;14115.717685646436;15353.846461535242;15364.692706146037;15597.532074037797;15658.295376708304;16396.508104756875;12592.74814503723;12684.809727853904;12886.677833053873;13807.585772426843;14396.928015359856;13927.303634818016;14248;14606.196659183423;14878;15112.546623601309;13146.60560183196;13623.460738845211;13779.724405512034;14087.788683169965;14745.1890146043;11039.95634812365;11091;11160.218784684994;11451.198416111125;11941.491487490952;10612.590618071361;11639.243449175776;11736.823947640622;11792.882071179178;12034.277943323079 18:44:23 INFO - PROCESS | 1874 | __end_tp_report 18:44:23 INFO - PROCESS | 1874 | __start_cc_report 18:44:23 INFO - PROCESS | 1874 | _x_x_mozilla_cycle_collect,1030 18:44:23 INFO - PROCESS | 1874 | __end_cc_report 18:44:23 INFO - PROCESS | 1874 | __startTimestamp1474681463814__endTimestamp 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | ------- Summary: start ------- 18:44:23 INFO - PROCESS | 1874 | Number of tests: 6 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | [#0] dojo.html Cycles:165 Average:5239.78 Median:5432.27 stddev:587.80 (10.8%) stddev-sans-first:560.47 18:44:23 INFO - PROCESS | 1874 | Values: 2909.9 2945.8 2955.9 2972.2 2998.5 5165.6 5213.8 5229.5 5289.5 5342.0 5283.3 5291.4 5383.5 5400.4 5419.7 4757.9 4762.3 4817.6 4820.8 4840.1 4879.4 4922.5 4950.2 4982.4 5034.9 4736.3 4911.9 4917.3 4953.8 5026.2 4791.1 4804.9 4852.1 4854.2 4909.0 4775.9 4790.9 4799.9 4803.1 4869.3 5271.8 5291.5 5304.9 5310.7 5321.5 5474.9 5532.9 5539.0 5576.9 5597.4 5622.6 5641.5 5646.6 5646.8 5706.9 5259.2 5260.1 5260.3 5260.5 5330.2 5630.5 5693.3 5723.9 5729.5 5761.4 5518.4 5520.9 5540.4 5580.6 5590.9 5563.2 5569.2 5605.3 5618.7 5619.1 5398.3 5489.0 5512.1 5598.3 5678.6 5549.4 5581.2 5588.1 5646.0 5653.1 4334.8 4340.1 4340.2 4340.9 4392.4 3866.3 3870.7 3870.7 3904.2 3924.2 5331.0 5347.8 5411.5 5415.3 5419.5 5629.2 5641.5 5643.0 5644.5 5649.3 5274.6 5317.7 5338.9 5361.9 5424.6 4767.2 4800.0 4816.3 4854.9 4864.9 5831.2 5838.2 5842.4 5842.7 5901.8 5657.4 5666.0 5669.8 5670.5 5676.0 5634.2 5704.0 5767.3 5817.4 5840.0 5523.1 5553.8 5556.3 5620.5 5639.3 5626.0 5668.1 5673.8 5675.4 5678.7 5428.4 5437.7 5496.3 5527.1 5542.4 5447.5 5524.6 5528.6 5586.3 5595.5 5432.3 5466.5 5474.5 5474.6 5475.3 5361.9 5455.1 5479.0 5511.3 5576.1 5319.3 5335.0 5373.2 5382.5 5440.2 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | [#1] ext.html Cycles:180 Average:56523.50 Median:34847.98 stddev:112552.81 (323.0%) stddev-sans-first:112790.10 18:44:23 INFO - PROCESS | 1874 | Values: 553.7 589.0 598.1 613.8 626.1 31828.4 42290.8 43087.8 43221.1 43263.8 45019.5 45328.3 45418.8 45479.5 45481.0 8137.1 8355.7 8998.1 9029.3 9150.7 10556.6 10832.9 10838.9 11152.9 11186.4 10879.3 10996.8 11006.0 11010.6 11014.4 8355.2 8633.0 10685.6 10810.9 10877.1 10390.1 10824.4 10827.8 10829.6 10834.6 41871.8 42278.4 42334.6 42341.0 42342.9 3313.7 3638.0 3658.3 3708.4 3985.8 31236.5 32128.8 32162.2 32273.4 32311.8 3963.6 4138.7 4185.4 4220.4 4304.9 324861.0 327624.0 327668.0 327678.0 328468.0 34395.3 56802.7 56850.1 56912.0 57069.7 34669.7 36269.5 36405.3 36461.6 36495.8 15287.8 15392.3 15400.9 15407.5 15409.2 12025.4 12313.9 12876.4 12994.2 13237.9 8576.3 8599.8 8637.9 8692.4 8714.2 3325.3 3361.4 3376.1 3398.6 3404.4 1013.2 1031.4 1036.7 1076.7 1147.8 31612.4 31871.7 32373.8 32634.5 32639.7 1080.8 1095.3 1106.2 1151.2 1154.5 16706.7 17119.1 17215.0 17325.7 17621.0 528223.0 535703.0 535907.0 535958.0 536002.0 57400.0 57831.0 58021.0 58027.7 58037.0 1194.3 1205.2 1262.6 1290.9 1349.8 30101.1 33406.7 33416.2 33438.5 33473.7 49620.5 50117.5 50134.2 50211.5 50346.0 382009.0 382768.0 383009.0 384148.0 384482.0 27695.2 28574.7 28638.9 28914.9 29776.7 43522.3 43724.6 43828.3 44046.3 45194.5 36885.7 37375.3 37452.6 37990.6 39069.4 37399.3 37699.0 37807.0 37926.6 37939.2 37749.4 38021.6 38462.8 39497.6 39585.4 31700.7 33551.0 34362.3 34521.7 35026.3 26646.6 26881.6 27159.5 27194.9 27224.5 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | [#2] jquery.html Cycles:180 Average:28145.40 Median:403.04 stddev:78572.77 (19495.2%) stddev-sans-first:78765.52 18:44:23 INFO - PROCESS | 1874 | Values: 373.1 395.4 399.2 406.8 419.1 13158.5 15139.9 18517.4 18908.2 19269.2 18604.5 18691.8 19217.5 19235.6 19375.7 5271.3 5550.8 5698.1 5742.6 5750.1 5586.5 5724.4 5749.5 5753.3 5758.4 5574.5 5667.4 5703.9 5711.3 5711.6 5810.4 5875.1 5890.2 5892.2 5894.3 5609.3 5724.1 5727.8 5734.2 5786.5 13958.0 14144.9 14159.8 17060.7 22399.8 2146.3 2261.9 2356.3 2719.6 2795.8 9941.8 10119.0 10518.1 10564.8 11535.9 1757.2 1805.8 1809.5 1810.7 1814.6 79754.0 84373.0 84465.6 84512.0 85987.6 10820.1 10890.9 10896.5 11258.5 11476.2 15536.3 15567.5 15582.4 15594.8 15606.9 8033.3 8271.8 8362.5 8392.0 8477.4 7894.2 7998.3 8206.3 8247.7 8296.1 4628.8 4677.4 4710.4 4755.6 4900.7 1933.8 1936.5 1947.3 1964.7 1992.8 854.6 963.2 967.0 985.9 990.2 18950.0 19353.8 19399.3 19469.2 19881.4 921.9 981.5 1012.4 1025.8 1040.2 9994.6 10741.6 10762.6 10782.1 10847.1 467775.0 469143.0 475397.0 483800.0 484674.0 74019.6 89745.6 90228.0 90333.0 90379.0 963.8 997.4 1026.6 1030.2 1032.1 15993.2 16553.8 16836.7 16933.2 17265.8 40741.8 41056.0 41204.2 41494.0 41666.6 52185.5 56237.7 56656.0 56865.0 57316.1 8185.2 8249.2 8583.1 8594.9 8737.5 11391.2 12165.3 12338.3 12518.2 12978.7 10794.7 11149.1 11511.4 11521.1 11612.1 10788.4 10858.2 10868.6 11097.3 11202.6 10339.8 11234.7 11302.6 11304.7 11704.0 8539.7 8712.0 8728.2 8825.9 8882.6 8685.3 10360.6 11247.3 11296.9 11748.7 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | [#3] mootools.html Cycles:180 Average:5737.68 Median:2636.66 stddev:3323.44 (126.0%) stddev-sans-first:3316.34 18:44:23 INFO - PROCESS | 1874 | Values: 1341.1 1462.9 1492.7 1603.4 1761.6 7715.9 8352.7 8566.7 8638.7 8838.6 8593.7 8614.5 8698.3 8704.7 8713.7 2297.5 2301.3 2314.5 2319.7 2324.5 2274.8 2323.7 2332.2 2332.3 2333.9 2131.7 2152.0 2155.2 2196.2 2198.3 2060.3 2075.9 2079.1 2080.1 2080.4 2234.6 2244.0 2244.5 2244.9 2253.7 8565.6 8811.2 8932.2 8932.2 8935.2 5070.8 5113.8 5197.1 5250.1 5280.4 3173.7 3304.0 3330.3 3334.9 3352.4 1051.5 1100.9 1103.3 1124.1 1125.5 12117.3 12126.2 12186.8 12226.3 12227.6 4385.5 4561.8 4648.8 4713.4 4731.9 9624.9 9625.4 9630.4 9630.8 9633.1 5339.6 5347.6 5356.3 5356.8 5361.9 4168.6 4280.9 4792.4 4926.4 4999.4 3622.2 3684.6 3748.5 3771.6 3780.5 1126.7 1142.5 1161.5 1167.9 1170.2 894.9 928.9 931.4 937.6 938.1 5775.6 5872.8 5880.5 6035.6 6085.3 439.0 444.9 464.8 465.6 480.9 4072.7 4189.7 4223.8 4226.0 4230.0 10453.3 11015.2 11126.4 11130.9 11134.2 10986.4 10998.9 11010.1 11013.1 11056.7 7668.5 8199.9 8291.8 8309.0 8312.0 5952.3 6047.5 6089.0 6118.8 6217.7 9989.5 10138.5 10233.4 10285.1 10311.5 10131.7 10297.6 10334.0 10376.9 10393.1 7132.8 7350.8 7511.0 7678.0 7690.9 8559.5 8755.4 8824.6 8859.2 8866.4 7744.4 7756.7 7880.6 8018.9 8057.1 7472.7 7608.4 7616.5 7688.5 7762.0 6784.5 6886.4 6891.8 6903.7 6906.2 4874.1 4962.4 4965.0 5105.1 5105.6 5156.9 5234.6 5241.8 5299.5 5338.2 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | [#4] prototype.html Cycles:180 Average:4533.66 Median:5052.87 stddev:1307.95 (25.9%) stddev-sans-first:1272.94 18:44:23 INFO - PROCESS | 1874 | Values: 327.1 334.0 338.7 340.5 342.7 5320.7 5399.1 5455.6 5472.2 5478.8 5068.7 5199.9 5204.9 5208.3 5389.3 4071.6 4146.1 4200.1 4209.4 4222.2 4219.7 4225.5 4228.2 4230.9 4233.6 4086.7 4131.9 4154.3 4170.1 4216.0 4134.5 4136.3 4157.6 4160.8 4185.2 3699.9 3703.1 3703.4 3707.9 3708.6 5053.8 5211.1 5257.6 5272.3 5304.3 4771.3 4801.0 4820.3 4839.1 4841.9 5527.3 5533.3 5548.6 5597.3 5599.3 3799.5 4064.8 4086.1 4100.2 4105.7 5796.4 5832.4 5838.3 5845.2 5847.6 4107.5 4119.0 4120.3 4136.1 4146.7 4086.9 4152.3 4164.2 4198.0 4199.5 4780.9 4794.2 4800.7 4813.9 4824.3 5673.3 5710.6 5725.4 5738.5 5745.9 1406.1 1432.3 1444.8 1445.1 1448.0 903.7 917.5 921.5 929.5 930.6 4818.9 4838.7 4839.8 4934.4 4948.6 4636.4 5020.8 5051.9 5085.5 5088.8 4733.7 4761.9 4793.7 4846.4 4848.7 2654.7 2659.6 2660.1 2716.8 2734.9 4828.7 5118.3 5174.5 5223.8 5250.4 5149.3 5153.5 5154.4 5155.5 5166.3 5123.3 5192.3 5198.5 5236.7 5250.8 4801.8 5049.6 5060.4 5080.3 5123.7 4991.0 5002.4 5019.7 5036.3 5050.9 5165.8 5172.1 5174.9 5216.4 5257.9 3589.5 3741.5 3779.5 3779.5 3783.7 5090.6 5500.6 5653.6 5657.2 5660.4 5499.7 5576.8 5584.6 5627.6 5688.1 5507.5 5585.1 5617.0 5625.7 5686.8 5531.0 5545.5 5552.2 5552.6 5556.4 5639.5 5648.6 5659.8 5706.5 5722.2 5298.3 5489.9 5529.7 5547.6 5553.6 18:44:23 INFO - PROCESS | 1874 | 18:44:23 INFO - PROCESS | 1874 | [#5] yui.html Cycles:180 Average:21918.08 Median:4538.37 stddev:32083.29 (706.9%) stddev-sans-first:32133.88 18:44:23 INFO - PROCESS | 1874 | Values: 738.9 744.7 748.6 787.9 808.8 16641.1 17475.8 17512.2 17512.4 17555.0 16950.4 17071.7 17092.1 17092.6 17117.4 674.3 798.4 818.0 819.9 820.4 687.2 782.8 795.8 803.6 806.1 852.6 852.6 852.8 854.1 855.3 698.9 855.8 859.0 859.3 859.5 521.8 597.5 611.3 613.2 614.6 15575.2 15590.5 15633.8 15639.3 15639.7 14122.8 14280.9 14563.0 14627.0 14709.8 12943.7 12984.8 13104.0 13250.9 13300.3 4391.9 4423.3 4444.4 4468.9 4492.6 78084.0 78363.0 79452.6 80120.0 81445.6 10882.7 10933.9 10937.3 11114.4 11403.4 20835.1 20836.6 20854.0 20868.8 20872.0 8376.7 8384.6 8616.1 8688.5 8709.1 4733.5 4936.2 5083.5 5185.6 5186.7 3452.1 3536.8 3542.4 3547.3 3549.7 617.1 620.1 622.9 630.6 631.3 549.4 593.7 631.4 655.3 709.3 8797.6 9395.7 9521.3 9647.6 9789.9 563.4 601.5 657.9 678.5 684.4 4514.2 4562.5 4696.3 4709.6 4741.0 119803.0 121808.0 123020.4 125503.0 127736.0 105741.0 105904.0 107014.0 108031.0 111976.0 93381.5 94062.0 95185.5 96152.0 96947.0 8996.4 9038.4 9043.5 9095.0 9101.3 53128.5 54114.0 54341.0 55461.4 56069.8 64541.7 66159.7 73570.6 82185.6 82890.6 13091.5 13449.4 13721.7 13923.2 14115.7 15353.8 15364.7 15597.5 15658.3 16396.5 12592.7 12684.8 12886.7 13807.6 14396.9 13927.3 14248.0 14606.2 14878.0 15112.5 13146.6 13623.5 13779.7 14087.8 14745.2 11040.0 11091.0 11160.2 11451.2 11941.5 10612.6 11639.2 11736.8 11792.9 12034.3 18:44:23 INFO - PROCESS | 1874 | -------- Summary: end -------- 18:44:23 INFO - PROCESS | 1874 | 18:44:24 INFO - TEST-INFO | 1874: exit 0 18:44:24 INFO - TEST-OK | dromaeo_css | took 1115613ms 18:44:24 INFO - TEST-START | kraken 18:44:24 INFO - operating with platform_type : linux_ 18:44:24 INFO - Initialising browser for kraken test... 18:44:24 INFO - TEST-INFO | started process 2181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprMLmAX/profile http://localhost:49479/getInfo.html) 18:44:28 INFO - PROCESS | 2181 | __metrics Screen width/height:1600/1200 18:44:28 INFO - PROCESS | 2181 | colorDepth:24 18:44:28 INFO - PROCESS | 2181 | Browser inner width/height: 1024/697 18:44:28 INFO - PROCESS | 2181 | __metrics 18:44:29 INFO - PROCESS | 2181 | 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" 18:44:29 INFO - PROCESS | 2181 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:44:29 INFO - PROCESS | 2181 | console.error: PushService: 18:44:29 INFO - PROCESS | 2181 | stateChangeProcessEnqueue: Error transitioning state 18:44:29 INFO - PROCESS | 2181 | UnknownError 18:44:30 INFO - TEST-INFO | 2181: exit 0 18:44:30 INFO - Browser initialized. 18:44:30 INFO - Running cycle 1/1 for kraken test... 18:44:30 INFO - TEST-INFO | started process 2264 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprMLmAX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:44:42 INFO - PROCESS | 2264 | RSS: Main: 173105152 18:44:42 INFO - PROCESS | 2264 | 18:44:45 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49479/tests/kraken/driver.html?testName=audio-beat-detection) 18:44:45 INFO - PROCESS | 2264 | RSS: Main: 232185856 18:44:45 INFO - PROCESS | 2264 | 18:44:48 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49479/tests/kraken/driver.html?testName=audio-dft) 18:44:49 INFO - PROCESS | 2264 | RSS: Main: 217473024 18:44:49 INFO - PROCESS | 2264 | 18:44:52 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49479/tests/kraken/driver.html?testName=audio-fft) 18:44:52 INFO - PROCESS | 2264 | RSS: Main: 298618880 18:44:52 INFO - PROCESS | 2264 | 18:44:55 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49479/tests/kraken/driver.html?testName=audio-oscillator) 18:44:55 INFO - PROCESS | 2264 | RSS: Main: 207794176 18:44:55 INFO - PROCESS | 2264 | 18:44:57 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49479/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:44:58 INFO - PROCESS | 2264 | RSS: Main: 211914752 18:44:58 INFO - PROCESS | 2264 | 18:45:01 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49479/tests/kraken/driver.html?testName=imaging-darkroom) 18:45:01 INFO - PROCESS | 2264 | RSS: Main: 249393152 18:45:01 INFO - PROCESS | 2264 | 18:45:05 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49479/tests/kraken/driver.html?testName=imaging-desaturate) 18:45:05 INFO - PROCESS | 2264 | RSS: Main: 248111104 18:45:05 INFO - PROCESS | 2264 | 18:45:08 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49479/tests/kraken/driver.html?testName=json-parse-financial) 18:45:09 INFO - PROCESS | 2264 | RSS: Main: 248348672 18:45:09 INFO - PROCESS | 2264 | 18:45:10 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49479/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:45:11 INFO - PROCESS | 2264 | RSS: Main: 210575360 18:45:11 INFO - PROCESS | 2264 | 18:45:13 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:45:13 INFO - PROCESS | 2264 | RSS: Main: 218288128 18:45:13 INFO - PROCESS | 2264 | 18:45:15 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:45:16 INFO - PROCESS | 2264 | RSS: Main: 222638080 18:45:16 INFO - PROCESS | 2264 | 18:45:19 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:45:19 INFO - PROCESS | 2264 | RSS: Main: 224559104 18:45:19 INFO - PROCESS | 2264 | 18:45:22 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:45:23 INFO - PROCESS | 2264 | RSS: Main: 221868032 18:45:23 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:49479/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49479/tests/kraken/driver.html?testName=ai-astar) 18:45:24 INFO - PROCESS | 2264 | RSS: Main: 273285120 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | __start_tp_report 18:45:24 INFO - PROCESS | 2264 | _x_x_mozilla_page_load 18:45:24 INFO - PROCESS | 2264 | _x_x_mozilla_page_load_details 18:45:24 INFO - PROCESS | 2264 | |i|pagename|runs| 18:45:24 INFO - PROCESS | 2264 | |0;ai-astar;110;111;111;111;111;111;111;111;110;111 18:45:24 INFO - PROCESS | 2264 | |1;audio-beat-detection;152;124;126;167;119;124;118;121;151;120 18:45:24 INFO - PROCESS | 2264 | |2;audio-dft;162;160;160;160;163;161;161;161;161;161 18:45:24 INFO - PROCESS | 2264 | |3;audio-fft;75;84;76;102;76;76;77;76;108;76 18:45:24 INFO - PROCESS | 2264 | |4;audio-oscillator;82;83;108;84;83;84;111;83;82;115 18:45:24 INFO - PROCESS | 2264 | |5;imaging-gaussian-blur;117;117;116;117;117;118;118;117;117;117 18:45:24 INFO - PROCESS | 2264 | |6;imaging-darkroom;145;145;145;145;145;147;145;145;145;145 18:45:24 INFO - PROCESS | 2264 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 18:45:24 INFO - PROCESS | 2264 | |8;json-parse-financial;51;56;51;52;51;52;51;52;51;51 18:45:24 INFO - PROCESS | 2264 | |9;json-stringify-tinderbox;60;60;59;60;60;59;60;60;60;61 18:45:24 INFO - PROCESS | 2264 | |10;stanford-crypto-aes;80;86;81;79;84;81;83;78;86;77 18:45:24 INFO - PROCESS | 2264 | |11;stanford-crypto-ccm;130;132;122;138;122;133;121;121;158;131 18:45:24 INFO - PROCESS | 2264 | |12;stanford-crypto-pbkdf2;164;167;162;163;167;163;163;165;163;166 18:45:24 INFO - PROCESS | 2264 | |13;stanford-crypto-sha256-iterative;69;64;66;64;64;65;64;64;73;62 18:45:24 INFO - PROCESS | 2264 | __end_tp_report 18:45:24 INFO - PROCESS | 2264 | __start_cc_report 18:45:24 INFO - PROCESS | 2264 | _x_x_mozilla_cycle_collect,1646 18:45:24 INFO - PROCESS | 2264 | __end_cc_report 18:45:24 INFO - PROCESS | 2264 | __startTimestamp1474681524971__endTimestamp 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | ------- Summary: start ------- 18:45:24 INFO - PROCESS | 2264 | Number of tests: 14 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:45:24 INFO - PROCESS | 2264 | Values: 110.0 111.0 111.0 111.0 111.0 111.0 111.0 111.0 110.0 111.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:125.00 stddev:17.57 (14.1%) stddev-sans-first:17.12 18:45:24 INFO - PROCESS | 2264 | Values: 152.0 124.0 126.0 167.0 119.0 124.0 118.0 121.0 151.0 120.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 18:45:24 INFO - PROCESS | 2264 | Values: 162.0 160.0 160.0 160.0 163.0 161.0 161.0 161.0 161.0 161.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.00 stddev:12.16 (16.0%) stddev-sans-first:12.58 18:45:24 INFO - PROCESS | 2264 | Values: 75.0 84.0 76.0 102.0 76.0 76.0 77.0 76.0 108.0 76.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:83.00 stddev:13.80 (16.6%) stddev-sans-first:14.20 18:45:24 INFO - PROCESS | 2264 | Values: 82.0 83.0 108.0 84.0 83.0 84.0 111.0 83.0 82.0 115.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:45:24 INFO - PROCESS | 2264 | Values: 117.0 117.0 116.0 117.0 117.0 118.0 118.0 117.0 117.0 117.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 18:45:24 INFO - PROCESS | 2264 | Values: 145.0 145.0 145.0 145.0 145.0 147.0 145.0 145.0 145.0 145.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 18:45:24 INFO - PROCESS | 2264 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:51.50 stddev:1.55 (3.0%) stddev-sans-first:1.62 18:45:24 INFO - PROCESS | 2264 | Values: 51.0 56.0 51.0 52.0 51.0 52.0 51.0 52.0 51.0 51.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 18:45:24 INFO - PROCESS | 2264 | Values: 60.0 60.0 59.0 60.0 60.0 59.0 60.0 60.0 60.0 61.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:82.00 stddev:3.17 (3.9%) stddev-sans-first:3.32 18:45:24 INFO - PROCESS | 2264 | Values: 80.0 86.0 81.0 79.0 84.0 81.0 83.0 78.0 86.0 77.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:131.50 stddev:11.28 (8.6%) stddev-sans-first:11.96 18:45:24 INFO - PROCESS | 2264 | Values: 130.0 132.0 122.0 138.0 122.0 133.0 121.0 121.0 158.0 131.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:164.50 stddev:1.83 (1.1%) stddev-sans-first:1.94 18:45:24 INFO - PROCESS | 2264 | Values: 164.0 167.0 162.0 163.0 167.0 163.0 163.0 165.0 163.0 166.0 18:45:24 INFO - PROCESS | 2264 | 18:45:24 INFO - PROCESS | 2264 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:64.50 stddev:3.21 (5.0%) stddev-sans-first:3.14 18:45:24 INFO - PROCESS | 2264 | Values: 69.0 64.0 66.0 64.0 64.0 65.0 64.0 64.0 73.0 62.0 18:45:24 INFO - PROCESS | 2264 | -------- Summary: end -------- 18:45:24 INFO - PROCESS | 2264 | 18:45:26 INFO - TEST-INFO | 2264: exit 0 18:45:26 INFO - TEST-OK | kraken | took 61411ms 18:45:26 INFO - SUITE-END | took 1177s 18:45:26 INFO - Completed test suite (00:19:37) 18:45:26 INFO - javascript benchmark 18:45:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2909.9272518187045, 2945.7790665700077, 2955.8669859856304, 2972.242078270041, 2998.4902566563683, 5165.63840531163, 5213.791448342066, 5229.50319719627, 5289.497497737713, 5341.973290133549, 5283.339582552181, 5291.391489978653, 5383.4616538346145, 5400.405955344909, 5419.729013549327, 4757.8810529736775, 4762.333273341735, 4817.590504807095, 4820.831270905519, 4840.128776820171, 4879.414470263573, 4922.458529561745, 4950.232713929347, 4982.377202849644, 5034.874128146807, 4736.313234581001, 4911.8772030699165, 4917.286993385939, 4953.777080031389, 5026.2209357549455, 4791.113643975876, 4804.8798780030775, 4852.078105160008, 4854.150523658345, 4908.950910490885, 4775.928361074587, 4790.904181916377, 4799.880002999918, 4803.063402636487, 4869.342638743795, 5271.789128434858, 5291.523762861361, 5304.893902121936, 5310.654807437504, 5321.49445802652, 5474.945250547484, 5532.917006244909, 5538.972305138448, 5576.86057848553, 5597.356304025048, 5622.57830662702, 5641.52047075995, 5646.57650676201, 5646.830595082154, 5706.914396284059, 5259.158534634478, 5260.132078207092, 5260.316158899357, 5260.473952604709, 5330.200469929541, 5630.521405680481, 5693.28833895763, 5723.8855222896145, 5729.541636669056, 5761.3950535194035, 5518.448155184449, 5520.861978450571, 5540.445955404508, 5580.637258578261, 5590.860228494239, 5563.248961390242, 5569.248151499496, 5605.32736071674, 5618.69097199658, 5619.129034999582, 5398.271233383443, 5488.9725551371985, 5512.118061110203, 5598.300212473441, 5678.630888992202, 5549.361823390362, 5581.190727344512, 5588.0779671354585, 5646.0, 5653.067243904792, 4334.804933777988, 4340.088581397942, 4340.175366680383, 4340.913181736348, 4392.363107349481, 3866.2654095721723, 3870.7096967727534, 3870.7290489666016, 3904.1801221743185, 3924.234774219057, 5331.0, 5347.839564813062, 5411.458854114636, 5415.296011518556, 5419.512243897989, 5629.183768353566, 5641.52047075995, 5643.01247281724, 5644.463775941279, 5649.322081350264, 5274.630775845731, 5317.73411329432, 5338.946610533845, 5361.892762144736, 5424.62027658068, 4767.237242041326, 4800.015996720664, 4816.301636262722, 4854.927176092361, 4864.927026094611, 5831.212786273968, 5838.211841401441, 5842.357340692605, 5842.737076831468, 5901.822945311647, 5657.43425657431, 5665.971670141623, 5669.801556945487, 5670.489655930986, 5676.035074037341, 5634.211210430461, 5704.030314846569, 5767.307923049261, 5817.360090389969, 5840.036393994943, 5523.143912693539, 5553.805616803393, 5556.277683901067, 5620.494155526022, 5639.295088113986, 5626.0, 5668.064769313182, 5673.773049078156, 5675.432456754292, 5678.6876721779045, 5428.430014848384, 5437.673739575638, 5496.340439147328, 5527.143292789624, 5542.445755424425, 5447.4824891635235, 5524.58565607573, 5528.640638358494, 5586.301712285965, 5595.496405323541, 5432.266644003009, 5466.480684334982, 5474.507294343528, 5474.644148130359, 5475.315585551806, 5361.865953351197, 5455.099908515051, 5479.04116779554, 5511.311086114236, 5576.079946808731, 5319.281896943863, 5335.013022590833, 5373.167159090347, 5382.515573598395, 5440.238366628754], "value": 5199.5647183388755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.7397423211091, 588.991165132523, 598.0789584040575, 613.8281281241249, 626.0984182776801, 31828.36343273136, 42290.77092290773, 43087.78456107723, 43221.135577288434, 43263.78368108159, 45019.54980450195, 45328.3200751989, 45418.772906135426, 45479.54520454795, 45481.0, 8137.064237612691, 8355.707550235744, 8998.100189980982, 9029.277657787361, 9150.725478235663, 10556.577736890553, 10832.891671083267, 10838.945805270963, 11152.888471115306, 11186.384748838811, 10879.347239165636, 10996.835047474295, 11006.0, 11010.614628488005, 11014.44927753613, 8355.206255405727, 8632.956835215846, 10685.572577096907, 10810.945945270263, 10877.07544858684, 10390.116840068604, 10824.350538967608, 10827.783444331148, 10829.566817327297, 10834.566617335297, 41871.7906410466, 42278.40633546152, 42334.57665423337, 42341.0, 42342.882855857395, 3313.718333941618, 3638.0359204810748, 3658.2500587379523, 3708.35103856824, 3985.7608543487486, 31236.531452028237, 32128.839355803306, 32162.195945101324, 32273.35453290921, 32311.838440807645, 3963.5869619110886, 4138.730982486159, 4185.414042034117, 4220.388043733641, 4304.870853874389, 324861.0, 327624.0, 327668.0, 327678.0, 328468.0, 34395.31209375799, 56802.715986419804, 56850.14724779132, 56912.0, 57069.714651426475, 34669.65330346664, 36269.45595816065, 36405.27189456196, 36461.63538364635, 36495.81752091223, 15287.847121528643, 15392.30734616945, 15400.922995385175, 15407.537773866577, 15409.229538523028, 12025.398730063462, 12313.938430307791, 12876.420561074774, 12994.220346779224, 13237.867621323663, 8576.313894888393, 8599.828003439896, 8637.870431943526, 8692.391532592785, 8714.21572058505, 3325.2684409429885, 3361.3613413451853, 3376.0547046826928, 3398.6261511233743, 3404.4042292598697, 1013.2046343620223, 1031.3863251365426, 1036.7356324137447, 1076.725435014082, 1147.8106112491346, 31612.36775264482, 31871.681283187336, 32373.838130809196, 32634.510482342783, 32639.67360326414, 1080.810858099845, 1095.2880627592128, 1106.2145876427826, 1151.1884121694186, 1154.5035634677001, 16706.665866682597, 17119.052008949795, 17215.0, 17325.7401138983, 17621.0, 528223.0, 535703.0, 535907.0, 535958.0, 536002.0, 57400.0, 57831.0, 58021.0, 58027.709861451265, 58037.0, 1194.3371428856894, 1205.1744554980041, 1262.5517941130765, 1290.909181741427, 1349.750296195207, 30101.087600950836, 33406.665933341326, 33416.16459588432, 33438.49842252417, 33473.66526334705, 49620.503794963035, 50117.49882501274, 50134.24798627951, 50211.47250857365, 50346.0, 382009.0, 382768.0, 383009.0, 384148.0, 384482.0, 27695.169144925683, 28574.714252857204, 28638.856805715837, 28914.855425723577, 29776.702232977394, 43522.34716479192, 43724.56275437332, 43828.34257486076, 44046.33930490981, 45194.548054520346, 36885.72942599711, 37375.252494950495, 37452.62547374491, 37990.62009379871, 39069.41395879007, 37399.252014959005, 37699.0, 37807.0, 37926.62073379231, 37939.24121517499, 37749.4337584931, 38021.61978380291, 38462.807685961394, 39497.60502394939, 39585.406218907316, 31700.682993170696, 33551.0, 34362.312753744285, 34521.65478345184, 35026.299474009866, 26646.600300995113, 26881.596776048766, 27159.456810864067, 27194.864025680537, 27224.455510890068], "value": 18910.64300378175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.0859394483516, 395.4187344603432, 399.22749479756675, 406.8474322129202, 419.0550309053087, 13158.5394511192, 15139.924300378496, 18517.44447666569, 18908.243670253207, 19269.229230830784, 18604.534886627807, 18691.81308186918, 19217.519562010955, 19235.615287694236, 19375.70936435951, 5271.26202331672, 5550.750216240269, 5698.116791897262, 5742.569307301948, 5750.137479378085, 5586.469285417878, 5724.370319264877, 5749.453801888835, 5753.309602847664, 5758.395368486312, 5574.470425309589, 5667.433256674341, 5703.914441283384, 5711.314642242936, 5711.628744131639, 5810.389909059551, 5875.059990401515, 5890.204822348971, 5892.175095486636, 5894.2632170978195, 5609.270794796691, 5724.112762521816, 5727.828165155052, 5734.168545560912, 5786.47921687046, 13958.0, 14144.858551414458, 14159.78760318596, 17060.744088838677, 22399.77600223993, 2146.3239079689847, 2261.9321420357414, 2356.2821779474602, 2719.619253304539, 2795.8397265134945, 9941.75145621358, 10119.0, 10518.053375196268, 10564.788704225872, 11535.942320288428, 1757.2268201991083, 1805.8103899090538, 1809.4752521768671, 1810.6921823290072, 1814.5917168637084, 79754.0, 84373.0, 84465.57767211125, 84512.0, 85987.57006214991, 10820.13438924884, 10890.891091089146, 10896.509657065451, 11258.549658013588, 11476.19666623345, 15536.300866461037, 15567.455139070078, 15582.376704931903, 15594.844051559567, 15606.9219653901, 8033.277005069572, 8271.751847444586, 8362.45644033136, 8391.958040209882, 8477.406581539357, 7894.21057894206, 7998.3201427879285, 8206.261436470746, 8247.711330103417, 8296.087430382653, 4628.83799067031, 4677.391939047902, 4710.4347478302825, 4755.643326750507, 4900.730459824744, 1933.7776155741988, 1936.4577918182931, 1947.279506582545, 1964.7445832041742, 1992.810682985114, 854.5513605357113, 963.1812958984947, 966.9613215471302, 985.9260555458369, 990.1979396688705, 18950.0, 19353.8064619352, 19399.3210237641, 19469.221231150594, 19881.403557893285, 921.9216366608779, 981.4798156976814, 1012.448215722433, 1025.7692019295598, 1040.1678657074463, 9994.600215991424, 10741.570337186426, 10762.612825422055, 10782.06093260965, 10847.143469292114, 467775.0, 469143.0, 475397.0, 483800.0, 484674.0, 74019.62990185014, 89745.55127224322, 90228.0, 90333.0, 90379.0, 963.8216929867857, 997.3816233934862, 1026.5893642543042, 1030.1964467715195, 1032.0505135275564, 15993.200339983186, 16553.751693724364, 16836.663266734842, 16933.15334233259, 17265.82734172642, 40741.796291018356, 41056.0, 41204.175916482105, 41494.0, 41666.58333416627, 52185.47814521806, 56237.718811407314, 56656.0, 56865.0, 57316.14025789533, 8185.181481852005, 8249.175082491944, 8583.141685831617, 8594.914050859412, 8737.519436430803, 11391.202615816814, 12165.270083795001, 12338.321392323509, 12518.186317889307, 12978.740425191254, 10794.73013174677, 11149.052330551995, 11511.42442877836, 11521.135914806255, 11612.12909031809, 10788.352698838095, 10858.2399232053, 10868.61959831402, 11097.33415995043, 11202.607908723156, 10339.84490232632, 11234.662960111209, 11302.604408845651, 11304.660860174208, 11704.0, 8539.701110461105, 8711.956440217758, 8728.170823771985, 8825.911740882766, 8882.600282987152, 8685.305175585812, 10360.637377691744, 11247.325160490396, 11296.910071636774, 11748.70628234301], "value": 9005.397927525104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.1081630715576, 1462.875655569277, 1492.7238460884735, 1603.3826976614007, 1761.55080454484, 7715.922840771591, 8352.665893364267, 8566.700165494209, 8638.697645582399, 8838.558072096386, 8593.742187734362, 8614.52620105894, 8698.304135669146, 8704.738857834274, 8713.695020674279, 2297.5290065536524, 2301.252093069759, 2314.513952070059, 2319.69843920291, 2324.523472688095, 2274.79526842585, 2323.7211534615785, 2332.183735692511, 2332.3236261484235, 2333.94165145871, 2131.6909048188004, 2152.0, 2155.2133471282946, 2196.2203417786695, 2198.263581700124, 2060.2994981706292, 2075.906584203707, 2079.064421010551, 2080.126346934291, 2080.396684961367, 2234.5866014787316, 2244.046280330869, 2244.450109723112, 2244.887755612213, 2253.6506841439605, 8565.571721413904, 8811.206991370807, 8932.19610235082, 8932.240759535382, 8935.195832375117, 5070.797168113271, 5113.795448182068, 5197.116490196676, 5250.107481728115, 5280.3663560372615, 3173.6953582039537, 3303.96696033039, 3330.283988942389, 3334.916627084318, 3352.430086885235, 1051.5005372448102, 1100.944952752359, 1103.332483847277, 1124.1344164992909, 1125.5047778977305, 12117.33354665493, 12126.211796233216, 12186.81719774204, 12226.266424014588, 12227.572034978823, 4385.495667998157, 4561.840335588238, 4648.83729069481, 4713.434387873477, 4731.905361892743, 9624.903750962401, 9625.374350667184, 9630.422174669542, 9630.807383852283, 9633.08485693858, 5339.5728341732565, 5347.625666203328, 5356.250124982505, 5356.758945847446, 5361.919571206435, 4168.583141685868, 4280.89297767552, 4792.400949881265, 4926.433460152057, 4999.425066117443, 3622.239329740731, 3684.6315368463474, 3748.4564738113368, 3771.641694039062, 3780.4896338994436, 1126.7239526316105, 1142.4744617475887, 1161.488944864257, 1167.9474423650956, 1170.1867202294325, 894.9015608283083, 928.9317285122164, 931.3527098666498, 937.6436953957589, 938.1299810293657, 5775.613852675412, 5872.794452194153, 5880.529557635465, 6035.637861728311, 6085.330613632497, 438.9846355377546, 444.8798824317481, 464.7745843266013, 465.5902805531111, 480.91343558159167, 4072.6741860651077, 4189.748615083044, 4223.788810559459, 4226.0, 4229.97885010573, 10453.320534165256, 11015.173861960226, 11126.388048657402, 11130.888691113147, 11134.220604557766, 10986.395748233765, 10998.890011099948, 11010.119190464744, 11013.063889569316, 11056.723581910517, 7668.463207575416, 8199.877001844978, 8291.792705182297, 8309.0, 8312.044114926739, 5952.315483719254, 6047.455728984412, 6089.0, 6118.816435506942, 6217.720202590985, 9989.500524973868, 10138.493075346056, 10233.437160956219, 10285.125764310118, 10311.535980881028, 10131.7467063324, 10297.588096476056, 10334.0, 10376.896231037594, 10393.116585090353, 7132.750353737594, 7350.77947661571, 7511.0, 7678.0, 7690.9230907692445, 8559.486430814171, 8755.430896991675, 8824.64701411962, 8859.202671759573, 8866.42368246062, 7744.419168562493, 7756.664564724072, 7880.645370958436, 8018.879716804369, 8057.113717491188, 7472.738454154079, 7608.429367797326, 7616.543007419573, 7688.500247484119, 7761.9611901940125, 6784.525083244125, 6886.414654754201, 6891.75878844238, 6903.723851045902, 6906.240313565403, 4874.098291816025, 4962.429320628174, 4965.0, 5105.106606343949, 5105.591552675852, 5156.922646160236, 5234.555062819703, 5241.790328386822, 5299.49654782795, 5338.172583249602], "value": 4478.439760945245, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.147780033014, 334.00133600534406, 338.7137868501117, 340.50286581590876, 342.69671340863357, 5320.669832541865, 5399.1091469907515, 5455.5908306876945, 5472.151816468444, 5478.835634930953, 5068.72122033288, 5199.896002079956, 5204.895902081956, 5208.348956380452, 5389.272448219499, 4071.6131967463043, 4146.087860670647, 4200.138971510848, 4209.379933257396, 4222.1766755482695, 4219.746815191083, 4225.471816022997, 4228.175505776375, 4230.851920182794, 4233.555476674951, 4086.652634526069, 4131.917361652765, 4154.335306350985, 4170.124273902484, 4215.957840421603, 4134.462519872397, 4136.31750761124, 4157.625813676784, 4160.750354978696, 4185.246655601991, 3699.8705045323554, 3703.0742314421395, 3703.407454807245, 3707.8516859325596, 3708.554973403182, 5053.837617347994, 5211.140161873286, 5257.605679574047, 5272.261883336336, 5304.310439642822, 4771.332013518076, 4800.967791924718, 4820.325154478376, 4839.080574690833, 4841.927371089437, 5527.281453411071, 5533.339998000583, 5548.639338442988, 5597.264847897165, 5599.300087489064, 3799.468074469549, 4064.7764372959477, 4086.060206152572, 4100.200460910124, 4105.651019663333, 5796.391378905197, 5832.416758324133, 5838.314375387327, 5845.152452894306, 5847.619904706223, 4107.507099148122, 4119.0, 4120.340745480768, 4136.110736191733, 4146.730462519927, 4086.9182616347507, 4152.273352163359, 4164.167166566699, 4197.97901010493, 4199.538050814469, 4780.928286075712, 4794.232922732346, 4800.73595952219, 4813.855584332406, 4824.252240902666, 5673.262475878109, 5710.571707121982, 5725.396888871122, 5738.507987923086, 5745.85635359111, 1406.1071219775313, 1432.2910159471128, 1444.7905053767145, 1445.1329202478428, 1447.9791746818469, 903.701778413122, 917.5183028909876, 921.5254144115786, 929.4516235420999, 930.6277489004452, 4818.8795280118275, 4838.685485443435, 4839.829911408034, 4934.407871055496, 4948.579370753526, 4636.44362676481, 5020.774065167003, 5051.898962020739, 5085.533516244618, 5088.847334579968, 4733.739644319527, 4761.880952976134, 4793.736344501016, 4846.442659094178, 4848.684835485682, 2654.681438227425, 2659.630290400445, 2660.068975858434, 2716.8098233123887, 2734.904278350248, 4828.66199366048, 5118.309028281209, 5174.534291913746, 5223.817166399158, 5250.396204436539, 5149.304843846107, 5153.458886816905, 5154.381474223117, 5155.484451554889, 5166.346769033873, 5123.2827404163445, 5192.273081768555, 5198.506141916588, 5236.690827293177, 5250.8424747258405, 4801.783919723551, 5049.621278404208, 5060.3927528696795, 5080.288759573737, 5123.718195499283, 4991.0, 5002.374703162105, 5019.698818070928, 5036.269740887624, 5050.898982020412, 5165.767540460613, 5172.120739474373, 5174.870628234325, 5216.426193118678, 5257.947420525898, 3589.515415418885, 3741.5323084614424, 3779.4708740776864, 3779.5464544253764, 3783.735138540276, 5090.643654944119, 5500.642458240201, 5653.568965344941, 5657.151427285776, 5660.377358490645, 5499.725013749377, 5576.776928922797, 5584.553235741049, 5627.606067575394, 5688.127495614688, 5507.476789704971, 5585.050541408052, 5617.045102332531, 5625.690587017753, 5686.772529098956, 5531.0, 5545.528630066489, 5552.167174923632, 5552.611317207919, 5556.416576259435, 5639.464250896158, 5648.63283886546, 5659.773609055592, 5706.546185140263, 5722.197507785035, 5298.28473156119, 5489.862753431196, 5529.668219906818, 5547.611667183259, 5553.555715542828], "value": 4120.956829301155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.9371903388209, 744.6686224630035, 748.5994992678917, 787.9251471110246, 808.793757591814, 16641.084740339276, 17475.825241747578, 17512.211950462228, 17512.387066452677, 17555.0, 16950.406735764253, 17071.743923841153, 17092.059936703477, 17092.572685682862, 17117.40089096882, 674.3256743256743, 798.385243362609, 818.0224631565281, 819.8934138561979, 820.4216027700487, 687.2474640268897, 782.8356045230508, 795.8209402884363, 803.602216902634, 806.1495122645606, 852.5992783391796, 852.6333676519095, 852.7953291210127, 854.1031916487694, 855.3456605696647, 698.905647737554, 855.7903313688124, 859.0292968945083, 859.3340161374922, 859.4928991894812, 521.8330134357008, 597.4801922327603, 611.3458599298752, 613.239582917184, 614.6220074654055, 15575.221238938007, 15590.454334098366, 15633.765493517607, 15639.296231669603, 15639.68720625581, 14122.788158177533, 14280.928595356956, 14563.0, 14627.0, 14709.779353309708, 12943.741125177443, 12984.805227921588, 13104.0, 13250.86749132506, 13300.334983250896, 4391.928776314358, 4423.292273236299, 4444.400005999181, 4468.865934021985, 4492.550744925481, 78084.0, 78363.0, 79452.60273698652, 80120.0, 81445.59277203635, 10882.673519794418, 10933.945330273456, 10937.343759374464, 11114.444277786079, 11403.42982850854, 20835.062422191037, 20836.583268334547, 20854.0, 20868.79131208699, 20872.0, 8376.706815261437, 8384.622691978875, 8616.13838616146, 8688.478691278544, 8709.129087091367, 4733.479317275097, 4936.185529387611, 5083.491650834961, 5185.585153187736, 5186.740662966837, 3452.1197094740683, 3536.770109942897, 3542.3977923753023, 3547.255076433926, 3549.6982756465472, 617.1421723803916, 620.1472974659843, 622.9377062293735, 630.6342321453546, 631.2961048430966, 549.3984087424265, 593.6538404456434, 631.4317114596807, 655.3151956205681, 709.3367701199395, 8797.560121993874, 9395.718128456158, 9521.28590355726, 9647.614095436104, 9789.874164471177, 563.3802816901376, 601.4737105033096, 657.86518256008, 678.5216422422279, 684.4148253243504, 4514.187446259639, 4562.543745625411, 4696.2955556666775, 4709.62323014158, 4740.952590474051, 119803.0, 121808.0, 123020.38489807493, 125503.0, 127736.0, 105741.0, 105904.0, 107014.0, 108031.0, 111976.0, 93381.53309233411, 94062.0, 95185.52407238058, 96152.0, 96947.0, 8996.370254082152, 9038.412503187272, 9043.547822608974, 9095.0, 9101.271898248257, 53128.468715313895, 54114.0, 54341.0, 55461.44538554563, 56069.832318445755, 64541.67729161324, 66159.66920165368, 73570.63214683892, 82185.58907205425, 82890.58554707188, 13091.541796037474, 13449.394777234853, 13721.725565488434, 13923.234222117475, 14115.717685646436, 15353.846461535242, 15364.692706146037, 15597.532074037797, 15658.295376708304, 16396.508104756875, 12592.74814503723, 12684.809727853904, 12886.677833053873, 13807.585772426843, 14396.928015359856, 13927.303634818016, 14248.0, 14606.196659183423, 14878.0, 15112.546623601309, 13146.60560183196, 13623.460738845211, 13779.724405512034, 14087.788683169965, 14745.1890146043, 11039.95634812365, 11091.0, 11160.218784684994, 11451.198416111125, 11941.491487490952, 10612.590618071361, 11639.243449175776, 11736.823947640622, 11792.882071179178, 12034.277943323079], "value": 7697.765086457897, "unit": "score"}], "name": "dromaeo_css", "value": 7078.672764216688, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 110.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 126.0, 167.0, 119.0, 124.0, 118.0, 121.0, 151.0, 120.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 163.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 76.0, 102.0, 76.0, 76.0, 77.0, 76.0, 108.0, 76.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 108.0, 84.0, 83.0, 84.0, 111.0, 83.0, 82.0, 115.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 147.0, 145.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 56.0, 51.0, 52.0, 51.0, 52.0, 51.0, 52.0, 51.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 61.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 81.0, 79.0, 84.0, 81.0, 83.0, 78.0, 86.0, 77.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 122.0, 138.0, 122.0, 133.0, 121.0, 121.0, 158.0, 131.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 162.0, 163.0, 167.0, 163.0, 163.0, 165.0, 163.0, 166.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 66.0, 64.0, 64.0, 65.0, 64.0, 64.0, 73.0, 62.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1489.2, "alertThreshold": 2.0}]} 18:45:26 INFO - Return code: 0 18:45:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:45:26 INFO - # TBPL SUCCESS # 18:45:26 INFO - Running post-action listener: _resource_record_post_action 18:45:26 INFO - [mozharness: 2016-09-24 01:45:26.770147Z] Finished run-tests step (success) 18:45:26 INFO - Running post-run listener: _resource_record_post_run 18:45:26 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 392282112; Read time: 0; Write time: 302584 18:45:26 INFO - TinderboxPrint: CPU usage
13.1% 18:45:26 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:45:26 INFO - TinderboxPrint: I/O write bytes / time
392,282,112 / 302,584 18:45:26 INFO - TinderboxPrint: CPU idle
8,281.5 (86.9%) 18:45:26 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 18:45:26 INFO - TinderboxPrint: Swap in / out
0 / 0 18:45:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103063552; Read time: 0; Write time: 74912 18:45:26 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288325632; Read time: 0; Write time: 227652 18:45:27 INFO - Running post-run listener: _upload_blobber_files 18:45:27 INFO - Blob upload gear active. 18:45:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:45:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:45:27 INFO - (blobuploader) - INFO - Open directory for files ... 18:45:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:45:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:45:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:28 INFO - (blobuploader) - INFO - Done attempting. 18:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:29 INFO - (blobuploader) - INFO - Done attempting. 18:45:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:45:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:45:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:35 INFO - (blobuploader) - INFO - Done attempting. 18:45:35 INFO - (blobuploader) - INFO - Iteration through files over. 18:45:35 INFO - Return code: 0 18:45:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:45:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:45:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7d328d72a7ce0b81b66dcc798c5e89d7985e3ec29074fdcb663061988e3bd90d745e325c48667f39830912ffbce48464e53a17c6fdb0c462c9323210f839b17f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45d4782ca0c73a4e064df9a47e6a09082a2737b0e76bb04c4be6b8819ab2bd3484d2b1a71f82b45e07ab658dae522bfe496f4579f50c0b0f00e5377b123e4b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69e94c8ff7d2b569b415fa69b52d6cdc43b3d4b645d30ce1fddeb4115adba2feee660388e3995aad6827791b84f094298ed54604c71417e094b5e8b8f7bf0019"} 18:45:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:45:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:45:35 INFO - Contents: 18:45:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7d328d72a7ce0b81b66dcc798c5e89d7985e3ec29074fdcb663061988e3bd90d745e325c48667f39830912ffbce48464e53a17c6fdb0c462c9323210f839b17f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45d4782ca0c73a4e064df9a47e6a09082a2737b0e76bb04c4be6b8819ab2bd3484d2b1a71f82b45e07ab658dae522bfe496f4579f50c0b0f00e5377b123e4b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69e94c8ff7d2b569b415fa69b52d6cdc43b3d4b645d30ce1fddeb4115adba2feee660388e3995aad6827791b84f094298ed54604c71417e094b5e8b8f7bf0019"} 18:45:35 INFO - Running post-run listener: copy_logs_to_upload_dir 18:45:35 INFO - Copying logs to upload dir... 18:45:35 INFO - mkdir: /builds/slave/test/build/upload/logs 18:45:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.245061 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-23 18:45:35.453841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 18:45:35.457084) ========= 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=62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7d328d72a7ce0b81b66dcc798c5e89d7985e3ec29074fdcb663061988e3bd90d745e325c48667f39830912ffbce48464e53a17c6fdb0c462c9323210f839b17f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45d4782ca0c73a4e064df9a47e6a09082a2737b0e76bb04c4be6b8819ab2bd3484d2b1a71f82b45e07ab658dae522bfe496f4579f50c0b0f00e5377b123e4b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69e94c8ff7d2b569b415fa69b52d6cdc43b3d4b645d30ce1fddeb4115adba2feee660388e3995aad6827791b84f094298ed54604c71417e094b5e8b8f7bf0019"} build_url:https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011374 build_url: 'https://queue.taskcluster.net/v1/task/EdMqB6gSQ7SqPW_CUF6JrA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7d328d72a7ce0b81b66dcc798c5e89d7985e3ec29074fdcb663061988e3bd90d745e325c48667f39830912ffbce48464e53a17c6fdb0c462c9323210f839b17f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45d4782ca0c73a4e064df9a47e6a09082a2737b0e76bb04c4be6b8819ab2bd3484d2b1a71f82b45e07ab658dae522bfe496f4579f50c0b0f00e5377b123e4b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69e94c8ff7d2b569b415fa69b52d6cdc43b3d4b645d30ce1fddeb4115adba2feee660388e3995aad6827791b84f094298ed54604c71417e094b5e8b8f7bf0019"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 18:45:35.482143) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:45:35.482438) ========= 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=62c568580acbfdaac3eec7be000001dc-1474680292.639236-487380656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:45:35.565649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 18:45:35.565963) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 18:45:35.566266) ========= ========= Total master_lag: 0.18 =========