builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1479137663.6 results: success (0) buildid: 20161114015620 builduid: 7575ab475a3f4481a1ee0dc2e232252d revision: 0f867a8846778f927cfb3871e58cc61f6193ad5d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.602380) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.602839) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.603129) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.618005) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.618404) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 07:34:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.6M=0s 2016-11-14 07:34:24 (70.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.068074 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.700556) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.700887) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020380 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.750997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:23.751365) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0f867a8846778f927cfb3871e58cc61f6193ad5d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0f867a8846778f927cfb3871e58cc61f6193ad5d --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=c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 07:34:24,546 truncating revision to first 12 chars 2016-11-14 07:34:24,547 Setting DEBUG logging. 2016-11-14 07:34:24,547 attempt 1/10 2016-11-14 07:34:24,547 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0f867a884677?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 07:34:24,865 unpacking tar archive at: mozilla-aurora-0f867a884677/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666535 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:24.432409) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:24.432895) ========= script_repo_revision: 0f867a8846778f927cfb3871e58cc61f6193ad5d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:24.433384) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:24.433664) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:34:24.443815) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-14 07:34:24.444419) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:34:25 INFO - MultiFileLogger online at 20161114 07:34:25 in /builds/slave/test 07:34:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:34:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:34:25 INFO - {'append_to_log': False, 07:34:25 INFO - 'base_work_dir': '/builds/slave/test', 07:34:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:34:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:34:25 INFO - 'branch': 'Mozilla-Aurora', 07:34:25 INFO - 'buildbot_json_path': 'buildprops.json', 07:34:25 INFO - 'config_files': ('talos/linux_config.py',), 07:34:25 INFO - 'default_actions': ('clobber', 07:34:25 INFO - 'read-buildbot-config', 07:34:25 INFO - 'download-and-extract', 07:34:25 INFO - 'populate-webroot', 07:34:25 INFO - 'create-virtualenv', 07:34:25 INFO - 'install', 07:34:25 INFO - 'run-tests'), 07:34:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:34:25 INFO - 'download_minidump_stackwalk': True, 07:34:25 INFO - 'download_symbols': 'ondemand', 07:34:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:34:25 INFO - 'tooltool.py': '/tools/tooltool.py', 07:34:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:34:25 INFO - '/tools/misc-python/virtualenv.py')}, 07:34:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:34:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:34:25 INFO - 'installer_path': 'installer.exe', 07:34:25 INFO - 'log_level': 'info', 07:34:25 INFO - 'log_name': 'talos', 07:34:25 INFO - 'log_to_console': True, 07:34:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:34:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:34:25 INFO - 'opt_config_files': (), 07:34:25 INFO - 'pip_index': False, 07:34:25 INFO - 'sps_profile': False, 07:34:25 INFO - 'sps_profile_interval': 0, 07:34:25 INFO - 'suite': 'dromaeojs', 07:34:25 INFO - 'system_bits': '32', 07:34:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:34:25 INFO - 'title': 'talos-linux64-ix-004', 07:34:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:34:25 INFO - 'use_talos_json': True, 07:34:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:34:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:34:25 INFO - 'work_dir': 'build'} 07:34:25 INFO - [mozharness: 2016-11-14 15:34:25.340240Z] Running clobber step. 07:34:25 INFO - Running pre-action listener: _resource_record_pre_action 07:34:25 INFO - Running main action method: clobber 07:34:25 INFO - rmtree: /builds/slave/test/build 07:34:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:34:25 INFO - Running post-action listener: _resource_record_post_action 07:34:25 INFO - [mozharness: 2016-11-14 15:34:25.841092Z] Finished clobber step (success) 07:34:25 INFO - [mozharness: 2016-11-14 15:34:25.841193Z] Running read-buildbot-config step. 07:34:25 INFO - Running pre-action listener: _resource_record_pre_action 07:34:25 INFO - Running main action method: read_buildbot_config 07:34:25 INFO - Using buildbot properties: 07:34:25 INFO - { 07:34:25 INFO - "project": "", 07:34:25 INFO - "product": "firefox", 07:34:25 INFO - "script_repo_revision": "production", 07:34:25 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:34:25 INFO - "repository": "", 07:34:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:34:25 INFO - "buildid": "20161114015620", 07:34:25 INFO - "pgo_build": "True", 07:34:25 INFO - "basedir": "/builds/slave/test", 07:34:25 INFO - "buildnumber": 590, 07:34:25 INFO - "slavename": "talos-linux64-ix-004", 07:34:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:34:25 INFO - "platform": "ubuntu64_hw", 07:34:25 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:34:25 INFO - "branch": "mozilla-aurora", 07:34:25 INFO - "repo_path": "releases/mozilla-aurora", 07:34:25 INFO - "revision": "0f867a8846778f927cfb3871e58cc61f6193ad5d", 07:34:25 INFO - "stage_platform": "linux64-pgo", 07:34:25 INFO - "builduid": "7575ab475a3f4481a1ee0dc2e232252d", 07:34:25 INFO - "slavebuilddir": "test" 07:34:25 INFO - } 07:34:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 07:34:25 INFO - Running post-action listener: _resource_record_post_action 07:34:25 INFO - [mozharness: 2016-11-14 15:34:25.843726Z] Finished read-buildbot-config step (success) 07:34:25 INFO - [mozharness: 2016-11-14 15:34:25.843813Z] Running download-and-extract step. 07:34:25 INFO - Running pre-action listener: _resource_record_pre_action 07:34:25 INFO - Running main action method: download_and_extract 07:34:25 INFO - mkdir: /builds/slave/test/build/tests 07:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:25 INFO - trying https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:34:25 INFO - Downloading https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:34:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:34:26 INFO - Downloaded 1361 bytes. 07:34:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:34:26 INFO - Using the following test package requirements: 07:34:26 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:34:26 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:34:26 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:34:26 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'jsshell-linux-x86_64.zip'], 07:34:26 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:34:26 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:34:26 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:34:26 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:34:26 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:34:26 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:34:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:34:26 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:34:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:34:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:34:26 INFO - Fetch https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:34:26 INFO - Expected file size: 21962623 07:34:31 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:34:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:34:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:34:31 INFO - Fetch https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:34:31 INFO - Expected file size: 21962623 07:34:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 07:34:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:34:36 INFO - Fetch https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 07:34:37 INFO - Expected file size: 14414089 07:34:37 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')]} 07:34:37 INFO - trying https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:34:37 INFO - Downloading https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:34:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:34:38 INFO - Downloaded 64070902 bytes. 07:34:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:34:38 INFO - mkdir: /builds/slave/test/properties 07:34:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:34:38 INFO - Writing to file /builds/slave/test/properties/build_url 07:34:38 INFO - Contents: 07:34:38 INFO - build_url:https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:34:39 INFO - Running post-action listener: _resource_record_post_action 07:34:39 INFO - Running post-action listener: set_extra_try_arguments 07:34:39 INFO - [mozharness: 2016-11-14 15:34:39.457648Z] Finished download-and-extract step (success) 07:34:39 INFO - [mozharness: 2016-11-14 15:34:39.457877Z] Running populate-webroot step. 07:34:39 INFO - Running pre-action listener: _resource_record_pre_action 07:34:39 INFO - Running main action method: populate_webroot 07:34:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:34:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:34:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:34:39 INFO - '--tppagecycles', 07:34:39 INFO - '7'], 07:34:39 INFO - 'tests': ['tsvgm']}}, 07:34:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:34:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:34:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:34:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:34:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:34:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:34:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['damp', 'tps']}, 07:34:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'tests': ['damp', 'tps']}, 07:34:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['dromaeo_dom']}, 07:34:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:34:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['basic_compositor_video']}, 07:34:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:34:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['a11yr', 07:34:39 INFO - 'ts_paint', 07:34:39 INFO - 'tpaint', 07:34:39 INFO - 'sessionrestore', 07:34:39 INFO - 'sessionrestore_no_auto_restore', 07:34:39 INFO - 'tabpaint']}, 07:34:39 INFO - 'other-e10s': {'tests': ['a11yr', 07:34:39 INFO - 'ts_paint', 07:34:39 INFO - 'tpaint', 07:34:39 INFO - 'sessionrestore', 07:34:39 INFO - 'sessionrestore_no_auto_restore', 07:34:39 INFO - 'tabpaint']}, 07:34:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:34:39 INFO - 'ts_paint', 07:34:39 INFO - 'tpaint', 07:34:39 INFO - 'sessionrestore', 07:34:39 INFO - 'sessionrestore_no_auto_restore', 07:34:39 INFO - 'tabpaint']}, 07:34:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:34:39 INFO - 'ts_paint', 07:34:39 INFO - 'tpaint', 07:34:39 INFO - 'sessionrestore', 07:34:39 INFO - 'sessionrestore_no_auto_restore', 07:34:39 INFO - 'tabpaint']}, 07:34:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['a11yr', 07:34:39 INFO - 'ts_paint', 07:34:39 INFO - 'tpaint', 07:34:39 INFO - 'sessionrestore', 07:34:39 INFO - 'sessionrestore_no_auto_restore', 07:34:39 INFO - 'tabpaint']}, 07:34:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['a11yr', 07:34:39 INFO - 'ts_paint', 07:34:39 INFO - 'tpaint', 07:34:39 INFO - 'sessionrestore', 07:34:39 INFO - 'sessionrestore_no_auto_restore', 07:34:39 INFO - 'tabpaint']}, 07:34:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['tsvgx', 07:34:39 INFO - 'tsvgr_opacity', 07:34:39 INFO - 'tart', 07:34:39 INFO - 'tscrollx', 07:34:39 INFO - 'cart']}, 07:34:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:34:39 INFO - 'tsvgr_opacity', 07:34:39 INFO - 'tart', 07:34:39 INFO - 'tscrollx', 07:34:39 INFO - 'cart']}, 07:34:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'talos_options': ['--disable-e10s'], 07:34:39 INFO - 'tests': ['tp5o']}, 07:34:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'tests': ['tp5o']}, 07:34:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'talos_options': ['--disable-e10s', 07:34:39 INFO - '--xperf_path', 07:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:34:39 INFO - 'tests': ['tp5n']}, 07:34:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:39 INFO - 'talos_options': ['--xperf_path', 07:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:34:39 INFO - 'tests': ['tp5n']}}, 07:34:39 INFO - 'talos.zip': {'path': '', 07:34:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:34:39 INFO - Running post-action listener: _resource_record_post_action 07:34:39 INFO - [mozharness: 2016-11-14 15:34:39.474547Z] Finished populate-webroot step (success) 07:34:39 INFO - [mozharness: 2016-11-14 15:34:39.474635Z] Running create-virtualenv step. 07:34:39 INFO - Running pre-action listener: _resource_record_pre_action 07:34:39 INFO - Running main action method: create_virtualenv 07:34:39 INFO - Creating virtualenv /builds/slave/test/build/venv 07:34:39 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 07:34:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:34:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:34:39 INFO - Using real prefix '/usr' 07:34:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:34:40 INFO - Installing distribute.............................................................................................................................................................................................done. 07:34:42 INFO - Installing pip.................done. 07:34:42 INFO - Return code: 0 07:34:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:34:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:34:42 INFO - Using env: {'DISPLAY': ':0', 07:34:42 INFO - 'HOME': '/home/cltbld', 07:34:42 INFO - 'LANG': 'en_US.UTF-8', 07:34:42 INFO - 'LANGUAGE': 'en_US:en', 07:34:42 INFO - 'LOGNAME': 'cltbld', 07:34:42 INFO - 'MAIL': '/var/mail/cltbld', 07:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:42 INFO - 'MOZ_NO_REMOTE': '1', 07:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:42 INFO - 'NO_EM_RESTART': '1', 07:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:42 INFO - 'PWD': '/builds/slave/test', 07:34:42 INFO - 'SHELL': '/bin/bash', 07:34:42 INFO - 'SHLVL': '1', 07:34:42 INFO - 'TERM': 'linux', 07:34:42 INFO - 'TMOUT': '86400', 07:34:42 INFO - 'USER': 'cltbld', 07:34:42 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:42 INFO - '_': '/tools/buildbot/bin/python'} 07:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:42 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 07:34:42 INFO - Cleaning up... 07:34:42 INFO - Return code: 0 07:34:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:34:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:34:42 INFO - Using env: {'DISPLAY': ':0', 07:34:42 INFO - 'HOME': '/home/cltbld', 07:34:42 INFO - 'LANG': 'en_US.UTF-8', 07:34:42 INFO - 'LANGUAGE': 'en_US:en', 07:34:42 INFO - 'LOGNAME': 'cltbld', 07:34:42 INFO - 'MAIL': '/var/mail/cltbld', 07:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:42 INFO - 'MOZ_NO_REMOTE': '1', 07:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:42 INFO - 'NO_EM_RESTART': '1', 07:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:42 INFO - 'PWD': '/builds/slave/test', 07:34:42 INFO - 'SHELL': '/bin/bash', 07:34:42 INFO - 'SHLVL': '1', 07:34:42 INFO - 'TERM': 'linux', 07:34:42 INFO - 'TMOUT': '86400', 07:34:42 INFO - 'USER': 'cltbld', 07:34:42 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:42 INFO - '_': '/tools/buildbot/bin/python'} 07:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:42 INFO - Downloading/unpacking psutil>=3.1.1 07:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:34:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:34:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:34:44 INFO - Installing collected packages: psutil 07:34:44 INFO - Running setup.py install for psutil 07:34:44 INFO - building 'psutil._psutil_linux' extension 07:34:44 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 07:34:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:34:44 INFO - building 'psutil._psutil_posix' extension 07:34:44 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 07:34:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:34:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:34:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:34:45 INFO - Successfully installed psutil 07:34:45 INFO - Cleaning up... 07:34:45 INFO - Return code: 0 07:34:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:34:45 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')]} 07:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:45 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')]} 07:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:45 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:45 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 07:34:45 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 07:34:45 INFO - Using env: {'DISPLAY': ':0', 07:34:45 INFO - 'HOME': '/home/cltbld', 07:34:45 INFO - 'LANG': 'en_US.UTF-8', 07:34:45 INFO - 'LANGUAGE': 'en_US:en', 07:34:45 INFO - 'LOGNAME': 'cltbld', 07:34:45 INFO - 'MAIL': '/var/mail/cltbld', 07:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:45 INFO - 'MOZ_NO_REMOTE': '1', 07:34:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:45 INFO - 'NO_EM_RESTART': '1', 07:34:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:45 INFO - 'PWD': '/builds/slave/test', 07:34:45 INFO - 'SHELL': '/bin/bash', 07:34:45 INFO - 'SHLVL': '1', 07:34:45 INFO - 'TERM': 'linux', 07:34:45 INFO - 'TMOUT': '86400', 07:34:45 INFO - 'USER': 'cltbld', 07:34:45 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:45 INFO - '_': '/tools/buildbot/bin/python'} 07:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:34:47 INFO - Installing collected packages: mozsystemmonitor 07:34:47 INFO - Running setup.py install for mozsystemmonitor 07:34:47 INFO - Successfully installed mozsystemmonitor 07:34:47 INFO - Cleaning up... 07:34:47 INFO - Return code: 0 07:34:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:34:47 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')]} 07:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:47 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')]} 07:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:47 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:47 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 07:34:47 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 07:34:47 INFO - Using env: {'DISPLAY': ':0', 07:34:47 INFO - 'HOME': '/home/cltbld', 07:34:47 INFO - 'LANG': 'en_US.UTF-8', 07:34:47 INFO - 'LANGUAGE': 'en_US:en', 07:34:47 INFO - 'LOGNAME': 'cltbld', 07:34:47 INFO - 'MAIL': '/var/mail/cltbld', 07:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:47 INFO - 'MOZ_NO_REMOTE': '1', 07:34:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:47 INFO - 'NO_EM_RESTART': '1', 07:34:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:47 INFO - 'PWD': '/builds/slave/test', 07:34:47 INFO - 'SHELL': '/bin/bash', 07:34:47 INFO - 'SHLVL': '1', 07:34:47 INFO - 'TERM': 'linux', 07:34:47 INFO - 'TMOUT': '86400', 07:34:47 INFO - 'USER': 'cltbld', 07:34:47 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:47 INFO - '_': '/tools/buildbot/bin/python'} 07:34:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:47 INFO - Downloading/unpacking jsonschema==2.5.1 07:34:47 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 07:34:47 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 07:34:47 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 07:34:47 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 07:34:47 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 07:34:47 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 07:34:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:34:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:49 INFO - Downloading functools32-3.2.3-2.tar.gz 07:34:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:34:49 INFO - warning: no files found matching '*.txt' 07:34:49 INFO - no previously-included directories found matching 'build' 07:34:49 INFO - no previously-included directories found matching 'dist' 07:34:49 INFO - no previously-included directories found matching '.git*' 07:34:49 INFO - Installing collected packages: jsonschema, functools32 07:34:49 INFO - Running setup.py install for functools32 07:34:49 INFO - warning: no files found matching '*.txt' 07:34:49 INFO - no previously-included directories found matching 'build' 07:34:49 INFO - no previously-included directories found matching 'dist' 07:34:49 INFO - no previously-included directories found matching '.git*' 07:34:49 INFO - Successfully installed jsonschema functools32 07:34:49 INFO - Cleaning up... 07:34:50 INFO - Return code: 0 07:34:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:34:50 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')]} 07:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:50 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')]} 07:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:50 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:50 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 07:34:50 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 07:34:50 INFO - Using env: {'DISPLAY': ':0', 07:34:50 INFO - 'HOME': '/home/cltbld', 07:34:50 INFO - 'LANG': 'en_US.UTF-8', 07:34:50 INFO - 'LANGUAGE': 'en_US:en', 07:34:50 INFO - 'LOGNAME': 'cltbld', 07:34:50 INFO - 'MAIL': '/var/mail/cltbld', 07:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:50 INFO - 'MOZ_NO_REMOTE': '1', 07:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:50 INFO - 'NO_EM_RESTART': '1', 07:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:50 INFO - 'PWD': '/builds/slave/test', 07:34:50 INFO - 'SHELL': '/bin/bash', 07:34:50 INFO - 'SHLVL': '1', 07:34:50 INFO - 'TERM': 'linux', 07:34:50 INFO - 'TMOUT': '86400', 07:34:50 INFO - 'USER': 'cltbld', 07:34:50 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:50 INFO - '_': '/tools/buildbot/bin/python'} 07:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:34:50 INFO - Cleaning up... 07:34:50 INFO - Return code: 0 07:34:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:34:50 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')]} 07:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:50 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')]} 07:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:50 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:50 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 07:34:50 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 07:34:50 INFO - Using env: {'DISPLAY': ':0', 07:34:50 INFO - 'HOME': '/home/cltbld', 07:34:50 INFO - 'LANG': 'en_US.UTF-8', 07:34:50 INFO - 'LANGUAGE': 'en_US:en', 07:34:50 INFO - 'LOGNAME': 'cltbld', 07:34:50 INFO - 'MAIL': '/var/mail/cltbld', 07:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:50 INFO - 'MOZ_NO_REMOTE': '1', 07:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:50 INFO - 'NO_EM_RESTART': '1', 07:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:50 INFO - 'PWD': '/builds/slave/test', 07:34:50 INFO - 'SHELL': '/bin/bash', 07:34:50 INFO - 'SHLVL': '1', 07:34:50 INFO - 'TERM': 'linux', 07:34:50 INFO - 'TMOUT': '86400', 07:34:50 INFO - 'USER': 'cltbld', 07:34:50 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:50 INFO - '_': '/tools/buildbot/bin/python'} 07:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:50 INFO - Downloading/unpacking blobuploader==1.2.4 07:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:52 INFO - Downloading blobuploader-1.2.4.tar.gz 07:34:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:34:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:34:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 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 07:34:52 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 07:34:53 INFO - Downloading docopt-0.6.1.tar.gz 07:34:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:34:53 INFO - Installing collected packages: blobuploader, requests, docopt 07:34:53 INFO - Running setup.py install for blobuploader 07:34:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:34:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:34:53 INFO - Running setup.py install for requests 07:34:53 INFO - Running setup.py install for docopt 07:34:53 INFO - Successfully installed blobuploader requests docopt 07:34:53 INFO - Cleaning up... 07:34:53 INFO - Return code: 0 07:34:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:53 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:53 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 07:34:53 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 07:34:53 INFO - Using env: {'DISPLAY': ':0', 07:34:53 INFO - 'HOME': '/home/cltbld', 07:34:53 INFO - 'LANG': 'en_US.UTF-8', 07:34:53 INFO - 'LANGUAGE': 'en_US:en', 07:34:53 INFO - 'LOGNAME': 'cltbld', 07:34:53 INFO - 'MAIL': '/var/mail/cltbld', 07:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:53 INFO - 'MOZ_NO_REMOTE': '1', 07:34:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:53 INFO - 'NO_EM_RESTART': '1', 07:34:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:53 INFO - 'PWD': '/builds/slave/test', 07:34:53 INFO - 'SHELL': '/bin/bash', 07:34:53 INFO - 'SHLVL': '1', 07:34:53 INFO - 'TERM': 'linux', 07:34:53 INFO - 'TMOUT': '86400', 07:34:53 INFO - 'USER': 'cltbld', 07:34:53 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:53 INFO - '_': '/tools/buildbot/bin/python'} 07:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:53 INFO - Running setup.py (path:/tmp/pip-e4Jwqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:54 INFO - Running setup.py (path:/tmp/pip-9epo3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:54 INFO - Running setup.py (path:/tmp/pip-aW195G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:54 INFO - Running setup.py (path:/tmp/pip-4r5mcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:54 INFO - Running setup.py (path:/tmp/pip-J3SCZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:54 INFO - Running setup.py (path:/tmp/pip-UhiHm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:54 INFO - Running setup.py (path:/tmp/pip-Rdjlo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:54 INFO - Running setup.py (path:/tmp/pip-b40gb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:54 INFO - Running setup.py (path:/tmp/pip-mi_d7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:54 INFO - Running setup.py (path:/tmp/pip-kAKEMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:54 INFO - Running setup.py (path:/tmp/pip-yeLu1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:54 INFO - Running setup.py (path:/tmp/pip-9kl4lH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:54 INFO - Running setup.py (path:/tmp/pip-7rTrdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:54 INFO - Running setup.py (path:/tmp/pip-C553CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:54 INFO - Running setup.py (path:/tmp/pip-MhpKxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:55 INFO - Running setup.py (path:/tmp/pip-UEb2qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:55 INFO - Running setup.py (path:/tmp/pip-C7oeBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:34:55 INFO - Running setup.py install for manifestparser 07:34:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Running setup.py install for mozcrash 07:34:55 INFO - Running setup.py install for mozdebug 07:34:55 INFO - Running setup.py install for mozdevice 07:34:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Running setup.py install for mozfile 07:34:55 INFO - Running setup.py install for mozhttpd 07:34:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Running setup.py install for mozinfo 07:34:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Running setup.py install for mozInstall 07:34:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:34:55 INFO - Running setup.py install for mozleak 07:34:56 INFO - Running setup.py install for mozlog 07:34:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Running setup.py install for moznetwork 07:34:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Running setup.py install for mozprocess 07:34:56 INFO - Running setup.py install for mozprofile 07:34:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Running setup.py install for mozrunner 07:34:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Running setup.py install for mozscreenshot 07:34:56 INFO - Running setup.py install for moztest 07:34:56 INFO - Running setup.py install for mozversion 07:34:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:34:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:34:56 INFO - Cleaning up... 07:34:56 INFO - Return code: 0 07:34:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34: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')]} 07:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34: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')]} 07:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:56 INFO - Using env: {'DISPLAY': ':0', 07:34:56 INFO - 'HOME': '/home/cltbld', 07:34:57 INFO - 'LANG': 'en_US.UTF-8', 07:34:57 INFO - 'LANGUAGE': 'en_US:en', 07:34:57 INFO - 'LOGNAME': 'cltbld', 07:34:57 INFO - 'MAIL': '/var/mail/cltbld', 07:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:57 INFO - 'MOZ_NO_REMOTE': '1', 07:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:57 INFO - 'NO_EM_RESTART': '1', 07:34:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:57 INFO - 'PWD': '/builds/slave/test', 07:34:57 INFO - 'SHELL': '/bin/bash', 07:34:57 INFO - 'SHLVL': '1', 07:34:57 INFO - 'TERM': 'linux', 07:34:57 INFO - 'TMOUT': '86400', 07:34:57 INFO - 'USER': 'cltbld', 07:34:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:57 INFO - '_': '/tools/buildbot/bin/python'} 07:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:57 INFO - Running setup.py (path:/tmp/pip-9ihKTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:57 INFO - Running setup.py (path:/tmp/pip-tB640A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:57 INFO - Running setup.py (path:/tmp/pip-Nd5_Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:57 INFO - Running setup.py (path:/tmp/pip-4rXcCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:57 INFO - Running setup.py (path:/tmp/pip-3pjcgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:57 INFO - Running setup.py (path:/tmp/pip-YQnnAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:57 INFO - Running setup.py (path:/tmp/pip-by03Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:57 INFO - Running setup.py (path:/tmp/pip-sclMXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:57 INFO - Running setup.py (path:/tmp/pip-pTjhPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:57 INFO - Running setup.py (path:/tmp/pip-64lyTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:57 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)) 07:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:57 INFO - Running setup.py (path:/tmp/pip-8C_NKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:58 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)) 07:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:58 INFO - Running setup.py (path:/tmp/pip-ckjxFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:58 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)) 07:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:58 INFO - Running setup.py (path:/tmp/pip-Hd2ela-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:58 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)) 07:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:58 INFO - Running setup.py (path:/tmp/pip-wz80xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:58 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)) 07:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:58 INFO - Running setup.py (path:/tmp/pip-GJxRWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:58 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)) 07:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:58 INFO - Running setup.py (path:/tmp/pip-4Byxia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:58 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)) 07:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:58 INFO - Running setup.py (path:/tmp/pip-xkaIeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:58 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)) 07:34:58 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)) 07:34:58 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)) 07:34:58 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)) 07:34:58 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)) 07:34:58 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)) 07:34:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:34:58 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 07:34:58 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 07:34:58 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 07:34:58 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 07:34:58 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 07:34:58 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 07:35:00 INFO - Downloading blessings-1.6.tar.gz 07:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:35:00 INFO - Installing collected packages: blessings 07:35:00 INFO - Running setup.py install for blessings 07:35:00 INFO - Successfully installed blessings 07:35:00 INFO - Cleaning up... 07:35:00 INFO - Return code: 0 07:35:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:35:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:35:00 INFO - Reading from file tmpfile_stdout 07:35:00 INFO - Current package versions: 07:35:00 INFO - argparse == 1.2.1 07:35:00 INFO - blessings == 1.6 07:35:00 INFO - blobuploader == 1.2.4 07:35:00 INFO - docopt == 0.6.1 07:35:00 INFO - functools32 == 3.2.3-2 07:35:00 INFO - jsonschema == 2.5.1 07:35:00 INFO - manifestparser == 1.1 07:35:00 INFO - mozInstall == 1.12 07:35:00 INFO - mozcrash == 0.17 07:35:00 INFO - mozdebug == 0.1 07:35:00 INFO - mozdevice == 0.48 07:35:00 INFO - mozfile == 1.2 07:35:00 INFO - mozhttpd == 0.7 07:35:00 INFO - mozinfo == 0.9 07:35:00 INFO - mozleak == 0.1 07:35:00 INFO - mozlog == 3.4 07:35:00 INFO - moznetwork == 0.27 07:35:00 INFO - mozprocess == 0.23 07:35:00 INFO - mozprofile == 0.28 07:35:00 INFO - mozrunner == 6.12 07:35:00 INFO - mozscreenshot == 0.1 07:35:00 INFO - mozsystemmonitor == 0.3 07:35:00 INFO - moztest == 0.7 07:35:00 INFO - mozversion == 1.4 07:35:00 INFO - psutil == 3.1.1 07:35:00 INFO - requests == 1.2.3 07:35:00 INFO - wsgiref == 0.1.2 07:35:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:00 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:35:00 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 07:35:00 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 07:35:00 INFO - Using env: {'DISPLAY': ':0', 07:35:00 INFO - 'HOME': '/home/cltbld', 07:35:00 INFO - 'LANG': 'en_US.UTF-8', 07:35:00 INFO - 'LANGUAGE': 'en_US:en', 07:35:00 INFO - 'LOGNAME': 'cltbld', 07:35:00 INFO - 'MAIL': '/var/mail/cltbld', 07:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:00 INFO - 'MOZ_NO_REMOTE': '1', 07:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:35:00 INFO - 'NO_EM_RESTART': '1', 07:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:00 INFO - 'PWD': '/builds/slave/test', 07:35:00 INFO - 'SHELL': '/bin/bash', 07:35:00 INFO - 'SHLVL': '1', 07:35:00 INFO - 'TERM': 'linux', 07:35:00 INFO - 'TMOUT': '86400', 07:35:00 INFO - 'USER': 'cltbld', 07:35:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:00 INFO - '_': '/tools/buildbot/bin/python'} 07:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 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)) 07:35:01 INFO - Cleaning up... 07:35:01 INFO - Return code: 0 07:35:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:35:01 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')]} 07:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:01 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')]} 07:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:01 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 0x7f08aaad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x278eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', '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 07:35:01 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 07:35:01 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 07:35:01 INFO - Using env: {'DISPLAY': ':0', 07:35:01 INFO - 'HOME': '/home/cltbld', 07:35:01 INFO - 'LANG': 'en_US.UTF-8', 07:35:01 INFO - 'LANGUAGE': 'en_US:en', 07:35:01 INFO - 'LOGNAME': 'cltbld', 07:35:01 INFO - 'MAIL': '/var/mail/cltbld', 07:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:01 INFO - 'MOZ_NO_REMOTE': '1', 07:35:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:35:01 INFO - 'NO_EM_RESTART': '1', 07:35:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:01 INFO - 'PWD': '/builds/slave/test', 07:35:01 INFO - 'SHELL': '/bin/bash', 07:35:01 INFO - 'SHLVL': '1', 07:35:01 INFO - 'TERM': 'linux', 07:35:01 INFO - 'TMOUT': '86400', 07:35:01 INFO - 'USER': 'cltbld', 07:35:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:01 INFO - '_': '/tools/buildbot/bin/python'} 07:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:35:01 INFO - Cleaning up... 07:35:01 INFO - Return code: 0 07:35:01 INFO - Running post-action listener: _resource_record_post_action 07:35:01 INFO - Running post-action listener: _start_resource_monitoring 07:35:01 INFO - Starting resource monitoring. 07:35:01 INFO - [mozharness: 2016-11-14 15:35:01.298002Z] Finished create-virtualenv step (success) 07:35:01 INFO - [mozharness: 2016-11-14 15:35:01.298504Z] Running install step. 07:35:01 INFO - Running pre-action listener: _resource_record_pre_action 07:35:01 INFO - Running main action method: install 07:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:35:01 INFO - Reading from file tmpfile_stdout 07:35:01 INFO - Detecting whether we're running mozinstall >=1.0... 07:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:35:01 INFO - Reading from file tmpfile_stdout 07:35:01 INFO - Output received: 07:35:01 INFO - Usage: mozinstall [options] installer 07:35:01 INFO - Options: 07:35:01 INFO - -h, --help show this help message and exit 07:35:01 INFO - -d DEST, --destination=DEST 07:35:01 INFO - Directory to install application into. [default: 07:35:01 INFO - "/builds/slave/test"] 07:35:01 INFO - --app=APP Application being installed. [default: firefox] 07:35:01 INFO - mkdir: /builds/slave/test/build/application 07:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:35:17 INFO - Reading from file tmpfile_stdout 07:35:17 INFO - Output received: 07:35:17 INFO - /builds/slave/test/build/application/firefox/firefox 07:35:17 INFO - Running post-action listener: _resource_record_post_action 07:35:17 INFO - [mozharness: 2016-11-14 15:35:17.893074Z] Finished install step (success) 07:35:17 INFO - [mozharness: 2016-11-14 15:35:17.893322Z] Running run-tests step. 07:35:17 INFO - Running pre-action listener: _resource_record_pre_action 07:35:17 INFO - Running main action method: run_tests 07:35:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:35:17 INFO - Python 2.7.3 07:35:17 INFO - Return code: 0 07:35:17 INFO - grabbing minidump binary from tooltool 07:35: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')]} 07:35:17 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 0x278bf60>, '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 0x278cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x278d1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:35:17 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 07:35:17 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 07:35:17 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 07:35:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:35:17 INFO - Return code: 0 07:35:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:35:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:35:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:35:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:35:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:35:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 07:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 07:35:17 INFO - Using env: {'DISPLAY': ':0', 07:35:17 INFO - 'HOME': '/home/cltbld', 07:35:17 INFO - 'LANG': 'en_US.UTF-8', 07:35:17 INFO - 'LANGUAGE': 'en_US:en', 07:35:17 INFO - 'LOGNAME': 'cltbld', 07:35:17 INFO - 'MAIL': '/var/mail/cltbld', 07:35:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:35:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:17 INFO - 'MOZ_NO_REMOTE': '1', 07:35:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:35:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:35:17 INFO - 'NO_EM_RESTART': '1', 07:35:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:17 INFO - 'PWD': '/builds/slave/test', 07:35:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:35:17 INFO - 'SHELL': '/bin/bash', 07:35:17 INFO - 'SHLVL': '1', 07:35:17 INFO - 'TERM': 'linux', 07:35:17 INFO - 'TMOUT': '86400', 07:35:17 INFO - 'USER': 'cltbld', 07:35:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726', 07:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:17 INFO - '_': '/tools/buildbot/bin/python'} 07:35:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 07:35:18 INFO - mozversion application_buildid: 20161114015620 07:35:18 INFO - mozversion application_changeset: 0f867a8846778f927cfb3871e58cc61f6193ad5d 07:35:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:35:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:35:18 INFO - mozversion application_name: Firefox 07:35:18 INFO - mozversion application_remotingname: firefox-dev 07:35:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:35:18 INFO - mozversion application_vendor: Mozilla 07:35:18 INFO - mozversion application_version: 51.0a2 07:35:18 INFO - mozversion platform_buildid: 20161114015620 07:35:18 INFO - mozversion platform_changeset: 0f867a8846778f927cfb3871e58cc61f6193ad5d 07:35:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:35:18 INFO - mozversion platform_version: 51.0a2 07:35:18 INFO - using testdate: 1479137718 07:35:18 INFO - actual date: 1479137718 07:35:18 INFO - starting webserver on 'localhost:37422' 07:35:18 INFO - SUITE-START | Running 2 tests 07:35:18 INFO - TEST-START | dromaeo_css 07:35:18 INFO - operating with platform_type : linux_ 07:35:18 INFO - Initialising browser for dromaeo_css test... 07:35:18 INFO - TEST-INFO | started process 12344 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptol_GR/profile http://localhost:37422/getInfo.html) 07:35:22 INFO - PROCESS | 12344 | __metrics Screen width/height:1600/1200 07:35:22 INFO - PROCESS | 12344 | colorDepth:24 07:35:22 INFO - PROCESS | 12344 | Browser inner width/height: 1024/697 07:35:22 INFO - PROCESS | 12344 | __metrics 07:35:22 INFO - PROCESS | 12344 | 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" 07:35:22 INFO - PROCESS | 12344 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:35:23 INFO - PROCESS | 12344 | console.error: PushService: 07:35:23 INFO - PROCESS | 12344 | stateChangeProcessEnqueue: Error transitioning state 07:35:23 INFO - PROCESS | 12344 | UnknownError 07:35:23 INFO - TEST-INFO | 12344: exit 0 07:35:23 INFO - Browser initialized. 07:35:23 INFO - Running cycle 1/1 for dromaeo_css test... 07:35:23 INFO - TEST-INFO | started process 12435 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptol_GR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:35:35 INFO - PROCESS | 12435 | RSS: Main: 144474112 07:35:35 INFO - PROCESS | 12435 | 07:36:26 INFO - PROCESS | 12435 | 1479137786794 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] 07:38:35 INFO - PROCESS | 12435 | Cycle 1(1): loaded http://localhost:37422/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37422/tests/dromaeo/cssquery-ext.html) 07:38:36 INFO - PROCESS | 12435 | RSS: Main: 238215168 07:38:36 INFO - PROCESS | 12435 | 07:41:39 INFO - PROCESS | 12435 | Cycle 1(1): loaded http://localhost:37422/tests/dromaeo/cssquery-ext.html (next: http://localhost:37422/tests/dromaeo/cssquery-jquery.html) 07:41:39 INFO - PROCESS | 12435 | RSS: Main: 233533440 07:41:39 INFO - PROCESS | 12435 | 07:44:41 INFO - PROCESS | 12435 | Cycle 1(1): loaded http://localhost:37422/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37422/tests/dromaeo/cssquery-mootools.html) 07:44:42 INFO - PROCESS | 12435 | RSS: Main: 204283904 07:44:42 INFO - PROCESS | 12435 | 07:47:43 INFO - PROCESS | 12435 | Cycle 1(1): loaded http://localhost:37422/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37422/tests/dromaeo/cssquery-prototype.html) 07:47:44 INFO - PROCESS | 12435 | RSS: Main: 229089280 07:47:44 INFO - PROCESS | 12435 | 07:50:47 INFO - PROCESS | 12435 | Cycle 1(1): loaded http://localhost:37422/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37422/tests/dromaeo/cssquery-yui.html) 07:50:47 INFO - PROCESS | 12435 | RSS: Main: 177967104 07:50:47 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | Cycle 1(1): loaded http://localhost:37422/tests/dromaeo/cssquery-yui.html (next: http://localhost:37422/tests/dromaeo/cssquery-dojo.html) 07:53:50 INFO - PROCESS | 12435 | RSS: Main: 268279808 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | __start_tp_report 07:53:50 INFO - PROCESS | 12435 | _x_x_mozilla_page_load 07:53:50 INFO - PROCESS | 12435 | _x_x_mozilla_page_load_details 07:53:50 INFO - PROCESS | 12435 | |i|pagename|runs| 07:53:50 INFO - PROCESS | 12435 | |0;dojo.html;3107.440660681077;3142.7171554560086;3161.7944833585802;3182.7135557799793;3193.6167659880816;5492.5605951523885;5542.501174894259;5566.137248726443;5574.080276754341;5578.274824272844;5503.20203570482;5563.026470367679;5563.165525171226;5564;5581.386047534778;5014.874628134298;5018.322526458936;5045.924311135336;5076.492350764913;5118.897622047575;4919.99140176263;5066.898662026757;5115.897682046357;5124.743762811844;5126.051680439112;4906.386701662293;4963.652544321899;4980.252962055684;4994.101061808873;5007.123753343169;5028.245763135523;5062.772175252123;5063.341765570489;5112.718800465973;5140.897182056376;4944.431390390115;5025.798968041274;5072.442031376546;5077.060743762416;5083.8983220335385;5361.544268737162;5382.838514844561;5399.136138217906;5416.945830541684;5463.2078348639625;5788.305403351625;5803;5805.912911306334;5895.705214739246;5897.14491398749;5931.525477961796;5939.851503712399;5947.821565353003;5949.167116603679;5950.851228719273;5387.488188622075;5419.647722897999;5457.317835270591;5508.889822203533;5523.033469142924;6064.423879731418;6083.8479038023515;6087.66517841523;6089.086637004485;6110.663913484801;5793.15999180125;5797.101449275369;5809.50619197373;5813.94186058134;5819.301683797887;5734.369219385864;5796.333421656564;5816.389279125716;5864.9413505864395;5897.764089436375;5908.20239267702;5916.49709774674;5927.407259274124;5928.911066334009;5947.316058653224;5861.41385861419;5918.171455996164;5923.378045305267;5924.733386997509;5946.137810017523;4382.802773875183;4412.117576484716;4476.597106260452;4559.52125026874;4565.360849481075;4018.517777866675;4023.7786921719585;4036.1120553478186;4058.939115913264;4069.7965101745385;5562.443755624486;5565.248691426606;5566.471185237396;5582.051051321285;5582.748776305076;5754.625949313281;5797.072468405035;5934.19888315072;5940.049592065248;5946.316173640087;5520.66875987442;5527.613067085346;5598.692071936001;5627.8874422512135;5660.660360378391;5146.459621739664;5160.174372100446;5168.61235407346;5170.870728231749;5171.63798534099;5797.9420205797405;6179.134921111048;6185.938140618537;6190.102435146879;6196.690165491707;5713.114467257582;5870.911936321044;5881.2942446906645;5883.2057672213705;6020.759169633166;6148.569600127948;6169;6173.475254603409;6175;6175.197224360984;5877.441643043904;5878.265216847894;5883.970580147072;5887.470127688529;5888.440598143341;5691.4877661010705;5705.22979397776;5861.032929566573;5894.174815525744;5915.881682366415;5868.295804503487;5880.176775251553;5884.264466941632;5885.911711324419;5909.349971503045;5809.8547536311935;5867.706614669164;5870.5010074144175;5872.559558033079;5875.853103672443;5543.334799824047;5546.584006199632;5612.663240205601;5618.073017951992;5791.710414479175;5787.102999035156;5787.623804452697;5799.246098007232;5810.680412577349;5832.037713777179;5650.660960342392;5652.830415087389;5652.943470565407;5658.264425624807;5662.03745363281 07:53:50 INFO - PROCESS | 12435 | |1;ext.html;599.337731806354;621.1180124223602;631.5200447659777;637.7289651897945;645.7675236914712;30901.38197236054;33590.16024599383;44558;45422.54577454225;45492;47798.76100619492;48151.51848481514;48268.27597586039;48280;48305.758471207686;8591.742247732578;9382.953085234582;9528.190103841182;9533.189678877303;9538.046195380475;8415;10910.672679819578;11131.332120072822;11168.888311116907;11510.482028308705;11419.54321827126;11501.654950351462;11514.654560363202;11519.712007199803;11520.539178432853;11249.325040497555;11293.71765705864;11302.604408845733;11319.886801131966;11333.0366918812;11105;11130.944345278303;11167.776644467147;11175.21773475857;11206.047485963622;45271.54728452706;45416.54583454156;45446;45475;45515.54484455146;3407.386670399327;3422.4524076147695;3452.223249768782;3452.4130897747445;3487.9476807847905;34481.827590862136;34542.48186277208;34854.12864678378;35082.64917350845;35592.28815423703;4586.128635559233;4691.62467002639;4694.976525117387;4798.904021919542;4830.541098595628;355952;356550;357350;358057;359296;59588.70205648944;60010.69994649999;60148.39851601516;60276;60277.39722602806;41053.384199237036;42222.78888605537;42385.788071060066;42639.57360426418;42670.1465970685;17279.56801079983;17384.4784656458;17415.216315265843;17418.91290543539;17425.65148697019;13154.671133221555;13549.187048777105;14670.926645366704;15360.385584576716;15487.225638718019;19474.929420652785;20158.496037599176;20202.58581899253;20304.289349872684;20370.59258814845;7276.708931642781;7335.376492998155;7393.8151546211775;7602.885956710654;7758.14660387357;891.7057371067532;1108.562117963416;1177.9823302650468;1188.0733028237987;1189.5301355964345;33635.32729345399;33965;33997.490037649455;34322.313553729284;34418.30965676804;1089.9885839082835;1142.0977427832086;1159.6984783956232;1207.824865394513;1279.6864768131682;17865.703856215012;17917.283308667767;17958.446971774414;18075.276988920297;18168.909155454137;554754;563277;563635;564111;564621;61722;62387.6880615594;62410.37589624136;62415;62422.68788656028;1226.5645695778048;1306.2750173653524;1379.2138481065697;1380.475419340644;1418.5531557559543;29404.411911762072;30999.945104575818;31453.68546314599;33082.33835323328;33164.668353316156;56083;56962;57010;57116.428835711115;57192;488065;490776;492370;492811;493594;24582.139625113035;25073;30580.694193057785;31022.689773102884;31391.84304078465;49233.2615010768;49253.75373123112;49477.75261123815;49746;50409.243861342846;42186.94638927257;42702.78648606737;43010;43681.78159109184;44058;43053.3541996864;43536.78231608822;43814.78092609644;44361.77819111013;45364.09271814479;43731.78134109309;44338.113237735706;44523;44871.77564112159;45064.774676126406;36786;38577.421338680506;38788.6121138785;38933.415998759476;39714;29281.121566353042;29442.411151777273;29695.851520742257;29978;29999.70000299969 07:53:50 INFO - PROCESS | 12435 | |2;jquery.html;417.6888218277382;420.1974229222186;426.15621070280855;426.29022677242386;446.886044058765;19121.617567648656;19320;20641.38075857723;20749.377518674428;20925.05837237324;19789.20843166275;20175.19299228033;20684.896575517138;20759.68860467094;20806.375808725763;5706.657600543981;5968.850778730544;5971.402859714016;5977.701114944257;5981.102834574827;5809.099589563624;5906.05503119501;6005.789797357093;6014.96992515039;6039.3960603939695;5983.640981541122;5985.461308482236;5987.670678112702;5995.820125396223;5997.220361353018;5737.856553586173;5893.675847828346;5924.022536281508;5936;5987.161797348374;5320.893582128336;5321.095413779628;5321.946780532184;5324.307839980777;5329.467053294678;24211.636825447633;24307.63538546923;24516.387090322707;24540.01839926401;24668.13661521838;2381.761823817622;2433.063270640816;2495.663085483455;2743.327884668249;2783.8190517616495;15263.236838158158;15398.692026159526;15441;15591.610209744733;15837.366505339773;1960.4118764370633;1985.990070049655;2026.493376655836;2083.8749675019394;2133.4026472587543;82973;83126.58436707778;83708.58145709294;84193.15806841975;87214.56392717996;10814.297070690538;11053.502592383362;11140.164487663458;11433.371164585862;11485.138614603771;15956.76064859028;15976.76034859478;15980.440684575984;15987;15998.520044398687;8765.167309105625;8815.250703690259;8914.064023277591;8926.821463570823;8961.283097352196;8445.113263107409;8537.103604121601;8568.957155214184;8710.782230444289;8757.824843503095;6809.7276108956075;6865.8328084225805;6912.308769123148;6952.165740111219;6966.268541803139;1826.9677632138066;1974.7531558555181;1976.6738488149294;2038.6840039793856;2060.05412260469;883.9425437346551;888.2849306308354;906.2613969614742;952.6189524190379;966.2077096780572;18535.53661158482;19607.117679704446;19745.901270493556;19783.208671652974;19908.90956449581;923.459776031016;936.3492372800974;949.5964215208509;967.2552134856121;984.2027957354566;10404.58381664725;11148;11233.943830280796;11387.772244555063;11388.20282580228;516284;519384;527967;531264;535359;82807;83920.58039709763;84135.579322103;84141;84306;1063.8510608514969;1071.3304184884448;1102.6967583914327;1107.9390633515234;1108.6895669212633;17245.31018759286;17788.46634600964;17823.910880445514;17945.73081403754;17986.91006544959;40093.398599021624;40136.59863401329;40596.59403405928;40833;42556.78721606372;51925.480745192064;52114;53049.734751325996;53758.731206345285;54577;8692;8693.782655433664;9021.729348119567;9130.132637399436;9242.584083716383;13376.866231337563;13418.060735748795;13624;13921.860781392057;14042.859571404564;11588.536458541901;11919.582814601446;11922.880771192524;12356.691082722644;12430.37848107573;11733;11968.640940771791;12135.393230338272;12366;12408.81386779217;11367.20429569922;11935.582254621047;12108.75782484363;12270.509179632714;12493.812592811297;8990.32572557074;9202.263818894606;9271.30465215098;9402;9409.717708468757;11819.881801181878;12157.08821838348;12235.265884046985;12329.321887296284;12525 07:53:50 INFO - PROCESS | 12435 | |3;mootools.html;1413.265102146883;1508.2684897824554;1543.3209387869338;1655.3709590355663;1847.5381154711322;7725.1116121645955;8882.156195161466;9203.631854725803;9219.907800921988;9530.332876698634;9040.593173307218;9120.17918387345;9123.817523649523;9128.224100951427;9129.817403651923;2448.706155261367;2486.17956074495;2489.3029951613576;2492.7382624824404;2495.3761559610098;2370.7866292033714;2481.3548477395916;2499.950000999979;2500.6749122614124;2501.3246423465744;2409.783119519243;2452.901883924641;2506.285708573057;2507.536105820429;2508.3729067733066;2334.8832558372183;2353.5528249632516;2359.8820058997067;2418.5525677749674;2419.68544089269;2330.731965823935;2335.474518233401;2342.156823543523;2384.6542251373626;2426.065964603623;7985.1615580363805;8281.21328473794;9290.72127836166;9401.10689484498;9423.199028082621;4930.063287975273;5074.923876141861;5458.863528411782;5610.298712660918;5620.325560932714;3541.8760343388117;3543.0787995121195;3558.3061303045915;3561.6082230954576;3612.6206748291315;1280.2574506786134;1289.1684863263142;1304.7651422743902;1325.7613629546677;1391.930403479822;12980.415881285271;12992.545260915917;13001.284929328884;13016.60950171487;13045.08684392093;4713.151632706145;4852.8301509447;4855.878603034882;4951.257311403318;4999.200127979506;10946.616868409568;11045.613403530839;11066.889331106748;11094.667159985212;11099.889001109885;5648.51987581052;5660.377358490563;5661.348944871393;5666.235058267163;5668.121441176624;5100.209467532539;5125.205593132996;5146.099432599355;5226.007058658919;5237.528622424;4415.448068991376;4431.822727090945;4474.731516108979;4508.977455112704;4532.614727748112;1430.3205977160765;1440.9351579178963;1446.5154173352046;1455.5924341184486;1465.4358072142088;918.6555041859302;984.3011461862158;1021.9642312519026;1037.7405648587853;1039.9896001040045;6104.328523862371;6222.28443728977;6344.714487847966;6387.680615969183;6517.380848819449;476.73541184642164;481.8120932836197;487.4808329129466;492.035610203997;510.59407770822287;4075.2335445345097;4175.164967006611;4194.622483976456;4227.513835908849;4274.294741367639;9719.271054670928;10083.294169408218;10916;11030.669079927615;11368.147388945863;11583.826242606368;11849.229800062969;11866.940665296792;11911.166218364631;11927.582534611247;8538.573071346407;8822.691205807765;8838.204561589488;8842.557872106368;8853.424527405698;6273.498120150286;6279.654618995999;6293.181886354744;6295.18162638854;6330.841728956813;10541.57833686644;10676.359418434919;10676.412797295912;10690.839637405601;10775.568977240822;10720.142388609049;10916.12670986335;10921.126309895348;10939.56241750321;11028.834567481656;7791.220877912164;7881.13307536182;7882.684692612232;7889.960550197212;7906.130325664058;8565.914340856512;8622.741317760478;8637;8669.349798764992;8748.518831464331;7783.961080194563;8060.959695201486;8159.959200203961;8223.835523289621;8391.790205244917;7768.4950478221;7890.881636775338;7911.683532658629;8113.8377232456205;8172.8365432689825;7131.714731410685;7210.675519601526;7276.781696549112;7312.085989251344;7328.487005909536;5177.301064356264;5288.233206185005;5310.044192045469;5330;5421.891562168656;5299.788008479618;5350.545203657577;5423.593230507807;5586.301712285965;5661.716914154358 07:53:50 INFO - PROCESS | 12435 | |4;prototype.html;336.52045834685566;348.73147676289256;355.24155927095643;357.456665867881;360.7438718509857;5386.757595908189;5420.728963551826;5544.8336549903515;5549.38956714761;5560.748831512918;5451.2640793492865;5457.5633949284065;5485.396606373296;5488.341399032121;5502.394736578973;4028;4300.763458009808;4325.248274448853;4347.739135651856;4387.517373088957;3797.962020379802;4137.089840235159;4235.597618226264;4253.2131555662145;4261.978690106545;4219.198352313066;4223.64099051581;4225.366195070733;4238.152369526092;4239.173361194568;4245.9787701061605;4249.405083288342;4249.44757181566;4253.001089487921;4255.468066491689;3497.510348551205;3608.9278214435826;3619.4208926571623;3757.9436308455397;3760.2103558252525;5222.529972302511;5224.190250511177;5228.633995620308;5230.712310822903;5236.7119808410525;4838.564529192389;4841.201201801734;4848.030393921194;4852.684575502581;4878.707277563358;5436.537894278991;5551.916721249185;5609.896288891666;5616.971915140398;5617.915731264035;4106.938395924063;4112.848402447319;4114.7119701620895;4119.237940980898;4119.752814831089;5487.99999999996;5592.916106258409;5849.912251316234;5900.557458190653;5904.0258357370985;4146.647534959562;4165.645920096765;4173.373993900939;4183.3515805050265;4195.706300558993;4225.126913698653;4230.64039556635;4237.91524169521;4239.364095385717;4246;4607.742206318947;4814.748165477001;4828.227483602606;4835.782389792468;4841.564259216688;5668.773249070074;5713.74288157034;5718.542516598662;5721.8569535761935;5732.742026608813;1462.2396353895915;1472.9263536823116;1481.6295926018495;1485.9554213373617;1489.1735087026589;913.3969882855679;954.4178051388648;956.2732323434101;959.6153558407352;968.1528662420383;4920.188168952154;4920.704757714549;4933.629977751683;4933.975330123327;4938.975305123451;5111.2465407171685;5126.666766660155;5132.897342053138;5192.247124166975;5194.92207616886;4634.443866736014;4849.636277279147;4862.246351815475;4872.634552408584;4902.607791376681;2698.8110832241946;2732.5764506501523;2735.5076086304657;2735.8768855401554;2744.5042451863783;5203.973980130151;5210.505002024802;5217.869553261123;5256.237845512379;5273.604479664041;4896.804127834846;5162.793488260428;5168.81909133186;5186.818461353834;5221.791128354823;5217.321748172713;5223.276318814782;5236.92144617831;5284.313039304866;5309.893802124013;5111;5118.8500829652385;5154.484551544815;5170.560502357192;5175.922361164661;5096.267269128514;5408.3509978802795;5841.007028805198;5854.297484301741;5861.267341582302;5708.200851880657;5884.852878678067;5893.734781934738;6009.519238460825;6024.849378765566;4199.559046300094;4220.261454245556;4236.436955641792;4262.976885547509;4274.722143060691;5766.452187042224;5855.59010869235;5862.94137058641;5898.852528686645;5916.243185577104;5736.655800651807;5746.626469279484;5758.4817366435545;5765.538756899522;5785.652860828363;5731.541476681772;5736.282964629421;5739.593799519144;5740.770369185353;5750.482456578928;5483.943521179558;5679.233303503974;5683.658980461186;5709.371969083314;5718.627529392833;5718;5764.279465066867;5772.538196944319;5773.826785196451;5774.509166720708;5586.972065139648;5629.662220266797;5633.267675202197;5640.802571909963;5647.378788333198 07:53:50 INFO - PROCESS | 12435 | |5;yui.html;729.8430837369967;777.4635501503965;868.7437206024222;876.9254613357864;920.7237828651407;15447.382104715813;15581.688366232696;16145.112018838989;16200.189990500472;16201.351945922164;15777.13225772582;15876.206189690527;15927.761083583755;15931.44239951602;16081.678366432665;625.0561651905632;835.8955130608674;852.071242345843;852.3692467573982;853.2917678326974;748.6631016042776;844.5481667307992;871.8822958900562;879.2921698033081;881.1849039638341;905.1717678324326;905.4341036851968;905.6060613633058;905.9637614495413;906.6237511432747;745.3403737692128;901.4861528928448;902.9683961061364;903.1284810158196;903.2367649336301;565.6408180805178;647.2879832184559;668.993310066898;673.0946876451166;674.2078058281548;15093.39626414942;15119.39522419102;15128.470503532433;15136.924315378352;15140.545783626509;14440.783388249185;14599.270036498132;14678.559643210721;15005.174715390649;15038.699226015528;13562.728745425135;13712.862871371259;13746.862531374658;13775.517856875062;13872.098313609584;4584.518625544303;4597.563231493003;4621.4223222097235;4628.4445866496235;4649.1053677932405;90456;90711;90942;91594;92223.53888230583;11557.30656160633;11687.427642987415;11715.882841171479;11955.282683039046;12053.337066461429;23631.881840590686;23699.763002370102;23726.406839829142;23727.1695490657;23735.881320593286;8951.552422378854;8989.640414383482;9060.365774395861;9063.093690631016;9414.105659962293;5047.646664733434;5060.7722652480725;5071.3153724247495;5167;5182.766775495112;3743.12036671382;3903.1413089120347;3905.2775236581324;3906.570277269498;3908;582.7173820696959;595.1429940885112;603.1706403694919;621.7264403662465;624.3693869192149;643.5720246036427;668.1014036121409;704.5138854190592;717.7631381644045;757.5984728094119;9655.08276713711;9690.37012594179;9742.95128524353;9892.60429582823;10003.199744020461;635.7870113511933;681.7068660476043;709.4714437743913;712.3126183233205;718.4360277182491;4451.443569553806;4760.809567617326;4768.356271903318;4788.928166077511;4803.153535252718;124347;124762;125531.86180326492;125817.37091314484;129057.35471322772;67965;90523;93687;114089;114776.42611787055;79958.6002069986;89770.55114724515;91143;91854.54072729594;93140.53429732943;9468.431894086378;9508.952455237679;9709.514524273616;9933.850992234979;10030.598776049168;53051;54053.45946540641;54275;54503.45496544984;56026;73503.63248183725;73546.63226684046;78709.60645196967;79225;79381.2061879397;13362.933185334012;13619.319034048456;13760.105593136414;13774.242416667179;13838.654033648836;13999.930000349934;14099.295035247991;14400.207988560729;14493.710125797636;14621.19583422922;13645.385957631732;13669.863301366859;13739.656508586966;14306.141631502143;14406.567802965928;12938.547150849676;13592.11651242666;13682.52111176104;13849.653758656113;14644.853551464776;12859.678508037374;13244.602661920158;13491.325433728078;13654.249016304198;13745.587632371045;10087;10404.47977601132;10423.895761042595;11019.173561983043;11050.778984420427;10795.514201860778;10989.120870330515;11616.186866919572;11643.301401915913;11660.943716895164 07:53:50 INFO - PROCESS | 12435 | __end_tp_report 07:53:50 INFO - PROCESS | 12435 | __start_cc_report 07:53:50 INFO - PROCESS | 12435 | _x_x_mozilla_cycle_collect,852 07:53:50 INFO - PROCESS | 12435 | __end_cc_report 07:53:50 INFO - PROCESS | 12435 | __startTimestamp1479138830561__endTimestamp 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | ------- Summary: start ------- 07:53:50 INFO - PROCESS | 12435 | Number of tests: 6 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | [#0] dojo.html Cycles:165 Average:5487.15 Median:5660.66 stddev:627.03 (11.1%) stddev-sans-first:600.52 07:53:50 INFO - PROCESS | 12435 | Values: 3107.4 3142.7 3161.8 3182.7 3193.6 5492.6 5542.5 5566.1 5574.1 5578.3 5503.2 5563.0 5563.2 5564.0 5581.4 5014.9 5018.3 5045.9 5076.5 5118.9 4920.0 5066.9 5115.9 5124.7 5126.1 4906.4 4963.7 4980.3 4994.1 5007.1 5028.2 5062.8 5063.3 5112.7 5140.9 4944.4 5025.8 5072.4 5077.1 5083.9 5361.5 5382.8 5399.1 5416.9 5463.2 5788.3 5803.0 5805.9 5895.7 5897.1 5931.5 5939.9 5947.8 5949.2 5950.9 5387.5 5419.6 5457.3 5508.9 5523.0 6064.4 6083.8 6087.7 6089.1 6110.7 5793.2 5797.1 5809.5 5813.9 5819.3 5734.4 5796.3 5816.4 5864.9 5897.8 5908.2 5916.5 5927.4 5928.9 5947.3 5861.4 5918.2 5923.4 5924.7 5946.1 4382.8 4412.1 4476.6 4559.5 4565.4 4018.5 4023.8 4036.1 4058.9 4069.8 5562.4 5565.2 5566.5 5582.1 5582.7 5754.6 5797.1 5934.2 5940.0 5946.3 5520.7 5527.6 5598.7 5627.9 5660.7 5146.5 5160.2 5168.6 5170.9 5171.6 5797.9 6179.1 6185.9 6190.1 6196.7 5713.1 5870.9 5881.3 5883.2 6020.8 6148.6 6169.0 6173.5 6175.0 6175.2 5877.4 5878.3 5884.0 5887.5 5888.4 5691.5 5705.2 5861.0 5894.2 5915.9 5868.3 5880.2 5884.3 5885.9 5909.3 5809.9 5867.7 5870.5 5872.6 5875.9 5543.3 5546.6 5612.7 5618.1 5791.7 5787.1 5787.6 5799.2 5810.7 5832.0 5650.7 5652.8 5652.9 5658.3 5662.0 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | [#1] ext.html Cycles:180 Average:63566.42 Median:37681.71 stddev:126745.57 (336.4%) stddev-sans-first:127012.96 07:53:50 INFO - PROCESS | 12435 | Values: 599.3 621.1 631.5 637.7 645.8 30901.4 33590.2 44558.0 45422.5 45492.0 47798.8 48151.5 48268.3 48280.0 48305.8 8591.7 9383.0 9528.2 9533.2 9538.0 8415.0 10910.7 11131.3 11168.9 11510.5 11419.5 11501.7 11514.7 11519.7 11520.5 11249.3 11293.7 11302.6 11319.9 11333.0 11105.0 11130.9 11167.8 11175.2 11206.0 45271.5 45416.5 45446.0 45475.0 45515.5 3407.4 3422.5 3452.2 3452.4 3487.9 34481.8 34542.5 34854.1 35082.6 35592.3 4586.1 4691.6 4695.0 4798.9 4830.5 355952.0 356550.0 357350.0 358057.0 359296.0 59588.7 60010.7 60148.4 60276.0 60277.4 41053.4 42222.8 42385.8 42639.6 42670.1 17279.6 17384.5 17415.2 17418.9 17425.7 13154.7 13549.2 14670.9 15360.4 15487.2 19474.9 20158.5 20202.6 20304.3 20370.6 7276.7 7335.4 7393.8 7602.9 7758.1 891.7 1108.6 1178.0 1188.1 1189.5 33635.3 33965.0 33997.5 34322.3 34418.3 1090.0 1142.1 1159.7 1207.8 1279.7 17865.7 17917.3 17958.4 18075.3 18168.9 554754.0 563277.0 563635.0 564111.0 564621.0 61722.0 62387.7 62410.4 62415.0 62422.7 1226.6 1306.3 1379.2 1380.5 1418.6 29404.4 30999.9 31453.7 33082.3 33164.7 56083.0 56962.0 57010.0 57116.4 57192.0 488065.0 490776.0 492370.0 492811.0 493594.0 24582.1 25073.0 30580.7 31022.7 31391.8 49233.3 49253.8 49477.8 49746.0 50409.2 42186.9 42702.8 43010.0 43681.8 44058.0 43053.4 43536.8 43814.8 44361.8 45364.1 43731.8 44338.1 44523.0 44871.8 45064.8 36786.0 38577.4 38788.6 38933.4 39714.0 29281.1 29442.4 29695.9 29978.0 29999.7 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | [#2] jquery.html Cycles:180 Average:30220.20 Median:40115.00 stddev:86314.32 (215.2%) stddev-sans-first:86527.44 07:53:50 INFO - PROCESS | 12435 | Values: 417.7 420.2 426.2 426.3 446.9 19121.6 19320.0 20641.4 20749.4 20925.1 19789.2 20175.2 20684.9 20759.7 20806.4 5706.7 5968.9 5971.4 5977.7 5981.1 5809.1 5906.1 6005.8 6015.0 6039.4 5983.6 5985.5 5987.7 5995.8 5997.2 5737.9 5893.7 5924.0 5936.0 5987.2 5320.9 5321.1 5321.9 5324.3 5329.5 24211.6 24307.6 24516.4 24540.0 24668.1 2381.8 2433.1 2495.7 2743.3 2783.8 15263.2 15398.7 15441.0 15591.6 15837.4 1960.4 1986.0 2026.5 2083.9 2133.4 82973.0 83126.6 83708.6 84193.2 87214.6 10814.3 11053.5 11140.2 11433.4 11485.1 15956.8 15976.8 15980.4 15987.0 15998.5 8765.2 8815.3 8914.1 8926.8 8961.3 8445.1 8537.1 8569.0 8710.8 8757.8 6809.7 6865.8 6912.3 6952.2 6966.3 1827.0 1974.8 1976.7 2038.7 2060.1 883.9 888.3 906.3 952.6 966.2 18535.5 19607.1 19745.9 19783.2 19908.9 923.5 936.3 949.6 967.3 984.2 10404.6 11148.0 11233.9 11387.8 11388.2 516284.0 519384.0 527967.0 531264.0 535359.0 82807.0 83920.6 84135.6 84141.0 84306.0 1063.9 1071.3 1102.7 1107.9 1108.7 17245.3 17788.5 17823.9 17945.7 17986.9 40093.4 40136.6 40596.6 40833.0 42556.8 51925.5 52114.0 53049.7 53758.7 54577.0 8692.0 8693.8 9021.7 9130.1 9242.6 13376.9 13418.1 13624.0 13921.9 14042.9 11588.5 11919.6 11922.9 12356.7 12430.4 11733.0 11968.6 12135.4 12366.0 12408.8 11367.2 11935.6 12108.8 12270.5 12493.8 8990.3 9202.3 9271.3 9402.0 9409.7 11819.9 12157.1 12235.3 12329.3 12525.0 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | [#3] mootools.html Cycles:180 Average:6063.80 Median:4854.35 stddev:3456.79 (71.2%) stddev-sans-first:3448.82 07:53:50 INFO - PROCESS | 12435 | Values: 1413.3 1508.3 1543.3 1655.4 1847.5 7725.1 8882.2 9203.6 9219.9 9530.3 9040.6 9120.2 9123.8 9128.2 9129.8 2448.7 2486.2 2489.3 2492.7 2495.4 2370.8 2481.4 2500.0 2500.7 2501.3 2409.8 2452.9 2506.3 2507.5 2508.4 2334.9 2353.6 2359.9 2418.6 2419.7 2330.7 2335.5 2342.2 2384.7 2426.1 7985.2 8281.2 9290.7 9401.1 9423.2 4930.1 5074.9 5458.9 5610.3 5620.3 3541.9 3543.1 3558.3 3561.6 3612.6 1280.3 1289.2 1304.8 1325.8 1391.9 12980.4 12992.5 13001.3 13016.6 13045.1 4713.2 4852.8 4855.9 4951.3 4999.2 10946.6 11045.6 11066.9 11094.7 11099.9 5648.5 5660.4 5661.3 5666.2 5668.1 5100.2 5125.2 5146.1 5226.0 5237.5 4415.4 4431.8 4474.7 4509.0 4532.6 1430.3 1440.9 1446.5 1455.6 1465.4 918.7 984.3 1022.0 1037.7 1040.0 6104.3 6222.3 6344.7 6387.7 6517.4 476.7 481.8 487.5 492.0 510.6 4075.2 4175.2 4194.6 4227.5 4274.3 9719.3 10083.3 10916.0 11030.7 11368.1 11583.8 11849.2 11866.9 11911.2 11927.6 8538.6 8822.7 8838.2 8842.6 8853.4 6273.5 6279.7 6293.2 6295.2 6330.8 10541.6 10676.4 10676.4 10690.8 10775.6 10720.1 10916.1 10921.1 10939.6 11028.8 7791.2 7881.1 7882.7 7890.0 7906.1 8565.9 8622.7 8637.0 8669.3 8748.5 7784.0 8061.0 8160.0 8223.8 8391.8 7768.5 7890.9 7911.7 8113.8 8172.8 7131.7 7210.7 7276.8 7312.1 7328.5 5177.3 5288.2 5310.0 5330.0 5421.9 5299.8 5350.5 5423.6 5586.3 5661.7 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | [#4] prototype.html Cycles:180 Average:4646.11 Median:5173.24 stddev:1345.75 (26.0%) stddev-sans-first:1310.08 07:53:50 INFO - PROCESS | 12435 | Values: 336.5 348.7 355.2 357.5 360.7 5386.8 5420.7 5544.8 5549.4 5560.7 5451.3 5457.6 5485.4 5488.3 5502.4 4028.0 4300.8 4325.2 4347.7 4387.5 3798.0 4137.1 4235.6 4253.2 4262.0 4219.2 4223.6 4225.4 4238.2 4239.2 4246.0 4249.4 4249.4 4253.0 4255.5 3497.5 3608.9 3619.4 3757.9 3760.2 5222.5 5224.2 5228.6 5230.7 5236.7 4838.6 4841.2 4848.0 4852.7 4878.7 5436.5 5551.9 5609.9 5617.0 5617.9 4106.9 4112.8 4114.7 4119.2 4119.8 5488.0 5592.9 5849.9 5900.6 5904.0 4146.6 4165.6 4173.4 4183.4 4195.7 4225.1 4230.6 4237.9 4239.4 4246.0 4607.7 4814.7 4828.2 4835.8 4841.6 5668.8 5713.7 5718.5 5721.9 5732.7 1462.2 1472.9 1481.6 1486.0 1489.2 913.4 954.4 956.3 959.6 968.2 4920.2 4920.7 4933.6 4934.0 4939.0 5111.2 5126.7 5132.9 5192.2 5194.9 4634.4 4849.6 4862.2 4872.6 4902.6 2698.8 2732.6 2735.5 2735.9 2744.5 5204.0 5210.5 5217.9 5256.2 5273.6 4896.8 5162.8 5168.8 5186.8 5221.8 5217.3 5223.3 5236.9 5284.3 5309.9 5111.0 5118.9 5154.5 5170.6 5175.9 5096.3 5408.4 5841.0 5854.3 5861.3 5708.2 5884.9 5893.7 6009.5 6024.8 4199.6 4220.3 4236.4 4263.0 4274.7 5766.5 5855.6 5862.9 5898.9 5916.2 5736.7 5746.6 5758.5 5765.5 5785.7 5731.5 5736.3 5739.6 5740.8 5750.5 5483.9 5679.2 5683.7 5709.4 5718.6 5718.0 5764.3 5772.5 5773.8 5774.5 5587.0 5629.7 5633.3 5640.8 5647.4 07:53:50 INFO - PROCESS | 12435 | 07:53:50 INFO - PROCESS | 12435 | [#5] yui.html Cycles:180 Average:21931.33 Median:4764.58 stddev:32001.77 (671.7%) stddev-sans-first:32051.95 07:53:50 INFO - PROCESS | 12435 | Values: 729.8 777.5 868.7 876.9 920.7 15447.4 15581.7 16145.1 16200.2 16201.4 15777.1 15876.2 15927.8 15931.4 16081.7 625.1 835.9 852.1 852.4 853.3 748.7 844.5 871.9 879.3 881.2 905.2 905.4 905.6 906.0 906.6 745.3 901.5 903.0 903.1 903.2 565.6 647.3 669.0 673.1 674.2 15093.4 15119.4 15128.5 15136.9 15140.5 14440.8 14599.3 14678.6 15005.2 15038.7 13562.7 13712.9 13746.9 13775.5 13872.1 4584.5 4597.6 4621.4 4628.4 4649.1 90456.0 90711.0 90942.0 91594.0 92223.5 11557.3 11687.4 11715.9 11955.3 12053.3 23631.9 23699.8 23726.4 23727.2 23735.9 8951.6 8989.6 9060.4 9063.1 9414.1 5047.6 5060.8 5071.3 5167.0 5182.8 3743.1 3903.1 3905.3 3906.6 3908.0 582.7 595.1 603.2 621.7 624.4 643.6 668.1 704.5 717.8 757.6 9655.1 9690.4 9743.0 9892.6 10003.2 635.8 681.7 709.5 712.3 718.4 4451.4 4760.8 4768.4 4788.9 4803.2 124347.0 124762.0 125531.9 125817.4 129057.4 67965.0 90523.0 93687.0 114089.0 114776.4 79958.6 89770.6 91143.0 91854.5 93140.5 9468.4 9509.0 9709.5 9933.9 10030.6 53051.0 54053.5 54275.0 54503.5 56026.0 73503.6 73546.6 78709.6 79225.0 79381.2 13362.9 13619.3 13760.1 13774.2 13838.7 13999.9 14099.3 14400.2 14493.7 14621.2 13645.4 13669.9 13739.7 14306.1 14406.6 12938.5 13592.1 13682.5 13849.7 14644.9 12859.7 13244.6 13491.3 13654.2 13745.6 10087.0 10404.5 10423.9 11019.2 11050.8 10795.5 10989.1 11616.2 11643.3 11660.9 07:53:50 INFO - PROCESS | 12435 | -------- Summary: end -------- 07:53:50 INFO - PROCESS | 12435 | 07:53:51 INFO - TEST-INFO | 12435: exit 0 07:53:51 INFO - TEST-OK | dromaeo_css | took 1113204ms 07:53:51 INFO - TEST-START | kraken 07:53:51 INFO - operating with platform_type : linux_ 07:53:51 INFO - Initialising browser for kraken test... 07:53:51 INFO - TEST-INFO | started process 12758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuhyRNW/profile http://localhost:37422/getInfo.html) 07:53:55 INFO - PROCESS | 12758 | __metrics Screen width/height:1600/1200 07:53:55 INFO - PROCESS | 12758 | colorDepth:24 07:53:55 INFO - PROCESS | 12758 | Browser inner width/height: 1024/697 07:53:55 INFO - PROCESS | 12758 | __metrics 07:53:55 INFO - PROCESS | 12758 | 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" 07:53:55 INFO - PROCESS | 12758 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:53:56 INFO - PROCESS | 12758 | console.error: PushService: 07:53:56 INFO - PROCESS | 12758 | stateChangeProcessEnqueue: Error transitioning state 07:53:56 INFO - PROCESS | 12758 | UnknownError 07:53:56 INFO - TEST-INFO | 12758: exit 0 07:53:56 INFO - Browser initialized. 07:53:56 INFO - Running cycle 1/1 for kraken test... 07:53:56 INFO - TEST-INFO | started process 12851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuhyRNW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:54:08 INFO - PROCESS | 12851 | RSS: Main: 144982016 07:54:08 INFO - PROCESS | 12851 | 07:54:11 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37422/tests/kraken/driver.html?testName=audio-beat-detection) 07:54:11 INFO - PROCESS | 12851 | RSS: Main: 186966016 07:54:11 INFO - PROCESS | 12851 | 07:54:14 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37422/tests/kraken/driver.html?testName=audio-dft) 07:54:15 INFO - PROCESS | 12851 | RSS: Main: 176545792 07:54:15 INFO - PROCESS | 12851 | 07:54:17 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37422/tests/kraken/driver.html?testName=audio-fft) 07:54:18 INFO - PROCESS | 12851 | RSS: Main: 267665408 07:54:18 INFO - PROCESS | 12851 | 07:54:20 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37422/tests/kraken/driver.html?testName=audio-oscillator) 07:54:21 INFO - PROCESS | 12851 | RSS: Main: 174260224 07:54:21 INFO - PROCESS | 12851 | 07:54:23 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37422/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:54:23 INFO - PROCESS | 12851 | RSS: Main: 176041984 07:54:23 INFO - PROCESS | 12851 | 07:54:26 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37422/tests/kraken/driver.html?testName=imaging-darkroom) 07:54:27 INFO - PROCESS | 12851 | RSS: Main: 211652608 07:54:27 INFO - PROCESS | 12851 | 07:54:30 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37422/tests/kraken/driver.html?testName=imaging-desaturate) 07:54:31 INFO - PROCESS | 12851 | RSS: Main: 210350080 07:54:31 INFO - PROCESS | 12851 | 07:54:34 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37422/tests/kraken/driver.html?testName=json-parse-financial) 07:54:34 INFO - PROCESS | 12851 | RSS: Main: 210239488 07:54:34 INFO - PROCESS | 12851 | 07:54:36 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37422/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:54:36 INFO - PROCESS | 12851 | RSS: Main: 172523520 07:54:36 INFO - PROCESS | 12851 | 07:54:38 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:54:39 INFO - PROCESS | 12851 | RSS: Main: 179847168 07:54:39 INFO - PROCESS | 12851 | 07:54:41 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:54:41 INFO - PROCESS | 12851 | RSS: Main: 189431808 07:54:41 INFO - PROCESS | 12851 | 07:54:44 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:54:45 INFO - PROCESS | 12851 | RSS: Main: 197799936 07:54:45 INFO - PROCESS | 12851 | 07:54:47 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:54:48 INFO - PROCESS | 12851 | RSS: Main: 194338816 07:54:48 INFO - PROCESS | 12851 | 07:54:49 INFO - PROCESS | 12851 | Cycle 1(1): loaded http://localhost:37422/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37422/tests/kraken/driver.html?testName=ai-astar) 07:54:50 INFO - PROCESS | 12851 | RSS: Main: 245858304 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | __start_tp_report 07:54:50 INFO - PROCESS | 12851 | _x_x_mozilla_page_load 07:54:50 INFO - PROCESS | 12851 | _x_x_mozilla_page_load_details 07:54:50 INFO - PROCESS | 12851 | |i|pagename|runs| 07:54:50 INFO - PROCESS | 12851 | |0;ai-astar;109;110;110;110;110;111;112;110;109;109 07:54:50 INFO - PROCESS | 12851 | |1;audio-beat-detection;146;118;117;158;119;121;121;118;143;126 07:54:50 INFO - PROCESS | 12851 | |2;audio-dft;161;161;161;160;163;160;160;164;159;160 07:54:50 INFO - PROCESS | 12851 | |3;audio-fft;75;75;75;109;79;75;76;76;100;74 07:54:50 INFO - PROCESS | 12851 | |4;audio-oscillator;82;82;116;82;81;82;113;81;82;127 07:54:50 INFO - PROCESS | 12851 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;117;116;116 07:54:50 INFO - PROCESS | 12851 | |6;imaging-darkroom;146;146;145;147;146;146;146;146;193;145 07:54:50 INFO - PROCESS | 12851 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;95 07:54:50 INFO - PROCESS | 12851 | |8;json-parse-financial;48;52;49;48;50;49;48;49;49;48 07:54:50 INFO - PROCESS | 12851 | |9;json-stringify-tinderbox;58;59;58;59;59;58;58;59;59;59 07:54:50 INFO - PROCESS | 12851 | |10;stanford-crypto-aes;80;73;83;74;76;73;74;74;73;73 07:54:50 INFO - PROCESS | 12851 | |11;stanford-crypto-ccm;124;126;130;130;139;123;121;130;155;141 07:54:50 INFO - PROCESS | 12851 | |12;stanford-crypto-pbkdf2;156;161;154;156;154;158;161;152;152;151 07:54:50 INFO - PROCESS | 12851 | |13;stanford-crypto-sha256-iterative;63;62;62;62;62;62;62;64;60;61 07:54:50 INFO - PROCESS | 12851 | __end_tp_report 07:54:50 INFO - PROCESS | 12851 | __start_cc_report 07:54:50 INFO - PROCESS | 12851 | _x_x_mozilla_cycle_collect,1570 07:54:50 INFO - PROCESS | 12851 | __end_cc_report 07:54:50 INFO - PROCESS | 12851 | __startTimestamp1479138890219__endTimestamp 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | ------- Summary: start ------- 07:54:50 INFO - PROCESS | 12851 | Number of tests: 14 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:0.94 (0.9%) stddev-sans-first:0.93 07:54:50 INFO - PROCESS | 12851 | Values: 109.0 110.0 110.0 110.0 110.0 111.0 112.0 110.0 109.0 109.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:123.50 stddev:14.71 (11.9%) stddev-sans-first:14.21 07:54:50 INFO - PROCESS | 12851 | Values: 146.0 118.0 117.0 158.0 119.0 121.0 121.0 118.0 143.0 126.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.52 (0.9%) stddev-sans-first:1.62 07:54:50 INFO - PROCESS | 12851 | Values: 161.0 161.0 161.0 160.0 163.0 160.0 160.0 164.0 159.0 160.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:12.43 (16.6%) stddev-sans-first:12.97 07:54:50 INFO - PROCESS | 12851 | Values: 75.0 75.0 75.0 109.0 79.0 75.0 76.0 76.0 100.0 74.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#4] audio-oscillator Cycles:10 Average:92.80 Median:82.00 stddev:18.19 (22.2%) stddev-sans-first:18.87 07:54:50 INFO - PROCESS | 12851 | Values: 82.0 82.0 116.0 82.0 81.0 82.0 113.0 81.0 82.0 127.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:54:50 INFO - PROCESS | 12851 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:146.00 stddev:14.91 (10.2%) stddev-sans-first:15.72 07:54:50 INFO - PROCESS | 12851 | Values: 146.0 146.0 145.0 147.0 146.0 146.0 146.0 146.0 193.0 145.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:54:50 INFO - PROCESS | 12851 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#8] json-parse-financial Cycles:10 Average:49.00 Median:49.00 stddev:1.25 (2.5%) stddev-sans-first:1.27 07:54:50 INFO - PROCESS | 12851 | Values: 48.0 52.0 49.0 48.0 50.0 49.0 48.0 49.0 49.0 48.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 07:54:50 INFO - PROCESS | 12851 | Values: 58.0 59.0 58.0 59.0 59.0 58.0 58.0 59.0 59.0 59.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:74.00 stddev:3.47 (4.7%) stddev-sans-first:3.23 07:54:50 INFO - PROCESS | 12851 | Values: 80.0 73.0 83.0 74.0 76.0 73.0 74.0 74.0 73.0 73.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:130.00 stddev:10.40 (8.0%) stddev-sans-first:10.63 07:54:50 INFO - PROCESS | 12851 | Values: 124.0 126.0 130.0 130.0 139.0 123.0 121.0 130.0 155.0 141.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.50 Median:156.00 stddev:3.60 (2.3%) stddev-sans-first:3.81 07:54:50 INFO - PROCESS | 12851 | Values: 156.0 161.0 154.0 156.0 154.0 158.0 161.0 152.0 152.0 151.0 07:54:50 INFO - PROCESS | 12851 | 07:54:50 INFO - PROCESS | 12851 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:1.05 (1.7%) stddev-sans-first:1.05 07:54:50 INFO - PROCESS | 12851 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 62.0 64.0 60.0 61.0 07:54:50 INFO - PROCESS | 12851 | -------- Summary: end -------- 07:54:50 INFO - PROCESS | 12851 | 07:54:51 INFO - TEST-INFO | 12851: exit 0 07:54:51 INFO - TEST-OK | kraken | took 59992ms 07:54:51 INFO - SUITE-END | took 1173s 07:54:51 INFO - Completed test suite (00:19:33) 07:54:51 INFO - javascript benchmark 07:54:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.440660681077, 3142.7171554560086, 3161.7944833585802, 3182.7135557799793, 3193.6167659880816, 5492.5605951523885, 5542.501174894259, 5566.137248726443, 5574.080276754341, 5578.274824272844, 5503.20203570482, 5563.026470367679, 5563.165525171226, 5564.0, 5581.386047534778, 5014.874628134298, 5018.322526458936, 5045.924311135336, 5076.492350764913, 5118.897622047575, 4919.99140176263, 5066.898662026757, 5115.897682046357, 5124.743762811844, 5126.051680439112, 4906.386701662293, 4963.652544321899, 4980.252962055684, 4994.101061808873, 5007.123753343169, 5028.245763135523, 5062.772175252123, 5063.341765570489, 5112.718800465973, 5140.897182056376, 4944.431390390115, 5025.798968041274, 5072.442031376546, 5077.060743762416, 5083.8983220335385, 5361.544268737162, 5382.838514844561, 5399.136138217906, 5416.945830541684, 5463.2078348639625, 5788.305403351625, 5803.0, 5805.912911306334, 5895.705214739246, 5897.14491398749, 5931.525477961796, 5939.851503712399, 5947.821565353003, 5949.167116603679, 5950.851228719273, 5387.488188622075, 5419.647722897999, 5457.317835270591, 5508.889822203533, 5523.033469142924, 6064.423879731418, 6083.8479038023515, 6087.66517841523, 6089.086637004485, 6110.663913484801, 5793.15999180125, 5797.101449275369, 5809.50619197373, 5813.94186058134, 5819.301683797887, 5734.369219385864, 5796.333421656564, 5816.389279125716, 5864.9413505864395, 5897.764089436375, 5908.20239267702, 5916.49709774674, 5927.407259274124, 5928.911066334009, 5947.316058653224, 5861.41385861419, 5918.171455996164, 5923.378045305267, 5924.733386997509, 5946.137810017523, 4382.802773875183, 4412.117576484716, 4476.597106260452, 4559.52125026874, 4565.360849481075, 4018.517777866675, 4023.7786921719585, 4036.1120553478186, 4058.939115913264, 4069.7965101745385, 5562.443755624486, 5565.248691426606, 5566.471185237396, 5582.051051321285, 5582.748776305076, 5754.625949313281, 5797.072468405035, 5934.19888315072, 5940.049592065248, 5946.316173640087, 5520.66875987442, 5527.613067085346, 5598.692071936001, 5627.8874422512135, 5660.660360378391, 5146.459621739664, 5160.174372100446, 5168.61235407346, 5170.870728231749, 5171.63798534099, 5797.9420205797405, 6179.134921111048, 6185.938140618537, 6190.102435146879, 6196.690165491707, 5713.114467257582, 5870.911936321044, 5881.2942446906645, 5883.2057672213705, 6020.759169633166, 6148.569600127948, 6169.0, 6173.475254603409, 6175.0, 6175.197224360984, 5877.441643043904, 5878.265216847894, 5883.970580147072, 5887.470127688529, 5888.440598143341, 5691.4877661010705, 5705.22979397776, 5861.032929566573, 5894.174815525744, 5915.881682366415, 5868.295804503487, 5880.176775251553, 5884.264466941632, 5885.911711324419, 5909.349971503045, 5809.8547536311935, 5867.706614669164, 5870.5010074144175, 5872.559558033079, 5875.853103672443, 5543.334799824047, 5546.584006199632, 5612.663240205601, 5618.073017951992, 5791.710414479175, 5787.102999035156, 5787.623804452697, 5799.246098007232, 5810.680412577349, 5832.037713777179, 5650.660960342392, 5652.830415087389, 5652.943470565407, 5658.264425624807, 5662.03745363281], "value": 5444.310037916836, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.337731806354, 621.1180124223602, 631.5200447659777, 637.7289651897945, 645.7675236914712, 30901.38197236054, 33590.16024599383, 44558.0, 45422.54577454225, 45492.0, 47798.76100619492, 48151.51848481514, 48268.27597586039, 48280.0, 48305.758471207686, 8591.742247732578, 9382.953085234582, 9528.190103841182, 9533.189678877303, 9538.046195380475, 8415.0, 10910.672679819578, 11131.332120072822, 11168.888311116907, 11510.482028308705, 11419.54321827126, 11501.654950351462, 11514.654560363202, 11519.712007199803, 11520.539178432853, 11249.325040497555, 11293.71765705864, 11302.604408845733, 11319.886801131966, 11333.0366918812, 11105.0, 11130.944345278303, 11167.776644467147, 11175.21773475857, 11206.047485963622, 45271.54728452706, 45416.54583454156, 45446.0, 45475.0, 45515.54484455146, 3407.386670399327, 3422.4524076147695, 3452.223249768782, 3452.4130897747445, 3487.9476807847905, 34481.827590862136, 34542.48186277208, 34854.12864678378, 35082.64917350845, 35592.28815423703, 4586.128635559233, 4691.62467002639, 4694.976525117387, 4798.904021919542, 4830.541098595628, 355952.0, 356550.0, 357350.0, 358057.0, 359296.0, 59588.70205648944, 60010.69994649999, 60148.39851601516, 60276.0, 60277.39722602806, 41053.384199237036, 42222.78888605537, 42385.788071060066, 42639.57360426418, 42670.1465970685, 17279.56801079983, 17384.4784656458, 17415.216315265843, 17418.91290543539, 17425.65148697019, 13154.671133221555, 13549.187048777105, 14670.926645366704, 15360.385584576716, 15487.225638718019, 19474.929420652785, 20158.496037599176, 20202.58581899253, 20304.289349872684, 20370.59258814845, 7276.708931642781, 7335.376492998155, 7393.8151546211775, 7602.885956710654, 7758.14660387357, 891.7057371067532, 1108.562117963416, 1177.9823302650468, 1188.0733028237987, 1189.5301355964345, 33635.32729345399, 33965.0, 33997.490037649455, 34322.313553729284, 34418.30965676804, 1089.9885839082835, 1142.0977427832086, 1159.6984783956232, 1207.824865394513, 1279.6864768131682, 17865.703856215012, 17917.283308667767, 17958.446971774414, 18075.276988920297, 18168.909155454137, 554754.0, 563277.0, 563635.0, 564111.0, 564621.0, 61722.0, 62387.6880615594, 62410.37589624136, 62415.0, 62422.68788656028, 1226.5645695778048, 1306.2750173653524, 1379.2138481065697, 1380.475419340644, 1418.5531557559543, 29404.411911762072, 30999.945104575818, 31453.68546314599, 33082.33835323328, 33164.668353316156, 56083.0, 56962.0, 57010.0, 57116.428835711115, 57192.0, 488065.0, 490776.0, 492370.0, 492811.0, 493594.0, 24582.139625113035, 25073.0, 30580.694193057785, 31022.689773102884, 31391.84304078465, 49233.2615010768, 49253.75373123112, 49477.75261123815, 49746.0, 50409.243861342846, 42186.94638927257, 42702.78648606737, 43010.0, 43681.78159109184, 44058.0, 43053.3541996864, 43536.78231608822, 43814.78092609644, 44361.77819111013, 45364.09271814479, 43731.78134109309, 44338.113237735706, 44523.0, 44871.77564112159, 45064.774676126406, 36786.0, 38577.421338680506, 38788.6121138785, 38933.415998759476, 39714.0, 29281.121566353042, 29442.411151777273, 29695.851520742257, 29978.0, 29999.70000299969], "value": 21330.53953355872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.6888218277382, 420.1974229222186, 426.15621070280855, 426.29022677242386, 446.886044058765, 19121.617567648656, 19320.0, 20641.38075857723, 20749.377518674428, 20925.05837237324, 19789.20843166275, 20175.19299228033, 20684.896575517138, 20759.68860467094, 20806.375808725763, 5706.657600543981, 5968.850778730544, 5971.402859714016, 5977.701114944257, 5981.102834574827, 5809.099589563624, 5906.05503119501, 6005.789797357093, 6014.96992515039, 6039.3960603939695, 5983.640981541122, 5985.461308482236, 5987.670678112702, 5995.820125396223, 5997.220361353018, 5737.856553586173, 5893.675847828346, 5924.022536281508, 5936.0, 5987.161797348374, 5320.893582128336, 5321.095413779628, 5321.946780532184, 5324.307839980777, 5329.467053294678, 24211.636825447633, 24307.63538546923, 24516.387090322707, 24540.01839926401, 24668.13661521838, 2381.761823817622, 2433.063270640816, 2495.663085483455, 2743.327884668249, 2783.8190517616495, 15263.236838158158, 15398.692026159526, 15441.0, 15591.610209744733, 15837.366505339773, 1960.4118764370633, 1985.990070049655, 2026.493376655836, 2083.8749675019394, 2133.4026472587543, 82973.0, 83126.58436707778, 83708.58145709294, 84193.15806841975, 87214.56392717996, 10814.297070690538, 11053.502592383362, 11140.164487663458, 11433.371164585862, 11485.138614603771, 15956.76064859028, 15976.76034859478, 15980.440684575984, 15987.0, 15998.520044398687, 8765.167309105625, 8815.250703690259, 8914.064023277591, 8926.821463570823, 8961.283097352196, 8445.113263107409, 8537.103604121601, 8568.957155214184, 8710.782230444289, 8757.824843503095, 6809.7276108956075, 6865.8328084225805, 6912.308769123148, 6952.165740111219, 6966.268541803139, 1826.9677632138066, 1974.7531558555181, 1976.6738488149294, 2038.6840039793856, 2060.05412260469, 883.9425437346551, 888.2849306308354, 906.2613969614742, 952.6189524190379, 966.2077096780572, 18535.53661158482, 19607.117679704446, 19745.901270493556, 19783.208671652974, 19908.90956449581, 923.459776031016, 936.3492372800974, 949.5964215208509, 967.2552134856121, 984.2027957354566, 10404.58381664725, 11148.0, 11233.943830280796, 11387.772244555063, 11388.20282580228, 516284.0, 519384.0, 527967.0, 531264.0, 535359.0, 82807.0, 83920.58039709763, 84135.579322103, 84141.0, 84306.0, 1063.8510608514969, 1071.3304184884448, 1102.6967583914327, 1107.9390633515234, 1108.6895669212633, 17245.31018759286, 17788.46634600964, 17823.910880445514, 17945.73081403754, 17986.91006544959, 40093.398599021624, 40136.59863401329, 40596.59403405928, 40833.0, 42556.78721606372, 51925.480745192064, 52114.0, 53049.734751325996, 53758.731206345285, 54577.0, 8692.0, 8693.782655433664, 9021.729348119567, 9130.132637399436, 9242.584083716383, 13376.866231337563, 13418.060735748795, 13624.0, 13921.860781392057, 14042.859571404564, 11588.536458541901, 11919.582814601446, 11922.880771192524, 12356.691082722644, 12430.37848107573, 11733.0, 11968.640940771791, 12135.393230338272, 12366.0, 12408.81386779217, 11367.20429569922, 11935.582254621047, 12108.75782484363, 12270.509179632714, 12493.812592811297, 8990.32572557074, 9202.263818894606, 9271.30465215098, 9402.0, 9409.717708468757, 11819.881801181878, 12157.08821838348, 12235.265884046985, 12329.321887296284, 12525.0], "value": 9682.039470948299, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.265102146883, 1508.2684897824554, 1543.3209387869338, 1655.3709590355663, 1847.5381154711322, 7725.1116121645955, 8882.156195161466, 9203.631854725803, 9219.907800921988, 9530.332876698634, 9040.593173307218, 9120.17918387345, 9123.817523649523, 9128.224100951427, 9129.817403651923, 2448.706155261367, 2486.17956074495, 2489.3029951613576, 2492.7382624824404, 2495.3761559610098, 2370.7866292033714, 2481.3548477395916, 2499.950000999979, 2500.6749122614124, 2501.3246423465744, 2409.783119519243, 2452.901883924641, 2506.285708573057, 2507.536105820429, 2508.3729067733066, 2334.8832558372183, 2353.5528249632516, 2359.8820058997067, 2418.5525677749674, 2419.68544089269, 2330.731965823935, 2335.474518233401, 2342.156823543523, 2384.6542251373626, 2426.065964603623, 7985.1615580363805, 8281.21328473794, 9290.72127836166, 9401.10689484498, 9423.199028082621, 4930.063287975273, 5074.923876141861, 5458.863528411782, 5610.298712660918, 5620.325560932714, 3541.8760343388117, 3543.0787995121195, 3558.3061303045915, 3561.6082230954576, 3612.6206748291315, 1280.2574506786134, 1289.1684863263142, 1304.7651422743902, 1325.7613629546677, 1391.930403479822, 12980.415881285271, 12992.545260915917, 13001.284929328884, 13016.60950171487, 13045.08684392093, 4713.151632706145, 4852.8301509447, 4855.878603034882, 4951.257311403318, 4999.200127979506, 10946.616868409568, 11045.613403530839, 11066.889331106748, 11094.667159985212, 11099.889001109885, 5648.51987581052, 5660.377358490563, 5661.348944871393, 5666.235058267163, 5668.121441176624, 5100.209467532539, 5125.205593132996, 5146.099432599355, 5226.007058658919, 5237.528622424, 4415.448068991376, 4431.822727090945, 4474.731516108979, 4508.977455112704, 4532.614727748112, 1430.3205977160765, 1440.9351579178963, 1446.5154173352046, 1455.5924341184486, 1465.4358072142088, 918.6555041859302, 984.3011461862158, 1021.9642312519026, 1037.7405648587853, 1039.9896001040045, 6104.328523862371, 6222.28443728977, 6344.714487847966, 6387.680615969183, 6517.380848819449, 476.73541184642164, 481.8120932836197, 487.4808329129466, 492.035610203997, 510.59407770822287, 4075.2335445345097, 4175.164967006611, 4194.622483976456, 4227.513835908849, 4274.294741367639, 9719.271054670928, 10083.294169408218, 10916.0, 11030.669079927615, 11368.147388945863, 11583.826242606368, 11849.229800062969, 11866.940665296792, 11911.166218364631, 11927.582534611247, 8538.573071346407, 8822.691205807765, 8838.204561589488, 8842.557872106368, 8853.424527405698, 6273.498120150286, 6279.654618995999, 6293.181886354744, 6295.18162638854, 6330.841728956813, 10541.57833686644, 10676.359418434919, 10676.412797295912, 10690.839637405601, 10775.568977240822, 10720.142388609049, 10916.12670986335, 10921.126309895348, 10939.56241750321, 11028.834567481656, 7791.220877912164, 7881.13307536182, 7882.684692612232, 7889.960550197212, 7906.130325664058, 8565.914340856512, 8622.741317760478, 8637.0, 8669.349798764992, 8748.518831464331, 7783.961080194563, 8060.959695201486, 8159.959200203961, 8223.835523289621, 8391.790205244917, 7768.4950478221, 7890.881636775338, 7911.683532658629, 8113.8377232456205, 8172.8365432689825, 7131.714731410685, 7210.675519601526, 7276.781696549112, 7312.085989251344, 7328.487005909536, 5177.301064356264, 5288.233206185005, 5310.044192045469, 5330.0, 5421.891562168656, 5299.788008479618, 5350.545203657577, 5423.593230507807, 5586.301712285965, 5661.716914154358], "value": 4793.136499764467, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.52045834685566, 348.73147676289256, 355.24155927095643, 357.456665867881, 360.7438718509857, 5386.757595908189, 5420.728963551826, 5544.8336549903515, 5549.38956714761, 5560.748831512918, 5451.2640793492865, 5457.5633949284065, 5485.396606373296, 5488.341399032121, 5502.394736578973, 4028.0, 4300.763458009808, 4325.248274448853, 4347.739135651856, 4387.517373088957, 3797.962020379802, 4137.089840235159, 4235.597618226264, 4253.2131555662145, 4261.978690106545, 4219.198352313066, 4223.64099051581, 4225.366195070733, 4238.152369526092, 4239.173361194568, 4245.9787701061605, 4249.405083288342, 4249.44757181566, 4253.001089487921, 4255.468066491689, 3497.510348551205, 3608.9278214435826, 3619.4208926571623, 3757.9436308455397, 3760.2103558252525, 5222.529972302511, 5224.190250511177, 5228.633995620308, 5230.712310822903, 5236.7119808410525, 4838.564529192389, 4841.201201801734, 4848.030393921194, 4852.684575502581, 4878.707277563358, 5436.537894278991, 5551.916721249185, 5609.896288891666, 5616.971915140398, 5617.915731264035, 4106.938395924063, 4112.848402447319, 4114.7119701620895, 4119.237940980898, 4119.752814831089, 5487.99999999996, 5592.916106258409, 5849.912251316234, 5900.557458190653, 5904.0258357370985, 4146.647534959562, 4165.645920096765, 4173.373993900939, 4183.3515805050265, 4195.706300558993, 4225.126913698653, 4230.64039556635, 4237.91524169521, 4239.364095385717, 4246.0, 4607.742206318947, 4814.748165477001, 4828.227483602606, 4835.782389792468, 4841.564259216688, 5668.773249070074, 5713.74288157034, 5718.542516598662, 5721.8569535761935, 5732.742026608813, 1462.2396353895915, 1472.9263536823116, 1481.6295926018495, 1485.9554213373617, 1489.1735087026589, 913.3969882855679, 954.4178051388648, 956.2732323434101, 959.6153558407352, 968.1528662420383, 4920.188168952154, 4920.704757714549, 4933.629977751683, 4933.975330123327, 4938.975305123451, 5111.2465407171685, 5126.666766660155, 5132.897342053138, 5192.247124166975, 5194.92207616886, 4634.443866736014, 4849.636277279147, 4862.246351815475, 4872.634552408584, 4902.607791376681, 2698.8110832241946, 2732.5764506501523, 2735.5076086304657, 2735.8768855401554, 2744.5042451863783, 5203.973980130151, 5210.505002024802, 5217.869553261123, 5256.237845512379, 5273.604479664041, 4896.804127834846, 5162.793488260428, 5168.81909133186, 5186.818461353834, 5221.791128354823, 5217.321748172713, 5223.276318814782, 5236.92144617831, 5284.313039304866, 5309.893802124013, 5111.0, 5118.8500829652385, 5154.484551544815, 5170.560502357192, 5175.922361164661, 5096.267269128514, 5408.3509978802795, 5841.007028805198, 5854.297484301741, 5861.267341582302, 5708.200851880657, 5884.852878678067, 5893.734781934738, 6009.519238460825, 6024.849378765566, 4199.559046300094, 4220.261454245556, 4236.436955641792, 4262.976885547509, 4274.722143060691, 5766.452187042224, 5855.59010869235, 5862.94137058641, 5898.852528686645, 5916.243185577104, 5736.655800651807, 5746.626469279484, 5758.4817366435545, 5765.538756899522, 5785.652860828363, 5731.541476681772, 5736.282964629421, 5739.593799519144, 5740.770369185353, 5750.482456578928, 5483.943521179558, 5679.233303503974, 5683.658980461186, 5709.371969083314, 5718.627529392833, 5718.0, 5764.279465066867, 5772.538196944319, 5773.826785196451, 5774.509166720708, 5586.972065139648, 5629.662220266797, 5633.267675202197, 5640.802571909963, 5647.378788333198], "value": 4224.7067235433415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.8430837369967, 777.4635501503965, 868.7437206024222, 876.9254613357864, 920.7237828651407, 15447.382104715813, 15581.688366232696, 16145.112018838989, 16200.189990500472, 16201.351945922164, 15777.13225772582, 15876.206189690527, 15927.761083583755, 15931.44239951602, 16081.678366432665, 625.0561651905632, 835.8955130608674, 852.071242345843, 852.3692467573982, 853.2917678326974, 748.6631016042776, 844.5481667307992, 871.8822958900562, 879.2921698033081, 881.1849039638341, 905.1717678324326, 905.4341036851968, 905.6060613633058, 905.9637614495413, 906.6237511432747, 745.3403737692128, 901.4861528928448, 902.9683961061364, 903.1284810158196, 903.2367649336301, 565.6408180805178, 647.2879832184559, 668.993310066898, 673.0946876451166, 674.2078058281548, 15093.39626414942, 15119.39522419102, 15128.470503532433, 15136.924315378352, 15140.545783626509, 14440.783388249185, 14599.270036498132, 14678.559643210721, 15005.174715390649, 15038.699226015528, 13562.728745425135, 13712.862871371259, 13746.862531374658, 13775.517856875062, 13872.098313609584, 4584.518625544303, 4597.563231493003, 4621.4223222097235, 4628.4445866496235, 4649.1053677932405, 90456.0, 90711.0, 90942.0, 91594.0, 92223.53888230583, 11557.30656160633, 11687.427642987415, 11715.882841171479, 11955.282683039046, 12053.337066461429, 23631.881840590686, 23699.763002370102, 23726.406839829142, 23727.1695490657, 23735.881320593286, 8951.552422378854, 8989.640414383482, 9060.365774395861, 9063.093690631016, 9414.105659962293, 5047.646664733434, 5060.7722652480725, 5071.3153724247495, 5167.0, 5182.766775495112, 3743.12036671382, 3903.1413089120347, 3905.2775236581324, 3906.570277269498, 3908.0, 582.7173820696959, 595.1429940885112, 603.1706403694919, 621.7264403662465, 624.3693869192149, 643.5720246036427, 668.1014036121409, 704.5138854190592, 717.7631381644045, 757.5984728094119, 9655.08276713711, 9690.37012594179, 9742.95128524353, 9892.60429582823, 10003.199744020461, 635.7870113511933, 681.7068660476043, 709.4714437743913, 712.3126183233205, 718.4360277182491, 4451.443569553806, 4760.809567617326, 4768.356271903318, 4788.928166077511, 4803.153535252718, 124347.0, 124762.0, 125531.86180326492, 125817.37091314484, 129057.35471322772, 67965.0, 90523.0, 93687.0, 114089.0, 114776.42611787055, 79958.6002069986, 89770.55114724515, 91143.0, 91854.54072729594, 93140.53429732943, 9468.431894086378, 9508.952455237679, 9709.514524273616, 9933.850992234979, 10030.598776049168, 53051.0, 54053.45946540641, 54275.0, 54503.45496544984, 56026.0, 73503.63248183725, 73546.63226684046, 78709.60645196967, 79225.0, 79381.2061879397, 13362.933185334012, 13619.319034048456, 13760.105593136414, 13774.242416667179, 13838.654033648836, 13999.930000349934, 14099.295035247991, 14400.207988560729, 14493.710125797636, 14621.19583422922, 13645.385957631732, 13669.863301366859, 13739.656508586966, 14306.141631502143, 14406.567802965928, 12938.547150849676, 13592.11651242666, 13682.52111176104, 13849.653758656113, 14644.853551464776, 12859.678508037374, 13244.602661920158, 13491.325433728078, 13654.249016304198, 13745.587632371045, 10087.0, 10404.47977601132, 10423.895761042595, 11019.173561983043, 11050.778984420427, 10795.514201860778, 10989.120870330515, 11616.186866919572, 11643.301401915913, 11660.943716895164], "value": 7860.152966535095, "unit": "score"}], "name": "dromaeo_css", "value": 7507.019954947998, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 111.0, 112.0, 110.0, 109.0, 109.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 117.0, 158.0, 119.0, 121.0, 121.0, 118.0, 143.0, 126.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 163.0, 160.0, 160.0, 164.0, 159.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 109.0, 79.0, 75.0, 76.0, 76.0, 100.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 116.0, 82.0, 81.0, 82.0, 113.0, 81.0, 82.0, 127.0], "value": 92.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 193.0, 145.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 52.0, 49.0, 48.0, 50.0, 49.0, 48.0, 49.0, 49.0, 48.0], "value": 49.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 59.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 83.0, 74.0, 76.0, 73.0, 74.0, 74.0, 73.0, 73.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 126.0, 130.0, 130.0, 139.0, 123.0, 121.0, 130.0, 155.0, 141.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 161.0, 154.0, 156.0, 154.0, 158.0, 161.0, 152.0, 152.0, 151.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 64.0, 60.0, 61.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1468.4, "alertThreshold": 2.0}]} 07:54:51 INFO - Return code: 0 07:54:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:54:51 INFO - # TBPL SUCCESS # 07:54:51 INFO - Running post-action listener: _resource_record_post_action 07:54:51 INFO - [mozharness: 2016-11-14 15:54:51.539797Z] Finished run-tests step (success) 07:54:51 INFO - Running post-run listener: _resource_record_post_run 07:54:51 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2236416; Write bytes: 366206976; Read time: 512; Write time: 389872 07:54:51 INFO - TinderboxPrint: CPU usage
13.0% 07:54:51 INFO - TinderboxPrint: I/O read bytes / time
2,236,416 / 512 07:54:51 INFO - TinderboxPrint: I/O write bytes / time
366,206,976 / 389,872 07:54:51 INFO - TinderboxPrint: CPU idle
8,263.0 (87.0%) 07:54:51 INFO - TinderboxPrint: CPU user
1,189.5 (12.5%) 07:54:51 INFO - TinderboxPrint: Swap in / out
0 / 0 07:54:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115392512; Read time: 0; Write time: 147184 07:54:51 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250806272; Read time: 12; Write time: 242688 07:54:52 INFO - Running post-run listener: _upload_blobber_files 07:54:52 INFO - Blob upload gear active. 07:54:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:54:52 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 07:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:54:52 INFO - (blobuploader) - INFO - Open directory for files ... 07:54:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:54:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:54:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:53 INFO - (blobuploader) - INFO - Done attempting. 07:54:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:54:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:54 INFO - (blobuploader) - INFO - Done attempting. 07:54:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:54:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:54:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:57 INFO - (blobuploader) - INFO - Done attempting. 07:54:57 INFO - (blobuploader) - INFO - Iteration through files over. 07:54:57 INFO - Return code: 0 07:54:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:54:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:54:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6babbb3199c684ae9a10a6a2780433149b759717054bfa5ab4887daaad7ec610c4c2db339d59369f0fd403d67224002ab4fd6f4e2fd9a6953d6c47639c26adbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21ea1f0bd7bd34cb88dcbbe57b8c7a94cf55789c99e7282122513ec9cff6f6173947a8823f71505f1eef05da981a10ad5e28fb934c7e8a7436bbcd31ec521b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdfd480558eb67396ec8a3876e7085dfcf7be5972305d87a449e03a4926855c1f0faf5acd3ce2502c1f716c7a37404408d49e8bba58ca115924bca85165595a8"} 07:54:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:54:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:54:57 INFO - Contents: 07:54:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6babbb3199c684ae9a10a6a2780433149b759717054bfa5ab4887daaad7ec610c4c2db339d59369f0fd403d67224002ab4fd6f4e2fd9a6953d6c47639c26adbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21ea1f0bd7bd34cb88dcbbe57b8c7a94cf55789c99e7282122513ec9cff6f6173947a8823f71505f1eef05da981a10ad5e28fb934c7e8a7436bbcd31ec521b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdfd480558eb67396ec8a3876e7085dfcf7be5972305d87a449e03a4926855c1f0faf5acd3ce2502c1f716c7a37404408d49e8bba58ca115924bca85165595a8"} 07:54:57 INFO - Running post-run listener: copy_logs_to_upload_dir 07:54:57 INFO - Copying logs to upload dir... 07:54:57 INFO - mkdir: /builds/slave/test/build/upload/logs 07:54:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.760489 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-14 07:54:57.220753) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:54:57.224667) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6babbb3199c684ae9a10a6a2780433149b759717054bfa5ab4887daaad7ec610c4c2db339d59369f0fd403d67224002ab4fd6f4e2fd9a6953d6c47639c26adbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21ea1f0bd7bd34cb88dcbbe57b8c7a94cf55789c99e7282122513ec9cff6f6173947a8823f71505f1eef05da981a10ad5e28fb934c7e8a7436bbcd31ec521b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdfd480558eb67396ec8a3876e7085dfcf7be5972305d87a449e03a4926855c1f0faf5acd3ce2502c1f716c7a37404408d49e8bba58ca115924bca85165595a8"} build_url:https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6babbb3199c684ae9a10a6a2780433149b759717054bfa5ab4887daaad7ec610c4c2db339d59369f0fd403d67224002ab4fd6f4e2fd9a6953d6c47639c26adbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21ea1f0bd7bd34cb88dcbbe57b8c7a94cf55789c99e7282122513ec9cff6f6173947a8823f71505f1eef05da981a10ad5e28fb934c7e8a7436bbcd31ec521b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdfd480558eb67396ec8a3876e7085dfcf7be5972305d87a449e03a4926855c1f0faf5acd3ce2502c1f716c7a37404408d49e8bba58ca115924bca85165595a8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:54:57.250491) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:54:57.250861) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479137663.656651-605849726 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:54:57.334347) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:54:57.334689) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:54:57.334999) ========= ========= Total master_lag: 0.17 =========